Trace file /u01/diag/rdbms/orcl/orcl/trace/orcl_ora_3017.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 = /u01/product/11.2.0/dbhome_1 System name: Linux Node name: lin6.localdomain Release: 2.6.39-201.0.2.el6uek.x86_64 Version: #1 SMP Fri Nov 2 14:38:23 PDT 2012 Machine: x86_64 Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Unix process pid: 3017, image: oracle@lin6.localdomain *** 2012-10-21 07:22:43.600 *** SESSION ID:(31.67) 2012-10-21 07:22:43.600 *** CLIENT ID:() 2012-10-21 07:22:43.600 *** SERVICE NAME:(SYS$USERS) 2012-10-21 07:22:43.600 *** MODULE NAME:(SQL*Plus) 2012-10-21 07:22:43.600 *** ACTION NAME:() 2012-10-21 07:22:43.600 ===================== PARSING IN CURSOR #139665587261584 len=52 dep=0 uid=90 oct=3 lid=90 tim=1350789763599832 hv=194023787 ad='9716f7c0' sqlid='9pzmsyh5t14bb' select --+ index(test) count(pad) from test END OF STMT EXEC #139665587261584:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=4182611088,tim=1350789763599829 *** 2012-10-21 07:22:49.054 FETCH #139665587261584:c=5403178,e=5453897,p=0,cr=41400,cu=0,mis=0,r=1,dep=0,og=1,plh=4182611088,tim=1350789769054152 STAT #139665587261584 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=41400 pr=0 pw=0 time=5453892 us)' STAT #139665587261584 id=2 cnt=10000000 pid=1 pos=1 obj=76865 op='TABLE ACCESS BY INDEX ROWID TEST (cr=41400 pr=0 pw=0 time=4543585 us cost=66128 size=20000000 card=10000000)' STAT #139665587261584 id=3 cnt=10000000 pid=2 pos=1 obj=76866 op='INDEX FULL SCAN IDX (cr=23555 pr=0 pw=0 time=1737859 us cost=24547 size=0 card=10000000)' FETCH #139665587261584:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=4182611088,tim=1350789769054887 CLOSE #139665587261584:c=0,e=12,dep=0,type=1,tim=1350789769058185 *** 2012-10-21 07:23:04.159 ===================== PARSING IN CURSOR #139665578749400 len=52 dep=0 uid=90 oct=3 lid=90 tim=1350789784159394 hv=194023787 ad='9716f7c0' sqlid='9pzmsyh5t14bb' select --+ index(test) count(pad) from test END OF STMT EXEC #139665578749400:c=0,e=79,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=4182611088,tim=1350789784159388 *** 2012-10-21 07:23:09.329 FETCH #139665578749400:c=5119221,e=5169173,p=0,cr=41400,cu=0,mis=0,r=1,dep=0,og=1,plh=4182611088,tim=1350789789329746 STAT #139665578749400 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=41400 pr=0 pw=0 time=5169190 us)' STAT #139665578749400 id=2 cnt=10000000 pid=1 pos=1 obj=76865 op='TABLE ACCESS BY INDEX ROWID TEST (cr=41400 pr=0 pw=0 time=4285927 us cost=66128 size=20000000 card=10000000)' STAT #139665578749400 id=3 cnt=10000000 pid=2 pos=1 obj=76866 op='INDEX FULL SCAN IDX (cr=23555 pr=0 pw=0 time=1633998 us cost=24547 size=0 card=10000000)' FETCH #139665578749400:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=4182611088,tim=1350789789330611 CLOSE #139665578749400:c=0,e=12,dep=0,type=1,tim=1350789789333751 *** 2012-10-21 07:23:27.822 ===================== PARSING IN CURSOR #139665585290416 len=52 dep=0 uid=90 oct=3 lid=90 tim=1350789807822188 hv=194023787 ad='9716f7c0' sqlid='9pzmsyh5t14bb' select --+ index(test) count(pad) from test END OF STMT EXEC #139665585290416:c=0,e=70,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=4182611088,tim=1350789807822184 *** 2012-10-21 07:23:39.491 FETCH #139665585290416:c=11504250,e=11668447,p=0,cr=41400,cu=0,mis=0,r=1,dep=0,og=1,plh=4182611088,tim=1350789819491035 STAT #139665585290416 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=41400 pr=0 pw=0 time=11668451 us)' STAT #139665585290416 id=2 cnt=10000000 pid=1 pos=1 obj=76865 op='TABLE ACCESS BY INDEX ROWID TEST (cr=41400 pr=0 pw=0 time=9153415 us cost=66128 size=20000000 card=10000000)' STAT #139665585290416 id=3 cnt=10000000 pid=2 pos=1 obj=76866 op='INDEX FULL SCAN IDX (cr=23555 pr=0 pw=0 time=3276275 us cost=24547 size=0 card=10000000)' FETCH #139665585290416:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=4182611088,tim=1350789819491567 CLOSE #139665585290416:c=0,e=11,dep=0,type=1,tim=1350789819493735