某生产库standby报警out of sync,版本10204;
登陆standby,尝试开启恢复进程,alertlog信息如下
ALTER DATABASE RECOVER managed standby database disconnect from session
Fri Aug 2 06:23:58 2013
Attempt to start background Managed Standby Recovery process (INTELP)
MRP0 started with pid=18, OS id=77
Fri Aug 2 06:23:58 2013
MRP0: Background Managed Standby Recovery process started (INTELP)
Managed Standby Recovery not using Real Time Apply
MRP0: Background Media Recovery terminated with error 1111
Fri Aug 2 06:24:03 2013
Errors in file /test/d01/app/oracle/admin/intelp/bdump/intelp_mrp0_77.trc:
ORA-01111: Message 1111 not found; No message file for product=RDBMS, facility=ORA; arguments: [49]
ORA-01110: Message 1110 not found; No message file for product=RDBMS, facility=ORA; arguments: [49] [/test/d01/app/oracle/product/10.2.0/db_1/dbs/UNNAMED00049]
SQL> select file#,name from v$datafile order by 2;
FILE# NAME
---------- --------------------------------------------------
49 /test/d01/app/oracle/product/10.2.0/db_1/dbs/UNNAMED00049
primay该文件路径为/test/d02/oradata/intelp,而standby该文件系统已经占满,所以才会创建失败
解决方法
SQL> select status,file#,name from v$datafile where status=’RECOVER’;
RECOVER 47 /test/d02/oradata/intelp/vaultlob27.dbf
RECOVER 48 /test/d02/oradata/intelp/vaultlob28.dbf
RECOVER 49 /test/d06/oradata/intelp/vaultlob29.dbf
RECOVER 50 /test/d01/app/oracle/product/10.2.0/db_1/dbs/UNNAMED00050
SQL> alter system set standby_file_management=manual;
System altered.
SQL> alter database create datafile '/test/d01/app/oracle/product/10.2.0/db_1/dbs/UNNAMED00049' as '/test/d06/oradata/intelp/vaultlob29.dbf';
Database altered
SQL> alter database create datafile '/test/d01/app/oracle/product/10.2.0/db_1/dbs/UNNAMED00050' as '/test/d06/oradata/intelp/vaultlob30.dbf';
Database altered.
SQL> select status,file#,name from v$datafile where status=’RECOVER’;
RECOVER 47 /test/d02/oradata/intelp/vaultlob27.dbf
RECOVER 48 /test/d02/oradata/intelp/vaultlob28.dbf
RECOVER 49 /test/d06/oradata/intelp/vaultlob29.dbf
RECOVER 50 /test/d06/oradata/intelp/vaultlob30.dbf
由于/test/d02以满,故将datafile 48迁移至其他文件系统
SQL> alter database create datafile '/test/d02/oradata/intelp/vaultlob28.dbf' as '/test/d06/oradata/intelp/vaultlob28.dbf';
Database altered.
$ rm vaultlob28.dbf
SQL> alter system set standby_file_management=auto;
System altered
此刻重启备库恢复进程,问题得到解决
小结
alter database … create datafile的作用
Creates a new empty datafile in place of an old one--useful to re-create a datafile that was lost with no backup.
前提是db开启归档模式且自愿数据文件创建以来的archive log都可用.
该命令通过查看control file提取出创建文件的DDL命令,初始化创建该文件(此刻文件内容为空),然后利用archive log重做所有的redo.
可在没有数据文件备份时使用。
Session 1
SQL> select OWNER,TABLESPACE_NAME from dba_tables where table_name='TEST';
OWNER TABLESPACE_NAME
------------------------------ ------------------------------
SYS TES
SQL> select file#,name,status from v$datafile where ts#=(select ts# from v$tablespace where name='TES');
FILE# NAME STATUS
---------- -------------------------------------------------- -------
31 /arch/tes.dbf ONLINE
SQL> select * from test;
ID
----------
1
Session 2
SQL> ! rm /arch/tes.dbf
SQL> select * from test;
ID
----------
1
SQL> select file#,name,status from v$datafile where ts#=(select ts# from v$tablespace where name='TES');
FILE# NAME STATUS
---------- ---------------------------------------- -------
31 /arch/tes.dbf ONLINE
SQL> alter system checkpoint;
System altered.
SQL> select file#,name,status from v$datafile where ts#=(select ts# from v$tablespace where name='TES');
FILE# NAME STATUS
---------- ---------------------------------------- -------
31 /arch/tes.dbf RECOVER
SQL> select * from test;
select * from test
*
ERROR at line 1:
ORA-00376: file 31 cannot be read at this time
ORA-01110: data file 31: '/arch/tes.dbf'
Session 1
SQL> oradebug setmypid
Statement processed.
SQL> oradebug event 10046 trace name context forever, level 12;
Statement processed.
SQL> oradebug tracefile_name;
/oralog/d23x/dump/d23x_ora_6669.trc
SQL> alter database create datafile '/arch/tes.dbf' as '/arch/d23x/tes.dbf';
Database altered.
Session 2
SQL> select file#,name,status from v$datafile where ts#=(select ts# from v$tablespace where name='TES');
FILE# NAME STATUS
---------- ---------------------------------------- -------
31 /arch/d23x/tes.dbf RECOVER
Session 1
SQL> recover datafile 31;
Media recovery complete.
SQL> alter database datafile 31 online;
Database altered.
跟踪文件
alter database create datafile '/arch/tes.dbf' as '/arch/d23x/tes.dbf'
END OF STMT
PARSE #1:c=10000,e=1672,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=2,tim=44196283624609
BINDS #1:
WAIT #1: nam='control file sequential read' ela= 65 file#=0 block#=1 blocks=1 obj#=37 tim=44196283625438
WAIT #1: nam='control file sequential read' ela= 42 file#=1 block#=1 blocks=1 obj#=37 tim=44196283625563
WAIT #1: nam='control file sequential read' ela= 37 file#=0 block#=15 blocks=1 obj#=37 tim=44196283625646
WAIT #1: nam='control file sequential read' ela= 35 file#=0 block#=17 blocks=1 obj#=37 tim=44196283625740
WAIT #1: nam='control file sequential read' ela= 38 file#=0 block#=77 blocks=1 obj#=37 tim=44196283625889
WAIT #1: nam='control file sequential read' ela= 32 file#=0 block#=79 blocks=1 obj#=37 tim=44196283626055
WAIT #1: nam='control file sequential read' ela= 33 file#=0 block#=1 blocks=1 obj#=37 tim=44196283626401
WAIT #1: nam='control file sequential read' ela= 26 file#=1 block#=1 blocks=1 obj#=37 tim=44196283626480
WAIT #1: nam='control file sequential read' ela= 25 file#=0 block#=15 blocks=1 obj#=37 tim=44196283626544
WAIT #1: nam='control file sequential read' ela= 24 file#=0 block#=17 blocks=1 obj#=37 tim=44196283626610
WAIT #1: nam='control file sequential read' ela= 29 file#=0 block#=24 blocks=1 obj#=37 tim=44196283626684
WAIT #1: nam='control file sequential read' ela= 18 file#=0 block#=79 blocks=1 obj#=37 tim=44196283626745
WAIT #1: nam='control file sequential read' ela= 26 file#=0 block#=1 blocks=1 obj#=37 tim=44196283626903
WAIT #1: nam='control file sequential read' ela= 23 file#=1 block#=1 blocks=1 obj#=37 tim=44196283626970
WAIT #1: nam='control file sequential read' ela= 22 file#=0 block#=15 blocks=1 obj#=37 tim=44196283627030
WAIT #1: nam='control file sequential read' ela= 23 file#=0 block#=17 blocks=1 obj#=37 tim=44196283627113
WAIT #1: nam='control file sequential read' ela= 24 file#=0 block#=77 blocks=1 obj#=37 tim=44196283627186
WAIT #1: nam='control file sequential read' ela= 17 file#=0 block#=79 blocks=1 obj#=37 tim=44196283627267
WAIT #1: nam='control file sequential read' ela= 20 file#=0 block#=24 blocks=1 obj#=37 tim=44196283627332
WAIT #1: nam='control file sequential read' ela= 29 file#=0 block#=367 blocks=1 obj#=37 tim=44196283627426
WAIT #1: nam='control file sequential read' ela= 23 file#=0 block#=77 blocks=1 obj#=37 tim=44196283627495
WAIT #1: nam='control file sequential read' ela= 17 file#=0 block#=79 blocks=1 obj#=37 tim=44196283627572
WAIT #1: nam='Data file init write' ela= 3419 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283636749
WAIT #1: nam='Data file init write' ela= 4381 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283644427
WAIT #1: nam='Data file init write' ela= 4182 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283651921
WAIT #1: nam='Data file init write' ela= 4301 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283659565
WAIT #1: nam='Data file init write' ela= 4062 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283664479
WAIT #1: nam='Data file init write' ela= 4055 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283669311
WAIT #1: nam='Data file init write' ela= 4094 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283674162
WAIT #1: nam='Data file init write' ela= 4071 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283679028
WAIT #1: nam='Data file init write' ela= 4081 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283683898
WAIT #1: nam='Data file init write' ela= 4188 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283688861
WAIT #1: nam='Data file init write' ela= 44 count=1 intr=256 timeout=4294967295 obj#=37 tim=44196283688967
WAIT #1: nam='Data file init write' ela= 7 count=4294967295 intr=32 timeout=2147483647 obj#=37 tim=44196283689007
WAIT #1: nam='control file sequential read' ela= 58 file#=0 block#=1 blocks=1 obj#=37 tim=44196283690203
WAIT #1: nam='control file sequential read' ela= 33 file#=1 block#=1 blocks=1 obj#=37 tim=44196283690311
WAIT #1: nam='control file sequential read' ela= 39 file#=0 block#=15 blocks=1 obj#=37 tim=44196283690394
WAIT #1: nam='control file sequential read' ela= 34 file#=0 block#=17 blocks=1 obj#=37 tim=44196283690485
WAIT #1: nam='control file sequential read' ela= 30 file#=0 block#=24 blocks=1 obj#=37 tim=44196283690588
WAIT #1: nam='control file sequential read' ela= 39 file#=0 block#=77 blocks=1 obj#=37 tim=44196283690706
WAIT #1: nam='control file sequential read' ela= 25 file#=0 block#=79 blocks=1 obj#=37 tim=44196283690819
WAIT #1: nam='control file sequential read' ela= 32 file#=0 block#=905 blocks=1 obj#=37 tim=44196283691031
WAIT #1: nam='control file parallel write' ela= 69833 files=2 block#=80 requests=2 obj#=37 tim=44196283760947
WAIT #1: nam='control file sequential read' ela= 46 file#=0 block#=908 blocks=1 obj#=37 tim=44196283761106
WAIT #1: nam='control file sequential read' ela= 36 file#=0 block#=367 blocks=1 obj#=37 tim=44196283761308
WAIT #1: nam='db file single write' ela= 44 file#=31 block#=1 blocks=1 obj#=37 tim=44196283761429
WAIT #1: nam='control file parallel write' ela= 4405 files=2 block#=18 requests=2 obj#=37 tim=44196283771996
WAIT #1: nam='control file parallel write' ela= 4352 files=2 block#=16 requests=2 obj#=37 tim=44196283776474
WAIT #1: nam='control file parallel write' ela= 3451 files=2 block#=1 requests=2 obj#=37 tim=44196283780057
WAIT #1: nam='control file sequential read' ela= 42 file#=0 block#=1 blocks=1 obj#=37 tim=44196283780156
WAIT #1: nam='db file sequential read' ela= 31 file#=2 block#=29 blocks=1 obj#=0 tim=44196283780925
XCTEND rlbk=0, rd_only=1
EXEC #1:c=70000,e=156982,p=1,cr=0,cu=3,mis=0,r=0,dep=0,og=2,tim=44196283781723
WAIT #1: nam='log file sync' ela= 11 buffer#=3455 p2=0 p3=0 obj#=0 tim=44196283782000
WAIT #1: nam='log file sync' ela= 15580 buffer#=3455 p2=0 p3=0 obj#=0 tim=44196283797710
WAIT #1: nam='SQL*Net message to client' ela= 10 driver id=1650815232 #bytes=1 p3=0 obj#=0 tim=44196283802914
*** 2013-08-02 09:30:02.497
WAIT #1: nam='SQL*Net message from client' ela= 614148552 driver id=1650815232 #bytes=1 p3=0 obj#=0 tim=44196897951614
XCTEND rlbk=0, rd_only=1
=====================
PARSING IN CURSOR #3 len=37 dep=0 uid=0 ct=35 lid=0 tim=44196897953551 hv=3898622947 ad='a1bc12e8'
ALTER DATABASE RECOVER datafile 31
END OF STMT
PARSE #3:c=0,e=1502,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=2,tim=44196897953539
BINDS #3:
WAIT #3: nam='control file sequential read' ela= 68 file#=0 block#=1 blocks=1 obj#=0 tim=44196897954180
WAIT #3: nam='control file sequential read' ela= 45 file#=1 block#=1 blocks=1 obj#=0 tim=44196897954639
WAIT #3: nam='control file sequential read' ela= 30 file#=0 block#=16 blocks=1 obj#=0 tim=44196897954712
WAIT #3: nam='control file sequential read' ela= 39 file#=0 block#=18 blocks=1 obj#=0 tim=44196897954808
WAIT #3: nam='control file sequential read' ela= 48 file#=0 block#=24 blocks=1 obj#=0 tim=44196897955227
WAIT #3: nam='control file sequential read' ela= 39 file#=0 block#=1 blocks=1 obj#=0 tim=44196897961251
WAIT #3: nam='control file sequential read' ela= 31 file#=1 block#=1 blocks=1 obj#=0 tim=44196897961342
WAIT #3: nam='control file sequential read' ela= 21 file#=0 block#=16 blocks=1 obj#=0 tim=44196897961401
WAIT #3: nam='control file sequential read' ela= 28 file#=0 block#=18 blocks=1 obj#=0 tim=44196897961476
WAIT #3: nam='control file sequential read' ela= 26 file#=0 block#=905 blocks=1 obj#=0 tim=44196897961554
WAIT #3: nam='rdbms ipc reply' ela= 842 from_process=5 timeout=910 p3=0 obj#=0 tim=44196897979541
WAIT #3: nam='control file sequential read' ela= 59 file#=0 block#=1 blocks=1 obj#=0 tim=44196897979836
WAIT #3: nam='control file sequential read' ela= 35 file#=1 block#=1 blocks=1 obj#=0 tim=44196897979937
WAIT #3: nam='control file sequential read' ela= 33 file#=0 block#=16 blocks=1 obj#=0 tim=44196897980013
WAIT #3: nam='control file sequential read' ela= 35 file#=0 block#=18 blocks=1 obj#=0 tim=44196897980212
WAIT #3: nam='control file sequential read' ela= 32 file#=0 block#=905 blocks=1 obj#=0 tim=44196897980319
WAIT #3: nam='control file sequential read' ela= 34 file#=0 block#=908 blocks=1 obj#=0 tim=44196897980419
WAIT #3: nam='control file sequential read' ela= 35 file#=0 block#=24 blocks=1 obj#=0 tim=44196897980632
WAIT #3: nam='db file sequential read' ela= 26 file#=31 block#=1 blocks=1 obj#=0 tim=44196897980851
WAIT #3: nam='control file sequential read' ela= 26 file#=0 block#=905 blocks=1 obj#=0 tim=44196897980961
WAIT #3: nam='db file single write' ela= 861 file#=31 block#=1 blocks=1 obj#=0 tim=44196897981895
WAIT #3: nam='control file parallel write' ela= 3814 files=2 block#=906 requests=2 obj#=0 tim=44196897985795
WAIT #3: nam='control file parallel write' ela= 3017 files=2 block#=17 requests=2 obj#=0 tim=44196897988930
WAIT #3: nam='control file parallel write' ela= 2931 files=2 block#=15 requests=2 obj#=0 tim=44196897991965
WAIT #3: nam='control file parallel write' ela= 2911 files=2 block#=1 requests=2 obj#=0 tim=44196897995027
WAIT #3: nam='control file sequential read' ela= 43 file#=0 block#=1 blocks=1 obj#=0 tim=44196897995118
Recovery target incarnation = 3, activation ID = 0
Influx buffer limit = 12350 (50% x 24700)
parallel recovery setup failed: using serial mode
Start recovery at thread 1 ckpt scn 9149368025750 logseq 6097 block 2117
WAIT #3: nam='rdbms ipc reply' ela= 414 from_process=5 timeout=2147483647 p3=0 obj#=0 tim=44196898034156
WAIT #3: nam='control file sequential read' ela= 65 file#=0 block#=1 blocks=1 obj#=0 tim=44196898041328
WAIT #3: nam='control file sequential read' ela= 35 file#=1 block#=1 blocks=1 obj#=0 tim=44196898041470
WAIT #3: nam='control file sequential read' ela= 36 file#=0 block#=15 blocks=1 obj#=0 tim=44196898041549
WAIT #3: nam='control file sequential read' ela= 33 file#=0 block#=17 blocks=1 obj#=0 tim=44196898041639
WAIT #3: nam='control file sequential read' ela= 38 file#=0 block#=20 blocks=1 obj#=0 tim=44196898041745
*** 2013-08-02 09:30:02.590
Media Recovery add redo thread 1
WAIT #3: nam='control file sequential read' ela= 41 file#=0 block#=1 blocks=1 obj#=0 tim=44196898044162
WAIT #3: nam='control file sequential read' ela= 27 file#=1 block#=1 blocks=1 obj#=0 tim=44196898044254
WAIT #3: nam='control file sequential read' ela= 28 file#=0 block#=15 blocks=1 obj#=0 tim=44196898044321
WAIT #3: nam='control file sequential read' ela= 26 file#=0 block#=17 blocks=1 obj#=0 tim=44196898044391
WAIT #3: nam='control file sequential read' ela= 26 file#=0 block#=20 blocks=1 obj#=0 tim=44196898044470
WAIT #3: nam='control file sequential read' ela= 38 file#=0 block#=21 blocks=1 obj#=0 tim=44196898044555
WAIT #3: nam='control file sequential read' ela= 36 file#=0 block#=77 blocks=1 obj#=0 tim=44196898047079
WAIT #3: nam='log file sequential read' ela= 20 log#=0 block#=1 blocks=1 obj#=0 tim=44196898047485
WAIT #3: nam='log file sequential read' ela= 12 log#=1 block#=1 blocks=1 obj#=0 tim=44196898047535
*** 2013-08-02 09:30:02.598
Recovery of Online Redo Log: Thread 1 Group 1 Seq 6097 Reading mem 0
WAIT #3: nam='recovery read' ela= 3 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898050323
WAIT #3: nam='control file sequential read' ela= 32 file#=0 block#=1 blocks=1 obj#=0 tim=44196898057057
WAIT #3: nam='control file sequential read' ela= 27 file#=1 block#=1 blocks=1 obj#=0 tim=44196898057133
WAIT #3: nam='control file sequential read' ela= 26 file#=0 block#=15 blocks=1 obj#=0 tim=44196898057198
WAIT #3: nam='control file sequential read' ela= 24 file#=0 block#=17 blocks=1 obj#=0 tim=44196898057263
WAIT #3: nam='log file sequential read' ela= 158471 log#=0 block#=2117 blocks=16384 obj#=0 tim=44196898215840
WAIT #3: nam='control file sequential read' ela= 60 file#=0 block#=1 blocks=1 obj#=0 tim=44196898228411
WAIT #3: nam='control file sequential read' ela= 35 file#=1 block#=1 blocks=1 obj#=0 tim=44196898228548
WAIT #3: nam='control file sequential read' ela= 35 file#=0 block#=15 blocks=1 obj#=0 tim=44196898228627
WAIT #3: nam='control file sequential read' ela= 36 file#=0 block#=17 blocks=1 obj#=0 tim=44196898228771
WAIT #3: nam='control file sequential read' ela= 34 file#=0 block#=24 blocks=1 obj#=0 tim=44196898228887
WAIT #3: nam='db file sequential read' ela= 71 file#=31 block#=1 blocks=1 obj#=0 tim=44196898229031
WAIT #3: nam='db file single write' ela= 902 file#=31 block#=1 blocks=1 obj#=0 tim=44196898230003
WAIT #3: nam='recovery read' ela= 4 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898230114
WAIT #3: nam='recovery read' ela= 2 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898230577
WAIT #3: nam='recovery read' ela= 7 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898242285
WAIT #3: nam='recovery read' ela= 1 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898242443
WAIT #3: nam='recovery read' ela= 2 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898243686
WAIT #3: nam='recovery read' ela= 1 p1=4294967295 p2=0 p3=2147483647 obj#=0 tim=44196898243771
WAIT #3: nam='checkpoint completed' ela= 917744 p1=0 p2=0 p3=0 obj#=0 tim=44196899161638
WAIT #3: nam='control file sequential read' ela= 64 file#=0 block#=1 blocks=1 obj#=0 tim=44196899161917
WAIT #3: nam='control file sequential read' ela= 39 file#=1 block#=1 blocks=1 obj#=0 tim=44196899162027
WAIT #3: nam='control file sequential read' ela= 38 file#=0 block#=15 blocks=1 obj#=0 tim=44196899162107
WAIT #3: nam='control file sequential read' ela= 34 file#=0 block#=17 blocks=1 obj#=0 tim=44196899162198
WAIT #3: nam='control file sequential read' ela= 33 file#=0 block#=24 blocks=1 obj#=0 tim=44196899162310
WAIT #3: nam='db file sequential read' ela= 28 file#=31 block#=1 blocks=1 obj#=0 tim=44196899162474
WAIT #3: nam='db file single write' ela= 740 file#=31 block#=1 blocks=1 obj#=0 tim=44196899163282
File 31 (stop scn 9149840017767) completed recovery at checkpoint scn 9149840017767
WAIT #3: nam='control file parallel write' ela= 3396 files=2 block#=23 requests=2 obj#=0 tim=44196899166785
WAIT #3: nam='control file parallel write' ela= 2824 files=2 block#=18 requests=2 obj#=0 tim=44196899169719
WAIT #3: nam='control file parallel write' ela= 2896 files=2 block#=16 requests=2 obj#=0 tim=44196899172755
WAIT #3: nam='control file parallel write' ela= 3847 files=2 block#=1 requests=2 obj#=0 tim=44196899176723
WAIT #3: nam='control file sequential read' ela= 42 file#=0 block#=1 blocks=1 obj#=0 tim=44196899176814
ARCH: Connecting to console port...
----- Redo read statistics for thread 1 -----
Read rate (SYNC): 777Kb in 1.18s => 0.64 Mb/sec
Total physical reads: 8192Kb
Longest record: 8Kb, moves: 0/1274 (0%)
Change moves: 5/37 (13%), moved: 0Mb
Longest LWN: 160Kb, moves: 0/171 (0%), moved: 0Mb
Last redo scn: 0x0852.5cf6c966 (9149840017766)
----------------------------------------------
*** 2013-08-02 09:30:03.769
Media Recovery drop redo thread 1
WAIT #3: nam='ksfd: async disk IO' ela= 4 count=4294967295 intr=0 timeout=2147483647 obj#=0 tim=44196899194593
KCBR: Number of read descriptors = 1024
KCBR: Influx buffers flushed = 3 times
KCBR: Reads = 0 reap (0 no-op, 0 one), 14 all
WAIT #3: nam='rdbms ipc reply' ela= 8 from_process=5 timeout=910 p3=0 obj#=0 tim=44196899221875
WAIT #3: nam='rdbms ipc reply' ela= 10 from_process=5 timeout=910 p3=0 obj#=0 tim=44196899221941
XCTEND rlbk=0, rd_only=1
EXEC #3:c=90000,e=1268925,p=0,cr=0,cu=2,mis=0,r=0,dep=0,og=2,tim=44196899222600
WAIT #3: nam='log file sync' ela= 7 buffer#=3663 p2=0 p3=0 obj#=0 tim=44196899222824
WAIT #3: nam='log file sync' ela= 5000 buffer#=3663 p2=0 p3=0 obj#=0 tim=44196899227869
WAIT #3: nam='SQL*Net message to client' ela= 11 driver id=1650815232 #bytes=1 p3=0 obj#=0 tim=44196899238045