Trace file C:\ORACLE\ORA12C\12CTEST\HOME\diag\rdbms\testdb12c\testdb12c\trace\testdb12c_ora_7092.trc
Oracle Database 12c Enterprise Edition Release 12.1.0.2.0 - 64bit Production
With the Partitioning, OLAP, Advanced Analytics and Real Application Testing options
Windows NT Version V6.2  
CPU                 : 8 - type 8664, 4 Physical Cores
Process Affinity    : 0x0x0000000000000000
Memory (Avail/Total): Ph:38875M/65439M, Ph+PgF:42280M/69535M 
Instance name: testdb12c
Redo thread mounted by this instance: 1
Oracle process number: 165
Windows thread id: 7092, image: ORACLE.EXE (SHAD)


*** 2016-03-02 11:15:25.142
*** SESSION ID:(2381.39357) 2016-03-02 11:15:25.142
*** CLIENT ID:() 2016-03-02 11:15:25.142
*** SERVICE NAME:(SYS$USERS) 2016-03-02 11:15:25.142
*** MODULE NAME:(SQL*Plus) 2016-03-02 11:15:25.142
*** CLIENT DRIVER:(SQL*PLUS) 2016-03-02 11:15:25.142
*** ACTION NAME:() 2016-03-02 11:15:25.142
 
CLOSE #1895485176:c=0,e=4,dep=1,type=1,tim=2403300972275
=====================
PARSING IN CURSOR #1894997848 len=597 dep=1 uid=0 oct=2 lid=0 tim=2403300973030 hv=914163366 ad='8f7ca3dd0' sqlid='4vs91dcv7u1p6'
insert into sys.aud$( sessionid,entryid,statement,ntimestamp#, userid,userhost,terminal,action#,returncode, obj$creator,obj$name,auth$privileges,auth$grantee, new$owner,new$name,ses$actions,ses$tid,logoff$pread, logoff$lwrite,logoff$dead,comment$text,spare1,spare2,  priv$used,clientid,sessioncpu,proxy$sid,user$guid, instance#,process#,xid,scn,auditid,  sqlbind,sqltext,obj$edition,dbid)  values(:1,:2,:3,SYS_EXTRACT_UTC(SYSTIMESTAMP),     :4,:5,:6,:7,:8,     :9,:10,:11,:12,     :13,:14,:15,:16,:17,     :18,:19,:20,:21,:22,     :23,:24,:25,:26,:27,     :28,:29,:30,:31,:32,     :33,:34,:35,:36)
END OF STMT
EXEC #1894997848:c=0,e=106,p=0,cr=0,cu=2,mis=0,r=1,dep=1,og=4,plh=0,tim=2403300973029

*** 2016-03-02 11:16:04.260
CLOSE #1895682888:c=0,e=4,dep=0,type=1,tim=2403340093025
=====================
PARSING IN CURSOR #1895671600 len=245 dep=1 uid=0 oct=3 lid=0 tim=2403340093521 hv=1602468406 ad='917ce64a8' sqlid='1p5grz1gs7fjq'
select obj#,type#,ctime,mtime,stime, status, dataobj#, flags, oid$, spare1, spare2, spare3, signature, spare7, spare8, spare9 from obj$ where owner#=:1 and name=:2 and namespace=:3 and remoteowner is null and linkname is null and subname is null
END OF STMT
EXEC #1895671600:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=813480514,tim=2403340093520
FETCH #1895671600:c=0,e=14,p=0,cr=3,cu=0,mis=0,r=0,dep=1,og=4,plh=813480514,tim=2403340093579
CLOSE #1895671600:c=0,e=1,dep=1,type=3,tim=2403340093597
EXEC #1895671600:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=813480514,tim=2403340093647
FETCH #1895671600:c=0,e=15,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=813480514,tim=2403340093673
CLOSE #1895671600:c=0,e=2,dep=1,type=3,tim=2403340093688
=====================
PARSING IN CURSOR #1895490240 len=46 dep=1 uid=0 oct=3 lid=0 tim=2403340093959 hv=1343089354 ad='8f7e1bd88' sqlid='1mjd9xp80vuqa'
select node,owner,name from syn$ where obj#=:1
END OF STMT
PARSE #1895490240:c=0,e=239,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340093958
EXEC #1895490240:c=15625,e=630,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=3023518864,tim=2403340094629
FETCH #1895490240:c=0,e=15,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,plh=3023518864,tim=2403340094671
STAT #1895490240 id=1 cnt=1 pid=0 pos=1 obj=94 op='TABLE ACCESS BY INDEX ROWID SYN$ (cr=3 pr=0 pw=0 time=14 us cost=2 size=36 card=1)'
STAT #1895490240 id=2 cnt=1 pid=1 pos=1 obj=103 op='INDEX UNIQUE SCAN I_SYN1 (cr=2 pr=0 pw=0 time=7 us cost=1 size=0 card=1)'
CLOSE #1895490240:c=0,e=1,dep=1,type=3,tim=2403340094717
=====================
PARSING IN CURSOR #1895678128 len=191 dep=1 uid=0 oct=3 lid=0 tim=2403340095021 hv=1070240621 ad='90fa20548' sqlid='3un99a0zwp4vd'
select owner#,name,namespace,remoteowner,linkname,p_timestamp,p_obj#, nvl(property,0),subname,type#,flags,d_attrs from dependency$ d, obj$ o where d_obj#=:1 and p_obj#=obj#(+) order by order#
END OF STMT
PARSE #1895678128:c=0,e=290,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340095021
EXEC #1895678128:c=0,e=1832,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=1475428744,tim=2403340096938
FETCH #1895678128:c=0,e=42,p=0,cr=8,cu=0,mis=0,r=1,dep=1,og=4,plh=1475428744,tim=2403340097006
FETCH #1895678128:c=0,e=4,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=1475428744,tim=2403340097031
STAT #1895678128 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT ORDER BY (cr=8 pr=0 pw=0 time=169 us cost=17 size=312 card=4)'
STAT #1895678128 id=2 cnt=1 pid=1 pos=1 obj=0 op='HASH JOIN OUTER (cr=8 pr=0 pw=0 time=157 us cost=16 size=312 card=4)'
STAT #1895678128 id=3 cnt=1 pid=2 pos=1 obj=0 op='NESTED LOOPS OUTER (cr=8 pr=0 pw=0 time=153 us cost=16 size=312 card=4)'
STAT #1895678128 id=4 cnt=1 pid=3 pos=1 obj=0 op='STATISTICS COLLECTOR  (cr=4 pr=0 pw=0 time=142 us)'
STAT #1895678128 id=5 cnt=1 pid=4 pos=1 obj=83 op='TABLE ACCESS BY INDEX ROWID BATCHED DEPENDENCY$ (cr=4 pr=0 pw=0 time=15 us cost=4 size=112 card=4)'
STAT #1895678128 id=6 cnt=1 pid=5 pos=1 obj=85 op='INDEX RANGE SCAN I_DEPENDENCY1 (cr=3 pr=0 pw=0 time=10 us cost=3 size=0 card=4)'
STAT #1895678128 id=7 cnt=1 pid=3 pos=2 obj=18 op='TABLE ACCESS BY INDEX ROWID BATCHED OBJ$ (cr=4 pr=0 pw=0 time=8 us cost=3 size=50 card=1)'
STAT #1895678128 id=8 cnt=1 pid=7 pos=1 obj=36 op='INDEX RANGE SCAN I_OBJ1 (cr=3 pr=0 pw=0 time=6 us cost=2 size=0 card=1)'
STAT #1895678128 id=9 cnt=0 pid=2 pos=2 obj=18 op='TABLE ACCESS FULL OBJ$ (cr=0 pr=0 pw=0 time=0 us cost=3 size=50 card=1)'
CLOSE #1895678128:c=0,e=3,dep=1,type=0,tim=2403340097128
=====================
PARSING IN CURSOR #1895678128 len=56 dep=1 uid=0 oct=3 lid=0 tim=2403340097345 hv=3993603298 ad='917cc4ab0' sqlid='8swypbbr0m372'
select order#,columns,types from access$ where d_obj#=:1
END OF STMT
PARSE #1895678128:c=0,e=204,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340097344
EXEC #1895678128:c=0,e=421,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=570326988,tim=2403340097809
FETCH #1895678128:c=0,e=11,p=0,cr=3,cu=0,mis=0,r=0,dep=1,og=4,plh=570326988,tim=2403340097843
STAT #1895678128 id=1 cnt=0 pid=0 pos=1 obj=84 op='TABLE ACCESS BY INDEX ROWID BATCHED ACCESS$ (cr=3 pr=0 pw=0 time=13 us cost=4 size=210 card=7)'
STAT #1895678128 id=2 cnt=0 pid=1 pos=1 obj=87 op='INDEX RANGE SCAN I_ACCESS1 (cr=3 pr=0 pw=0 time=10 us cost=3 size=0 card=7)'
CLOSE #1895678128:c=0,e=3,dep=1,type=0,tim=2403340097884
=====================
PARSING IN CURSOR #1895678128 len=132 dep=1 uid=0 oct=3 lid=0 tim=2403340098131 hv=2328831744 ad='9178e3b30' sqlid='ga9j9xk5cy9s0'
select /*+ index(idl_sb4$ i_idl_sb41) +*/ piece#,length,piece from idl_sb4$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=224,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340098131
EXEC #1895678128:c=0,e=530,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=1697022209,tim=2403340098710
FETCH #1895678128:c=0,e=8,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=1697022209,tim=2403340098740
STAT #1895678128 id=1 cnt=0 pid=0 pos=1 obj=305 op='TABLE ACCESS BY INDEX ROWID IDL_SB4$ (cr=2 pr=0 pw=0 time=9 us cost=3 size=19 card=1)'
STAT #1895678128 id=2 cnt=0 pid=1 pos=1 obj=316 op='INDEX RANGE SCAN I_IDL_SB41 (cr=2 pr=0 pw=0 time=9 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=2,dep=1,type=0,tim=2403340098781
=====================
PARSING IN CURSOR #1895678128 len=132 dep=1 uid=0 oct=3 lid=0 tim=2403340098991 hv=4260389146 ad='9178db830' sqlid='cvn54b7yz0s8u'
select /*+ index(idl_ub1$ i_idl_ub11) +*/ piece#,length,piece from idl_ub1$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=201,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340098990
EXEC #1895678128:c=0,e=537,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=3246118364,tim=2403340099576
FETCH #1895678128:c=0,e=7,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3246118364,tim=2403340099606
STAT #1895678128 id=1 cnt=0 pid=0 pos=1 obj=302 op='TABLE ACCESS BY INDEX ROWID IDL_UB1$ (cr=2 pr=0 pw=0 time=8 us cost=3 size=20 card=1)'
STAT #1895678128 id=2 cnt=0 pid=1 pos=1 obj=313 op='INDEX RANGE SCAN I_IDL_UB11 (cr=2 pr=0 pw=0 time=7 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=3,dep=1,type=0,tim=2403340099671
=====================
PARSING IN CURSOR #1895678128 len=135 dep=1 uid=0 oct=3 lid=0 tim=2403340099943 hv=1115215392 ad='9178cfa00' sqlid='c6awqs517jpj0'
select /*+ index(idl_char$ i_idl_char1) +*/ piece#,length,piece from idl_char$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=262,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340099942
EXEC #1895678128:c=0,e=522,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=1319326155,tim=2403340100513
FETCH #1895678128:c=0,e=7,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=1319326155,tim=2403340100543
STAT #1895678128 id=1 cnt=0 pid=0 pos=1 obj=303 op='TABLE ACCESS BY INDEX ROWID IDL_CHAR$ (cr=2 pr=0 pw=0 time=9 us cost=3 size=21 card=1)'
STAT #1895678128 id=2 cnt=0 pid=1 pos=1 obj=314 op='INDEX RANGE SCAN I_IDL_CHAR1 (cr=2 pr=0 pw=0 time=8 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=3,dep=1,type=0,tim=2403340100585
=====================
PARSING IN CURSOR #1895678128 len=132 dep=1 uid=0 oct=3 lid=0 tim=2403340100793 hv=1684122946 ad='9178c74d0' sqlid='39m4sx9k63ba2'
select /*+ index(idl_ub2$ i_idl_ub21) +*/ piece#,length,piece from idl_ub2$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=201,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340100793
EXEC #1895678128:c=0,e=523,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=2317816222,tim=2403340101364
FETCH #1895678128:c=0,e=7,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=2317816222,tim=2403340101394
STAT #1895678128 id=1 cnt=0 pid=0 pos=1 obj=304 op='TABLE ACCESS BY INDEX ROWID IDL_UB2$ (cr=2 pr=0 pw=0 time=8 us cost=3 size=21 card=1)'
STAT #1895678128 id=2 cnt=0 pid=1 pos=1 obj=315 op='INDEX RANGE SCAN I_IDL_UB21 (cr=2 pr=0 pw=0 time=8 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=2,dep=1,type=0,tim=2403340101435
=====================
PARSING IN CURSOR #1895678128 len=132 dep=1 uid=0 oct=3 lid=0 tim=2403340101473 hv=2328831744 ad='9178e3b30' sqlid='ga9j9xk5cy9s0'
select /*+ index(idl_sb4$ i_idl_sb41) +*/ piece#,length,piece from idl_sb4$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=1697022209,tim=2403340101473
EXEC #1895678128:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=1697022209,tim=2403340101525
FETCH #1895678128:c=0,e=11,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=1697022209,tim=2403340101548
FETCH #1895678128:c=0,e=6,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,plh=1697022209,tim=2403340101581
FETCH #1895678128:c=0,e=2,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,plh=1697022209,tim=2403340101595
STAT #1895678128 id=1 cnt=2 pid=0 pos=1 obj=305 op='TABLE ACCESS BY INDEX ROWID IDL_SB4$ (cr=6 pr=0 pw=0 time=7 us cost=3 size=19 card=1)'
STAT #1895678128 id=2 cnt=2 pid=1 pos=1 obj=316 op='INDEX RANGE SCAN I_IDL_SB41 (cr=4 pr=0 pw=0 time=6 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=2,dep=1,type=0,tim=2403340101632
=====================
PARSING IN CURSOR #1895678128 len=132 dep=1 uid=0 oct=3 lid=0 tim=2403340101651 hv=4260389146 ad='9178db830' sqlid='cvn54b7yz0s8u'
select /*+ index(idl_ub1$ i_idl_ub11) +*/ piece#,length,piece from idl_ub1$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=12,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3246118364,tim=2403340101651
EXEC #1895678128:c=0,e=9,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3246118364,tim=2403340101698
FETCH #1895678128:c=0,e=9,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=3246118364,tim=2403340101718
FETCH #1895678128:c=0,e=3,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,plh=3246118364,tim=2403340101735
STAT #1895678128 id=1 cnt=1 pid=0 pos=1 obj=302 op='TABLE ACCESS BY INDEX ROWID IDL_UB1$ (cr=4 pr=0 pw=0 time=8 us cost=3 size=20 card=1)'
STAT #1895678128 id=2 cnt=1 pid=1 pos=1 obj=313 op='INDEX RANGE SCAN I_IDL_UB11 (cr=3 pr=0 pw=0 time=7 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=2,dep=1,type=0,tim=2403340101770
=====================
PARSING IN CURSOR #1895678128 len=135 dep=1 uid=0 oct=3 lid=0 tim=2403340101787 hv=1115215392 ad='9178cfa00' sqlid='c6awqs517jpj0'
select /*+ index(idl_char$ i_idl_char1) +*/ piece#,length,piece from idl_char$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=1319326155,tim=2403340101787
EXEC #1895678128:c=0,e=9,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=1319326155,tim=2403340101836
FETCH #1895678128:c=0,e=9,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=1319326155,tim=2403340101856
FETCH #1895678128:c=0,e=2,p=0,cr=1,cu=0,mis=0,r=0,dep=1,og=4,plh=1319326155,tim=2403340101883
STAT #1895678128 id=1 cnt=1 pid=0 pos=1 obj=303 op='TABLE ACCESS BY INDEX ROWID IDL_CHAR$ (cr=4 pr=0 pw=0 time=7 us cost=3 size=21 card=1)'
STAT #1895678128 id=2 cnt=1 pid=1 pos=1 obj=314 op='INDEX RANGE SCAN I_IDL_CHAR1 (cr=3 pr=0 pw=0 time=5 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=2,dep=1,type=0,tim=2403340101919
=====================
PARSING IN CURSOR #1895678128 len=132 dep=1 uid=0 oct=3 lid=0 tim=2403340101936 hv=1684122946 ad='9178c74d0' sqlid='39m4sx9k63ba2'
select /*+ index(idl_ub2$ i_idl_ub21) +*/ piece#,length,piece from idl_ub2$ where obj#=:1 and part=:2 and version=:3 order by piece#
END OF STMT
PARSE #1895678128:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=2317816222,tim=2403340101936
EXEC #1895678128:c=0,e=9,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=2317816222,tim=2403340101981
FETCH #1895678128:c=0,e=8046,p=1,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=2317816222,tim=2403340110037
FETCH #1895678128:c=0,e=199,p=1,cr=3,cu=0,mis=0,r=1,dep=1,og=4,plh=2317816222,tim=2403340110273
STAT #1895678128 id=1 cnt=2 pid=0 pos=1 obj=304 op='TABLE ACCESS BY INDEX ROWID IDL_UB2$ (cr=5 pr=2 pw=0 time=8043 us cost=3 size=21 card=1)'
STAT #1895678128 id=2 cnt=2 pid=1 pos=1 obj=315 op='INDEX RANGE SCAN I_IDL_UB21 (cr=3 pr=0 pw=0 time=7 us cost=2 size=0 card=1)'
CLOSE #1895678128:c=0,e=32,dep=1,type=0,tim=2403340110351
EXEC #1895671600:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=813480514,tim=2403340110417
FETCH #1895671600:c=0,e=14,p=0,cr=4,cu=0,mis=0,r=1,dep=1,og=4,plh=813480514,tim=2403340110443
CLOSE #1895671600:c=0,e=3,dep=1,type=3,tim=2403340110458
=====================
PARSING IN CURSOR #1895677976 len=238 dep=1 uid=0 oct=3 lid=0 tim=2403340110730 hv=130978137 ad='8eb966160' sqlid='f0nbbn03wx4at'
select privilege#,nvl(col#,0),max(mod(nvl(option$,0),2)),min(bitand(nvl(option$,0), 4) /4), max(bitand(nvl(option$,0), 8) /8), max(bitand(nvl(option$,0), 16) /16) from objauth$ where obj#=:1 and grantee#=:2 group by privilege#,nvl(col#,0)
END OF STMT
PARSE #1895677976:c=0,e=255,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403340110729
EXEC #1895677976:c=0,e=743,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=3290949889,tim=2403340111521
FETCH #1895677976:c=0,e=14,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340111553
STAT #1895677976 id=1 cnt=0 pid=0 pos=1 obj=0 op='SORT GROUP BY (cr=2 pr=0 pw=0 time=21 us cost=4 size=16 card=1)'
STAT #1895677976 id=2 cnt=0 pid=1 pos=1 obj=61 op='TABLE ACCESS BY INDEX ROWID BATCHED OBJAUTH$ (cr=2 pr=0 pw=0 time=11 us cost=3 size=16 card=1)'
STAT #1895677976 id=3 cnt=0 pid=2 pos=1 obj=63 op='INDEX RANGE SCAN I_OBJAUTH2 (cr=2 pr=0 pw=0 time=10 us cost=2 size=0 card=1)'
CLOSE #1895677976:c=0,e=1,dep=1,type=3,tim=2403340111609
EXEC #1895677976:c=0,e=14,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340111639
FETCH #1895677976:c=0,e=3709,p=1,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340115358
CLOSE #1895677976:c=0,e=0,dep=1,type=3,tim=2403340115387
EXEC #1895677976:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340115421
FETCH #1895677976:c=0,e=9949,p=1,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125381
CLOSE #1895677976:c=0,e=0,dep=1,type=3,tim=2403340125402
EXEC #1895677976:c=0,e=14,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125430
FETCH #1895677976:c=0,e=6,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125446
CLOSE #1895677976:c=0,e=1,dep=1,type=3,tim=2403340125460
EXEC #1895677976:c=0,e=11,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125482
FETCH #1895677976:c=0,e=7,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125534
CLOSE #1895677976:c=0,e=1,dep=1,type=3,tim=2403340125550
EXEC #1895677976:c=15625,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125577
FETCH #1895677976:c=0,e=186,p=1,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125786
CLOSE #1895677976:c=0,e=1,dep=1,type=3,tim=2403340125807
EXEC #1895677976:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125833
FETCH #1895677976:c=0,e=6,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125847
CLOSE #1895677976:c=0,e=0,dep=1,type=3,tim=2403340125860
EXEC #1895677976:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125881
FETCH #1895677976:c=0,e=5,p=0,cr=2,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125895
CLOSE #1895677976:c=0,e=0,dep=1,type=3,tim=2403340125907
EXEC #1895677976:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125928
FETCH #1895677976:c=0,e=27,p=0,cr=3,cu=0,mis=0,r=1,dep=1,og=4,plh=3290949889,tim=2403340125963
FETCH #1895677976:c=0,e=3,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=3290949889,tim=2403340125979
CLOSE #1895677976:c=0,e=0,dep=1,type=3,tim=2403340125992
=====================
PARSING IN CURSOR #1895312376 len=253 dep=0 uid=109 oct=47 lid=109 tim=2403340126493 hv=4107843811 ad='8b6426a10' sqlid='2gzb5brudjf73'
BEGIN
    DBMS_SESSION.session_trace_enable (waits          => TRUE,
                                       binds          => FALSE,
                                       plan_stat      => 'all_executions'
                                      );
 END;
END OF STMT
PARSE #1895312376:c=31250,e=33417,p=5,cr=77,cu=0,mis=1,r=0,dep=0,og=1,plh=0,tim=2403340126493
EXEC #1895312376:c=0,e=9393,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,plh=0,tim=2403340135931
EXEC #1894997848:c=0,e=630,p=0,cr=1,cu=2,mis=1,r=1,dep=1,og=4,plh=0,tim=2403340136634
STAT #1894997848 id=1 cnt=0 pid=0 pos=1 obj=0 op='LOAD TABLE CONVENTIONAL  AUD$ (cr=1 pr=0 pw=0 time=83 us)'
WAIT #1895312376: nam='log file sync' ela= 208 buffer#=46957 sync scn=2095528681 p3=0 obj#=-1 tim=2403340136917
WAIT #1895312376: nam='SQL*Net message to client' ela= 1 driver id=1111838976 #bytes=1 p3=0 obj#=-1 tim=2403340136998

*** 2016-03-02 11:16:31.795
WAIT #1895312376: nam='SQL*Net message from client' ela= 27476738 driver id=1111838976 #bytes=1 p3=0 obj#=-1 tim=2403367613757
CLOSE #1895312376:c=0,e=10,dep=0,type=0,tim=2403367613825
XCTEND rlbk=0, rd_only=1, tim=2403367614007
=====================
PARSING IN CURSOR #1895312376 len=26 dep=0 uid=109 oct=17 lid=109 tim=2403367614121 hv=3119730303 ad='8ebb2d3f8' sqlid='0qshmpqwz6mmz'
grant isslapps to catftes
END OF STMT
PARSE #1895312376:c=0,e=274,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,plh=0,tim=2403367614121
=====================
PARSING IN CURSOR #1734779264 len=70 dep=1 uid=0 oct=3 lid=0 tim=2403367614215 hv=1853064805 ad='90f791b00' sqlid='5hrvvu1r771m5'
 SELECT VALUE$ FROM SYS.PROPS$ WHERE NAME = 'OGG_TRIGGER_OPTIMIZATION'
END OF STMT
PARSE #1734779264:c=0,e=29,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=415205717,tim=2403367614215
EXEC #1734779264:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=4,plh=415205717,tim=2403367614269
FETCH #1734779264:c=0,e=22,p=0,cr=3,cu=0,mis=0,r=0,dep=1,og=4,plh=415205717,tim=2403367614301
STAT #1734779264 id=1 cnt=0 pid=0 pos=1 obj=126 op='TABLE ACCESS FULL PROPS$ (cr=3 pr=0 pw=0 time=22 us cost=2 size=28 card=1)'

*** 2016-03-02 11:19:49.892
WAIT #1895312376: nam='enq: CB - role operation' ela= 198092906 name|mode=1128398854 0=0 0=0 obj#=-1 tim=2403565707281
EXEC #1895312376:c=0,e=198093273,p=0,cr=3,cu=0,mis=0,r=0,dep=0,og=1,plh=0,tim=2403565707423
ERROR #1895312376:err=3113 tim=2403565707442
=====================
PARSING IN CURSOR #1734776072 len=447 dep=1 uid=0 oct=2 lid=0 tim=2403565707997 hv=1097020010 ad='8f6bbdf48' sqlid='f711myt0q6cma'
insert into sys.aud$( sessionid,entryid,statement,ntimestamp#, userid,userhost,terminal,action#,returncode, logoff$lread,logoff$pread,logoff$lwrite,logoff$dead, logoff$time,comment$text,spare1,clientid,sessioncpu,proxy$sid,user$guid, instance#,process#,auditid,dbid) values(:1,:2,:3,SYS_EXTRACT_UTC(SYSTIMESTAMP),     :4,:5,:6,:7,:8,     :9,:10,:11,:12,     cast(SYS_EXTRACT_UTC(systimestamp) as date),:13,:14,:15,:16,:17,:18,     :19,:20,:21,:22)
END OF STMT
PARSE #1734776072:c=0,e=378,p=0,cr=0,cu=0,mis=1,r=0,dep=1,og=4,plh=0,tim=2403565707997
EXEC #1734776072:c=0,e=535,p=0,cr=1,cu=2,mis=1,r=1,dep=1,og=4,plh=0,tim=2403565708609
STAT #1734776072 id=1 cnt=0 pid=0 pos=1 obj=0 op='LOAD TABLE CONVENTIONAL  AUD$ (cr=1 pr=0 pw=0 time=82 us)'
WAIT #1895312376: nam='log file sync' ela= 73 buffer#=10893 sync scn=2095529331 p3=0 obj#=-1 tim=2403565708795
CLOSE #1734776072:c=0,e=3,dep=0,type=0,tim=2403565708828
CLOSE #1734779264:c=0,e=2,dep=0,type=0,tim=2403565708846
CLOSE #1895312376:c=0,e=1,dep=0,type=0,tim=2403565708855
CLOSE #1894997848:c=0,e=4,dep=0,type=0,tim=2403565708866