Trace file /home/oracle/admin/diag/rdbms/aggstest/AGGSTEST/trace/AGGSTEST_ora_40108.trc Oracle Database 11g Enterprise Edition Release 11.2.0.3.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options ORACLE_HOME = /home/oracle/db11g System name: Linux Node name: aggs00.test.scl.efrontier.com Release: 2.6.32-279.14.1.el6.x86_64 Version: #1 SMP Mon Oct 15 13:44:51 EDT 2012 Machine: x86_64 Instance name: AGGSTEST Redo thread mounted by this instance: 1 Oracle process number: 78 Unix process pid: 40108, image: oracle@aggs00.test.scl.efrontier.com *** 2013-08-10 14:59:29.046 *** SESSION ID:(604.16233) 2013-08-10 14:59:29.046 *** CLIENT ID:() 2013-08-10 14:59:29.046 *** SERVICE NAME:(AGGSTEST) 2013-08-10 14:59:29.046 *** MODULE NAME:(PL/SQL Developer) 2013-08-10 14:59:29.046 *** ACTION NAME:(SQL Window - New) 2013-08-10 14:59:29.046 ... *** 2013-08-10 15:29:21.304 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... WAIT #140533526725304: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173951255501 *** 2013-08-10 15:32:31.332 WAIT #140533526725304: nam='SQL*Net message from client' ela= 77351 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173951332928 CLOSE #140533526725304:c=0,e=5,dep=0,type=1,tim=1376173951332996 ===================== PARSING IN CURSOR #140533526786456 len=61 dep=0 uid=35 oct=47 lid=35 tim=1376173951333033 hv=356401299 ad='7b38baecf0' sqlid='9m7787camwh4m' begin :id := sys.dbms_transaction.local_transaction_id; end; END OF STMT PARSE #140533526786456:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376173951333029 BINDS #140533526786456: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 WAIT #140533526786456: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173951333152 EXEC #140533526786456:c=0,e=81,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376173951333167 WAIT #140533526786456: nam='SQL*Net message from client' ela= 26393 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173951359584 CLOSE #140533526786456:c=0,e=15,dep=0,type=3,tim=1376173951359662 ===================== PARSING IN CURSOR #140533526739168 len=73 dep=0 uid=35 oct=47 lid=35 tim=1376173951359721 hv=2674323898 ad='7b792a0e30' sqlid='bydf32qgqdwdu' begin sys.dbms_output.get_line(line => :line, status => :status); end; END OF STMT PARSE #140533526739168:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376173951359721 BINDS #140533526739168: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 Bind#1 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=24 off=0 kxsbbbfp=7fd082aac530 bln=22 avl=00 flg=05 WAIT #140533526739168: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173951359932 EXEC #140533526739168:c=0,e=97,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376173951359947 *** 2013-08-10 15:32:37.679 WAIT #140533526739168: nam='SQL*Net message from client' ela= 6319231 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173957679200 CLOSE #140533526739168:c=0,e=26,dep=0,type=3,tim=1376173957679321 ===================== PARSING IN CURSOR #140533526726480 len=21 dep=0 uid=35 oct=3 lid=35 tim=1376173957679404 hv=2564406738 ad='7b7945ee98' sqlid='cf06fwacdmgfk' select 'x' from dual END OF STMT PARSE #140533526726480:c=0,e=51,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1388734953,tim=1376173957679403 EXEC #140533526726480:c=0,e=33,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1388734953,tim=1376173957679486 WAIT #140533526726480: nam='SQL*Net message to client' ela= 6 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173957679519 WAIT #140533526726480: nam='SQL*Net message from client' ela= 25172 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173957704807 WAIT #140533526726480: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173957704891 FETCH #140533526726480:c=0,e=51,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=1388734953,tim=1376173957704924 STAT #140533526726480 id=1 cnt=1 pid=0 pos=1 obj=0 op='FAST DUAL (cr=0 pr=0 pw=0 time=3 us cost=2 size=0 card=1)' WAIT #140533526726480: nam='SQL*Net message from client' ela= 25661 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173957730672 CLOSE #140533526726480:c=0,e=8,dep=0,type=1,tim=1376173957730744 ===================== PARSING IN CURSOR #140533526768216 len=114 dep=0 uid=35 oct=47 lid=35 tim=1376173957730810 hv=2538660186 ad='7b5645ec68' sqlid='fnj6cnubp1sau' begin if :enable = 0 then sys.dbms_output.disable; else sys.dbms_output.enable(:size); end if; end; END OF STMT PARSE #140533526768216:c=0,e=21,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376173957730810 BINDS #140533526768216: Bind#0 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=48 off=0 kxsbbbfp=7fd082aac518 bln=22 avl=02 flg=05 value=1 Bind#1 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=0 off=24 kxsbbbfp=7fd082aac530 bln=22 avl=00 flg=01 WAIT #140533526768216: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173957731007 EXEC #140533526768216:c=0,e=143,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376173957731022 WAIT #140533526768216: nam='SQL*Net message from client' ela= 296952 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173958027996 CLOSE #140533526768216:c=0,e=8,dep=0,type=3,tim=1376173958028057 PARSE #140533526786456:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376173958028095 BINDS #140533526786456: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 WAIT #140533526786456: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173958028185 EXEC #140533526786456:c=0,e=88,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376173958028201 WAIT #140533526786456: nam='SQL*Net message from client' ela= 25698 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173958053923 CLOSE #140533526786456:c=0,e=15,dep=0,type=3,tim=1376173958053999 ===================== PARSING IN CURSOR #140533522706936 len=145 dep=1 uid=35 oct=47 lid=35 tim=1376173958055536 hv=2178852815 ad='72cfa0f338' sqlid='b75s0xf0xx9yg' declare iflist SYS.ODCIOBJECTLIST; begin :1 := "SYS"."ORACLE_DATAPUMP"."ODCIGETINTERFACES"(iflist); :2 := iflist; end; END OF STMT PARSE #140533522706936:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=0,tim=1376173958055535 BINDS #140533522706936: Bind#0 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=00 fl2=0001 frm=00 csi=00 siz=24 off=0 kxsbbbfp=7fd082b369a8 bln=22 avl=00 flg=05 Bind#1 oacdty=123 mxl=4000(4000) mxlc=00 mal=00 scl=00 pre=00 oacflg=00 fl2=0001 frm=00 csi=00 siz=4000 off=0 toid ptr value=CF998DA8 length=16 8A42AB108F674EB2E040A80AA4107D64 kxsbbbfp=7fd082b65060 bln=4000 avl=00 flg=15 EXEC #140533522706936:c=0,e=258,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,plh=0,tim=1376173958055963 CLOSE #140533522706936:c=0,e=7,dep=1,type=3,tim=1376173958056003 ===================== PARSING IN CURSOR #140533526719744 len=50 dep=0 uid=35 oct=3 lid=35 tim=1376173958064045 hv=2802533099 ad='71432243b0' sqlid='9duk7r6mhqhrb' SELECT * FROM IMP_3251198_2419 t WHERE ROWNUM = 1 END OF STMT PARSE #140533526719744:c=10999,e=10004,p=0,cr=318,cu=0,mis=1,r=0,dep=0,og=1,plh=2394912001,tim=1376173958064045 EXEC #140533526719744:c=0,e=62,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=2394912001,tim=1376173958064150 WAIT #140533526719744: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173958064173 WAIT #140533526719744: nam='SQL*Net message from client' ela= 25684 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376173958090383 ===================== PARSING IN CURSOR #140533522704768 len=72 dep=1 uid=0 oct=3 lid=0 tim=1376173958090498 hv=1154343954 ad='7328042148' sqlid='fz9xwpt2cvt0k' SELECT par_type, param_clob, param_blob from external_tab$ where obj#=:1 END OF STMT PARSE #140533522704768:c=0,e=28,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=409139402,tim=1376173958090498 BINDS #140533522704768: Bind#0 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=00 fl2=0001 frm=00 csi=00 siz=24 off=0 kxsbbbfp=7fd082f14410 bln=22 avl=05 flg=05 value=3694948 EXEC #140533522704768:c=0,e=58,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=409139402,tim=1376173958090683 FETCH #140533522704768:c=0,e=28,p=0,cr=2,cu=0,mis=0,r=1,dep=1,og=4,plh=409139402,tim=1376173958090722 STAT #140533522704768 id=1 cnt=1 pid=0 pos=1 obj=1035 op='TABLE ACCESS BY INDEX ROWID EXTERNAL_TAB$ (cr=2 pr=0 pw=0 time=24 us cost=0 size=4030 card=1)' STAT #140533522704768 id=2 cnt=1 pid=1 pos=1 obj=1040 op='INDEX UNIQUE SCAN I_EXTERNAL_TAB1$ (cr=1 pr=0 pw=0 time=8 us cost=0 size=0 card=1)' CLOSE #140533522704768:c=0,e=2,dep=1,type=0,tim=1376173958090787 CLOSE #140533522470336:c=0,e=6,dep=1,type=1,tim=1376173958091059 LOBGETLEN: c=1000,e=10,p=0,cr=0,cu=0,tim=1376173958091554 LOBREAD: c=0,e=6,p=0,cr=0,cu=0,tim=1376173958091599 ===================== PARSING IN CURSOR #140533526737928 len=64 dep=1 uid=35 oct=3 lid=35 tim=1376173958091793 hv=4099360948 ad='7328065d88' sqlid='f5ra7dru5fk5n' SELECT NAME, PATH, READ, WRITE, EXECUTE FROM SYS.LOADER_DIR_OBJS END OF STMT PARSE #140533526737928:c=0,e=67,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=3642656162,tim=1376173958091793 EXEC #140533526737928:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=3642656162,tim=1376173958091865 FETCH #140533526737928:c=0,e=108,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958091999 FETCH #140533526737928:c=0,e=33,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092065 FETCH #140533526737928:c=0,e=22,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092113 FETCH #140533526737928:c=0,e=20,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092159 FETCH #140533526737928:c=0,e=17,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092200 FETCH #140533526737928:c=0,e=17,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092241 FETCH #140533526737928:c=0,e=22,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092288 FETCH #140533526737928:c=0,e=16,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092329 FETCH #140533526737928:c=0,e=19,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092372 FETCH #140533526737928:c=0,e=24,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092421 FETCH #140533526737928:c=0,e=17,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092512 FETCH #140533526737928:c=1000,e=31,p=0,cr=5,cu=0,mis=0,r=1,dep=1,og=1,plh=3642656162,tim=1376173958092571 FETCH #140533526737928:c=0,e=87,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=1,plh=3642656162,tim=1376173958092685 STAT #140533526737928 id=1 cnt=12 pid=0 pos=1 obj=3872 op='VIEW LOADER_DIR_OBJS (cr=61 pr=0 pw=0 time=114 us cost=15 size=8124 card=4)' STAT #140533526737928 id=2 cnt=12 pid=1 pos=1 obj=0 op='UNION-ALL (cr=61 pr=0 pw=0 time=111 us)' STAT #140533526737928 id=3 cnt=12 pid=2 pos=1 obj=0 op='NESTED LOOPS (cr=61 pr=0 pw=0 time=105 us)' STAT #140533526737928 id=4 cnt=12 pid=3 pos=1 obj=0 op='NESTED LOOPS (cr=49 pr=0 pw=0 time=262 us cost=6 size=124 card=2)' STAT #140533526737928 id=5 cnt=12 pid=4 pos=1 obj=495 op='TABLE ACCESS FULL DIR$ (cr=13 pr=0 pw=0 time=80 us cost=2 size=70 card=2)' STAT #140533526737928 id=6 cnt=12 pid=4 pos=2 obj=36 op='INDEX RANGE SCAN I_OBJ1 (cr=36 pr=0 pw=0 time=88 us cost=1 size=0 card=1)' STAT #140533526737928 id=7 cnt=12 pid=3 pos=2 obj=18 op='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=12 pr=0 pw=0 time=84 us cost=2 size=27 card=1)' STAT #140533526737928 id=8 cnt=1 pid=7 pos=1 obj=0 op='FIXED TABLE FULL X$KZSPR (cr=0 pr=0 pw=0 time=38 us cost=0 size=26 card=1)' STAT #140533526737928 id=9 cnt=0 pid=2 pos=2 obj=0 op='HASH GROUP BY (cr=0 pr=0 pw=0 time=73 us cost=9 size=146 card=2)' STAT #140533526737928 id=10 cnt=0 pid=9 pos=1 obj=0 op='FILTER (cr=0 pr=0 pw=0 time=23 us)' STAT #140533526737928 id=11 cnt=0 pid=10 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=0 us cost=8 size=73 card=1)' STAT #140533526737928 id=12 cnt=0 pid=11 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=0 us cost=6 size=124 card=2)' STAT #140533526737928 id=13 cnt=0 pid=12 pos=1 obj=495 op='TABLE ACCESS FULL DIR$ (cr=0 pr=0 pw=0 time=0 us cost=2 size=70 card=2)' STAT #140533526737928 id=14 cnt=0 pid=12 pos=2 obj=18 op='TABLE ACCESS BY INDEX ROWID OBJ$ (cr=0 pr=0 pw=0 time=0 us cost=2 size=27 card=1)' STAT #140533526737928 id=15 cnt=0 pid=14 pos=1 obj=36 op='INDEX RANGE SCAN I_OBJ1 (cr=0 pr=0 pw=0 time=0 us cost=1 size=0 card=1)' STAT #140533526737928 id=16 cnt=0 pid=11 pos=2 obj=62 op='INDEX RANGE SCAN I_OBJAUTH1 (cr=0 pr=0 pw=0 time=0 us cost=1 size=11 card=1)' STAT #140533526737928 id=17 cnt=0 pid=16 pos=1 obj=0 op='FIXED TABLE FULL X$KZSRO (cr=0 pr=0 pw=0 time=0 us cost=0 size=13 card=1)' STAT #140533526737928 id=18 cnt=1 pid=10 pos=2 obj=0 op='FIXED TABLE FULL X$KZSPR (cr=0 pr=0 pw=0 time=21 us cost=0 size=26 card=1)' ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:32:39.097 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:37:16.765 WAIT #140533526719744: nam='latch free' ela= 6809 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174236765046 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:42:01.986 WAIT #140533526719744: nam='latch free' ela= 96683 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174521986923 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:42:35.119 WAIT #140533526719744: nam='latch free' ela= 14632 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174555119091 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:42:38.160 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... WAIT #140533526719744: nam='Disk file operations I/O' ela= 599953569 FileOperation=2 fileno=0 filetype=18 obj#=-1 tim=1376174558164644 WAIT #140533526719744: nam='Datapump dump file I/O' ela= 579 count=1 intr=0 timeout=4294967295 obj#=-1 tim=1376174558165287 WAIT #140533526719744: nam='Disk file operations I/O' ela= 14 FileOperation=5 fileno=0 filetype=18 obj#=-1 tim=1376174558165352 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:43:01.212 WAIT #140533526719744: nam='latch free' ela= 9037 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174581212389 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:43:42.325 WAIT #140533526719744: nam='latch free' ela= 26201 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174622325527 ... *** 2013-08-10 15:44:16.467 WAIT #140533526719744: nam='latch free' ela= 57713 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174656467116 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:44:17.469 WAIT #140533526719744: nam='latch free' ela= 291 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174657469474 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:44:18.471 WAIT #140533526719744: nam='latch free' ela= 860 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174658471886 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:44:19.474 WAIT #140533526719744: nam='latch free' ela= 659 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174659474248 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:44:20.476 WAIT #140533526719744: nam='latch free' ela= 514 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174660476454 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:44:21.478 WAIT #140533526719744: nam='latch free' ela= 352 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174661478400 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:44:23.499 WAIT #140533526719744: nam='latch free' ela= 18066 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174663499470 ... *** 2013-08-10 15:45:34.635 WAIT #140533526719744: nam='latch free' ela= 367 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174734635905 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:35.637 WAIT #140533526719744: nam='latch free' ela= 230 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174735637679 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:38.642 WAIT #140533526719744: nam='latch free' ela= 185 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174738642059 *** 2013-08-10 15:45:39.643 WAIT #140533526719744: nam='latch free' ela= 31 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174739643297 *** 2013-08-10 15:45:40.644 WAIT #140533526719744: nam='latch free' ela= 276 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174740644665 *** 2013-08-10 15:45:41.646 WAIT #140533526719744: nam='latch free' ela= 592 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174741646523 *** 2013-08-10 15:45:42.648 WAIT #140533526719744: nam='latch free' ela= 184 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174742648016 *** 2013-08-10 15:45:43.649 WAIT #140533526719744: nam='latch free' ela= 237 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174743649509 *** 2013-08-10 15:45:44.683 WAIT #140533526719744: nam='latch free' ela= 33070 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174744683873 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:45.687 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:46.687 WAIT #140533526719744: nam='latch free' ela= 240 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174746687728 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:47.689 WAIT #140533526719744: nam='latch free' ela= 217 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174747689677 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:48.691 WAIT #140533526719744: nam='latch free' ela= 97 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174748691508 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:45:49.693 WAIT #140533526719744: nam='latch free' ela= 712 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174749693824 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:48:32.048 WAIT #140533526719744: nam='latch free' ela= 4280 address=1610842088 number=342 tries=0 obj#=-1 tim=1376174912048386 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:50:05.559 WAIT #140533526719744: nam='latch free' ela= 14815 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175005559820 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ... *** 2013-08-10 15:50:16.620 WAIT #140533526719744: nam='latch free' ela= 10061 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175016620458 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:17.622 WAIT #140533526719744: nam='latch free' ela= 327 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175017622772 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:18.625 WAIT #140533526719744: nam='latch free' ela= 750 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175018625088 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:19.628 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:20.629 WAIT #140533526719744: nam='latch free' ela= 538 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175020629463 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:22.632 WAIT #140533526719744: nam='latch free' ela= 245 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175022632900 *** 2013-08-10 15:50:23.634 WAIT #140533526719744: nam='latch free' ela= 623 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175023634882 *** 2013-08-10 15:50:24.636 WAIT #140533526719744: nam='latch free' ela= 449 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175024636539 *** 2013-08-10 15:50:25.637 WAIT #140533526719744: nam='latch free' ela= 105 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175025637932 *** 2013-08-10 15:50:27.640 WAIT #140533526719744: nam='latch free' ela= 176 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175027640535 *** 2013-08-10 15:50:28.642 WAIT #140533526719744: nam='latch free' ela= 227 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175028642093 *** 2013-08-10 15:50:29.643 WAIT #140533526719744: nam='latch free' ela= 671 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175029643885 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:30.645 WAIT #140533526719744: nam='latch free' ela= 267 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175030645653 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:31.647 WAIT #140533526719744: nam='latch free' ela= 178 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175031647334 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:50:32.649 WAIT #140533526719744: nam='latch free' ela= 237 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175032649038 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 15:52:38.995 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... WAIT #140533526719744: nam='Disk file operations I/O' ela= 600649797 FileOperation=2 fileno=0 filetype=18 obj#=-1 tim=1376175158999341 WAIT #140533526719744: nam='Datapump dump file I/O' ela= 8846 count=4294967295 intr=256 timeout=2147483647 obj#=-1 tim=1376175159009309 WAIT #140533526719744: nam='Datapump dump file I/O' ela= 5 count=4294967295 intr=256 timeout=2147483647 obj#=-1 tim=1376175159009409 WAIT #140533526719744: nam='Disk file operations I/O' ela= 16 FileOperation=5 fileno=0 filetype=18 obj#=-1 tim=1376175159009440 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... *** 2013-08-10 16:02:39.081 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... WAIT #140533526719744: nam='latch free' ela= 1413 address=1610842088 number=342 tries=0 obj#=-1 tim=1376175759082853 ...FAIL 13 ...len 112 0x... 0x... 0x... 0x... ...len 328 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... 0x... WAIT #140533526719744: nam='Disk file operations I/O' ela= 600079144 FileOperation=2 fileno=0 filetype=18 obj#=-1 tim=1376175759090054 WAIT #140533526719744: nam='Datapump dump file I/O' ela= 11605 count=4294967295 intr=256 timeout=2147483647 obj#=-1 tim=1376175759101830 WAIT #140533526719744: nam='Datapump dump file I/O' ela= 1372 count=1 intr=256 timeout=2147483647 obj#=-1 tim=1376175759103387 CLOSE #140533526737928:c=0,e=10,dep=1,type=0,tim=1376175759103653 ===================== PARSING IN CURSOR #140533522704768 len=106 dep=1 uid=35 oct=3 lid=35 tim=1376175759104239 hv=4100319766 ad='732803b8b8' sqlid='8nb6mg3u6buhq' select pname, length(pname) from loader_part_info where parttype = 3 and tname = :1 and owner = :2 END OF STMT PARSE #140533522704768:c=0,e=426,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=1,plh=0,tim=1376175759104238 ===================== PARSING IN CURSOR #140533522473056 len=37 dep=2 uid=0 oct=3 lid=0 tim=1376175759104552 hv=1398610540 ad='7b391d3c10' sqlid='grwydz59pu6mc' select text from view$ where rowid=:1 END OF STMT PARSE #140533522473056:c=0,e=41,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=4,plh=3684871272,tim=1376175759104552 BINDS #140533522473056: Bind#0 oacdty=11 mxl=16(16) mxlc=00 mal=00 scl=00 pre=00 oacflg=18 fl2=0001 frm=00 csi=00 siz=16 off=0 kxsbbbfp=7fd082535f30 bln=16 avl=16 flg=05 value=00000B16.000B.0001 EXEC #140533522473056:c=0,e=77,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=4,plh=3684871272,tim=1376175759104702 FETCH #140533522473056:c=0,e=42,p=0,cr=2,cu=0,mis=0,r=1,dep=2,og=4,plh=3684871272,tim=1376175759104760 STAT #140533522473056 id=1 cnt=1 pid=0 pos=1 obj=69 op='TABLE ACCESS BY USER ROWID VIEW$ (cr=1 pr=0 pw=0 time=29 us cost=1 size=15 card=1)' CLOSE #140533522473056:c=0,e=48,dep=2,type=1,tim=1376175759104826 BINDS #140533522704768: Bind#0 oacdty=96 mxl=32(00) mxlc=00 mal=00 scl=00 pre=00 oacflg=10 fl2=0000 frm=01 csi=871 siz=64 off=0 kxsbbbfp=7fd082535f00 bln=32 avl=00 flg=05 Bind#1 oacdty=96 mxl=32(00) mxlc=00 mal=00 scl=00 pre=00 oacflg=10 fl2=0000 frm=01 csi=871 siz=0 off=32 kxsbbbfp=7fd082535f20 bln=32 avl=00 flg=01 EXEC #140533522704768:c=6000,e=6906,p=0,cr=2,cu=0,mis=1,r=0,dep=1,og=1,plh=1544571525,tim=1376175759111276 FETCH #140533522704768:c=0,e=7,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=1544571525,tim=1376175759111297 STAT #140533522704768 id=1 cnt=0 pid=0 pos=1 obj=0 op='FILTER (cr=0 pr=0 pw=0 time=10 us)' STAT #140533522704768 id=2 cnt=0 pid=1 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=8 us)' STAT #140533522704768 id=3 cnt=0 pid=2 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=6 us cost=4 size=66 card=1)' STAT #140533522704768 id=4 cnt=0 pid=3 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=5 us cost=3 size=58 card=1)' STAT #140533522704768 id=5 cnt=0 pid=4 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=5 us cost=2 size=48 card=1)' STAT #140533522704768 id=6 cnt=0 pid=5 pos=1 obj=22 op='TABLE ACCESS BY INDEX ROWID USER$ (cr=0 pr=0 pw=0 time=3 us cost=1 size=19 card=1)' STAT #140533522704768 id=7 cnt=0 pid=6 pos=1 obj=46 op='INDEX UNIQUE SCAN I_USER1 (cr=0 pr=0 pw=0 time=1 us cost=0 size=0 card=1)' STAT #140533522704768 id=8 cnt=0 pid=5 pos=2 obj=37 op='INDEX RANGE SCAN I_OBJ2 (cr=0 pr=0 pw=0 time=0 us cost=1 size=29 card=1)' STAT #140533522704768 id=9 cnt=0 pid=4 pos=2 obj=576 op='TABLE ACCESS BY INDEX ROWID TABPART$ (cr=0 pr=0 pw=0 time=0 us cost=1 size=10 card=1)' STAT #140533522704768 id=10 cnt=0 pid=9 pos=1 obj=580 op='INDEX UNIQUE SCAN I_TABPART_OBJ$ (cr=0 pr=0 pw=0 time=0 us cost=0 size=0 card=1)' STAT #140533522704768 id=11 cnt=0 pid=3 pos=2 obj=573 op='INDEX UNIQUE SCAN I_PARTOBJ$ (cr=0 pr=0 pw=0 time=0 us cost=0 size=0 card=1)' STAT #140533522704768 id=12 cnt=0 pid=2 pos=2 obj=572 op='TABLE ACCESS BY INDEX ROWID PARTOBJ$ (cr=0 pr=0 pw=0 time=0 us cost=1 size=8 card=1)' STAT #140533522704768 id=13 cnt=0 pid=1 pos=2 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=0 us cost=2 size=21 card=1)' STAT #140533522704768 id=14 cnt=0 pid=13 pos=1 obj=62 op='INDEX RANGE SCAN I_OBJAUTH1 (cr=0 pr=0 pw=0 time=0 us cost=2 size=8 card=1)' STAT #140533522704768 id=15 cnt=0 pid=13 pos=2 obj=0 op='FIXED TABLE FULL X$KZSRO (cr=0 pr=0 pw=0 time=0 us cost=0 size=13 card=1)' STAT #140533522704768 id=16 cnt=0 pid=1 pos=3 obj=0 op='FIXED TABLE FULL X$KZSPR (cr=0 pr=0 pw=0 time=0 us cost=0 size=26 card=1)' CLOSE #140533522704768:c=0,e=4,dep=1,type=0,tim=1376175759113553 ===================== PARSING IN CURSOR #140533522704768 len=61 dep=1 uid=35 oct=3 lid=35 tim=1376175759114053 hv=3422591629 ad='732802d058' sqlid='15jnrrb6016nd' SELECT SESSIONTIMEZONE, LENGTH(SESSIONTIMEZONE) FROM SYS.DUAL END OF STMT PARSE #140533522704768:c=1000,e=465,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=1,plh=1388734953,tim=1376175759114053 EXEC #140533522704768:c=0,e=11,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=1388734953,tim=1376175759114110 FETCH #140533522704768:c=0,e=12,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,plh=1388734953,tim=1376175759114156 STAT #140533522704768 id=1 cnt=1 pid=0 pos=1 obj=0 op='FAST DUAL (cr=0 pr=0 pw=0 time=2 us cost=2 size=0 card=1)' CLOSE #140533522704768:c=0,e=3,dep=1,type=0,tim=1376175759114219 ===================== PARSING IN CURSOR #140533522704768 len=51 dep=1 uid=35 oct=3 lid=35 tim=1376175759114688 hv=2397724299 ad='738d912300' sqlid='fhzj09a7fnrnb' SELECT DBTIMEZONE, LENGTH(DBTIMEZONE) FROM SYS.DUAL END OF STMT PARSE #140533522704768:c=1000,e=448,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=1,plh=1388734953,tim=1376175759114688 EXEC #140533522704768:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=1388734953,tim=1376175759114749 FETCH #140533522704768:c=0,e=5,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,plh=1388734953,tim=1376175759114781 STAT #140533522704768 id=1 cnt=1 pid=0 pos=1 obj=0 op='FAST DUAL (cr=0 pr=0 pw=0 time=1 us cost=2 size=0 card=1)' WAIT #140533526719744: nam='SQL*Net message to client' ela= 6 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759115424 WAIT #140533526719744: nam='Datapump dump file I/O' ela= 8217 count=4294967295 intr=256 timeout=2147483647 obj#=-1 tim=1376175759123987 WAIT #140533526719744: nam='Disk file operations I/O' ela= 20 FileOperation=5 fileno=0 filetype=18 obj#=-1 tim=1376175759124043 FETCH #140533526719744:c=617906,e=1801033745,p=0,cr=77,cu=0,mis=0,r=1,dep=0,og=1,plh=2394912001,tim=1376175759124187 STAT #140533526719744 id=1 cnt=1 pid=0 pos=1 obj=0 op='COUNT STOPKEY (cr=77 pr=0 pw=0 time=1801033677 us)' STAT #140533526719744 id=2 cnt=1 pid=1 pos=1 obj=3694948 op='EXTERNAL TABLE ACCESS FULL IMP_3251198_2419 (cr=71 pr=0 pw=0 time=1801024975 us cost=2 size=2137 card=1)' WAIT #140533526719744: nam='SQL*Net message from client' ela= 53913 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759178223 PARSE #140533522706936:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,plh=0,tim=1376175759179983 BINDS #140533522706936: Bind#0 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=00 fl2=0001 frm=00 csi=00 siz=24 off=0 kxsbbbfp=7fd082b36b00 bln=22 avl=00 flg=05 Bind#1 oacdty=123 mxl=4000(4000) mxlc=00 mal=00 scl=00 pre=00 oacflg=00 fl2=0001 frm=00 csi=00 siz=4000 off=0 toid ptr value=CF998DA8 length=16 8A42AB108F674EB2E040A80AA4107D64 kxsbbbfp=7fd082b50ef0 bln=4000 avl=00 flg=15 EXEC #140533522706936:c=0,e=368,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,plh=0,tim=1376175759180524 CLOSE #140533522706936:c=0,e=14,dep=1,type=3,tim=1376175759180584 ===================== PARSING IN CURSOR #140533526737928 len=31 dep=0 uid=35 oct=3 lid=35 tim=1376175759190316 hv=2834092765 ad='71432d6958' sqlid='3m5gnzfnftnqx' SELECT * FROM IMP_3251198_2419 END OF STMT PARSE #140533526737928:c=12998,e=11800,p=0,cr=528,cu=0,mis=1,r=0,dep=0,og=1,plh=222062658,tim=1376175759190315 WAIT #140533526737928: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759190393 WAIT #140533526737928: nam='SQL*Net message from client' ela= 69734 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759260190 CLOSE #140533526719744:c=0,e=13,dep=0,type=0,tim=1376175759260275 CLOSE #140533526737928:c=0,e=2,dep=0,type=0,tim=1376175759260382 PARSE #140533526786456:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376175759260425 BINDS #140533526786456: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 WAIT #140533526786456: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759260521 EXEC #140533526786456:c=0,e=94,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376175759260539 WAIT #140533526786456: nam='SQL*Net message from client' ela= 34244 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759294813 CLOSE #140533526786456:c=0,e=8,dep=0,type=3,tim=1376175759294851 PARSE #140533526739168:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376175759294887 BINDS #140533526739168: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 Bind#1 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=24 off=0 kxsbbbfp=7fd082b2e5b0 bln=22 avl=00 flg=05 WAIT #140533526739168: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376175759294986 EXEC #140533526739168:c=0,e=96,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376175759295001 *** 2013-08-10 16:12:03.638 WAIT #140533526739168: nam='SQL*Net message from client' ela= 564343635 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176323638658 CLOSE #140533526739168:c=0,e=40,dep=0,type=3,tim=1376176323638807 PARSE #140533526786456:c=0,e=131,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376176323638990 BINDS #140533526786456: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 WAIT #140533526786456: nam='SQL*Net message to client' ela= 10 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176323639195 EXEC #140533526786456:c=0,e=184,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376176323639219 *** 2013-08-10 16:12:10.214 WAIT #140533526786456: nam='SQL*Net message from client' ela= 6575298 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330214559 CLOSE #140533526786456:c=0,e=9,dep=0,type=3,tim=1376176330214713 PARSE #140533526726480:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1388734953,tim=1376176330214751 EXEC #140533526726480:c=0,e=22,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1388734953,tim=1376176330214788 WAIT #140533526726480: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330214815 WAIT #140533526726480: nam='SQL*Net message from client' ela= 25168 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330240011 WAIT #140533526726480: nam='SQL*Net message to client' ela= 4 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330240094 FETCH #140533526726480:c=0,e=52,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=1388734953,tim=1376176330240126 WAIT #140533526726480: nam='SQL*Net message from client' ela= 28081 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330268336 CLOSE #140533526726480:c=0,e=4,dep=0,type=3,tim=1376176330268398 PARSE #140533526768216:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376176330268437 BINDS #140533526768216: Bind#0 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=48 off=0 kxsbbbfp=7fd082b2e598 bln=22 avl=02 flg=05 value=1 Bind#1 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=0 off=24 kxsbbbfp=7fd082b2e5b0 bln=22 avl=00 flg=01 WAIT #140533526768216: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330268565 EXEC #140533526768216:c=0,e=126,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376176330268581 WAIT #140533526768216: nam='SQL*Net message from client' ela= 294983 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330563585 CLOSE #140533526768216:c=0,e=16,dep=0,type=3,tim=1376176330563663 PARSE #140533526786456:c=0,e=12,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376176330563712 BINDS #140533526786456: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 WAIT #140533526786456: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330563783 EXEC #140533526786456:c=0,e=67,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376176330563797 WAIT #140533526786456: nam='SQL*Net message from client' ela= 25572 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330589391 CLOSE #140533526786456:c=0,e=7,dep=0,type=3,tim=1376176330589427 ===================== PARSING IN CURSOR #140533526737928 len=36 dep=0 uid=35 oct=42 lid=35 tim=1376176330590621 hv=1962437709 ad='7fd082edfe10' sqlid='30tsghxughv2d' ALTER SESSION SET SQL_TRACE = FALSE END OF STMT PARSE #140533526737928:c=2000,e=1175,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,plh=0,tim=1376176330590620 EXEC #140533526737928:c=0,e=64,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376176330590728 WAIT #140533526737928: nam='SQL*Net message to client' ela= 3 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330591125 WAIT #140533526737928: nam='SQL*Net message from client' ela= 77197 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330668341 CLOSE #140533526737928:c=0,e=3,dep=0,type=0,tim=1376176330668403 PARSE #140533526786456:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376176330668436 BINDS #140533526786456: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 WAIT #140533526786456: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330668507 EXEC #140533526786456:c=0,e=69,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376176330668522 WAIT #140533526786456: nam='SQL*Net message from client' ela= 26533 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330695076 CLOSE #140533526786456:c=0,e=8,dep=0,type=3,tim=1376176330695221 PARSE #140533526739168:c=0,e=14,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=1376176330695256 BINDS #140533526739168: Bind#0 oacdty=01 mxl=16386(12000) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=01 csi=871 siz=16392 off=0 kxsbbbfp=7fd082aa1ff8 bln=16386 avl=00 flg=05 Bind#1 oacdty=02 mxl=22(22) mxlc=00 mal=00 scl=00 pre=00 oacflg=01 fl2=1000000 frm=00 csi=00 siz=24 off=0 kxsbbbfp=7fd082b2e5b0 bln=22 avl=00 flg=05 WAIT #140533526739168: nam='SQL*Net message to client' ela= 2 driver id=1413697536 #bytes=1 p3=0 obj#=-1 tim=1376176330695350 EXEC #140533526739168:c=0,e=91,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=1376176330695365