Trace file /u01/app/oracle/diag/rdbms/orcl2/orcl2/trace/orcl2_ora_2946.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/app/oracle/product/11.2.3/db
System name:	SunOS
Node name:	sol10
Release:	5.10
Version:	Generic_141445-09
Machine:	i86pc
Instance name: orcl2
Redo thread mounted by this instance: 1
Oracle process number: 34
Unix process pid: 2946, image: oracle@sol10


*** 2012-12-19 18:54:47.897
*** SESSION ID:(22.857) 2012-12-19 18:54:47.897
*** CLIENT ID:() 2012-12-19 18:54:47.897
*** SERVICE NAME:(orcl2) 2012-12-19 18:54:47.897
*** MODULE NAME:(SQL*Plus) 2012-12-19 18:54:47.897
*** ACTION NAME:() 2012-12-19 18:54:47.897
 
=====================
PARSING IN CURSOR #18446741324893029224 len=29 dep=0 uid=90 oct=3 lid=90 tim=350411877872 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324893029224:c=0,e=153,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350411877865

*** 2012-12-19 18:54:48.677
FETCH #18446741324893029224:c=770000,e=779184,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350412657541
STAT #18446741324893029224 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=779212 us)'
STAT #18446741324893029224 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3321542 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324893029224:c=0,e=9,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350412658295
CLOSE #18446741324893029224:c=0,e=16,dep=0,type=1,tim=350412662994
=====================
PARSING IN CURSOR #18446741324893027936 len=29 dep=0 uid=90 oct=3 lid=90 tim=350412663177 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324893027936:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350412663176
FETCH #18446741324893027936:c=650000,e=653522,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350413316762
STAT #18446741324893027936 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=653516 us)'
STAT #18446741324893027936 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3134875 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324893027936:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350413331874
CLOSE #18446741324893027936:c=0,e=13,dep=0,type=1,tim=350413340977

*** 2012-12-19 18:54:49.625
=====================
PARSING IN CURSOR #18446741324892119664 len=29 dep=0 uid=90 oct=3 lid=90 tim=350413605759 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324892119664:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350413605757
FETCH #18446741324892119664:c=570000,e=571203,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350414177056
STAT #18446741324892119664 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=0 us)'
STAT #18446741324892119664 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=0 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324892119664:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350414177543
CLOSE #18446741324892119664:c=0,e=16,dep=0,type=1,tim=350414186644
=====================
PARSING IN CURSOR #18446741324891353688 len=29 dep=0 uid=90 oct=3 lid=90 tim=350414245939 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324891353688:c=0,e=34,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350414245938

*** 2012-12-19 18:54:50.895
FETCH #18446741324891353688:c=640000,e=629522,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350414875553
STAT #18446741324891353688 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=629517 us)'
STAT #18446741324891353688 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3147466 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324891353688:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350414876094
CLOSE #18446741324891353688:c=0,e=17,dep=0,type=1,tim=350414884347
=====================
PARSING IN CURSOR #18446741324891997256 len=29 dep=0 uid=90 oct=3 lid=90 tim=350414928981 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324891997256:c=0,e=42,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350414928979

*** 2012-12-19 18:54:51.977
FETCH #18446741324891997256:c=1020000,e=1029148,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350415958270
STAT #18446741324891997256 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=1029145 us)'
STAT #18446741324891997256 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3250734 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324891997256:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350415958755
CLOSE #18446741324891997256:c=0,e=14,dep=0,type=1,tim=350415969382
=====================
PARSING IN CURSOR #18446741324892390104 len=29 dep=0 uid=90 oct=3 lid=90 tim=350416009696 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324892390104:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350416009694

*** 2012-12-19 18:54:53.345
FETCH #18446741324892390104:c=1320000,e=1316178,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350417325978
STAT #18446741324892390104 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=1316173 us)'
STAT #18446741324892390104 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3098247 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324892390104:c=0,e=3,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350417326501
CLOSE #18446741324892390104:c=0,e=20,dep=0,type=1,tim=350417334986

*** 2012-12-19 18:54:53.397
=====================
PARSING IN CURSOR #18446741324883682088 len=29 dep=0 uid=90 oct=3 lid=90 tim=350417378253 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324883682088:c=0,e=37,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350417378251

*** 2012-12-19 18:54:55.421
FETCH #18446741324883682088:c=2020000,e=2023183,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350419401555
STAT #18446741324883682088 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=2023179 us)'
STAT #18446741324883682088 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3123804 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324883682088:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350419402080
CLOSE #18446741324883682088:c=0,e=19,dep=0,type=1,tim=350419410419
=====================
PARSING IN CURSOR #18446741324892138824 len=29 dep=0 uid=90 oct=3 lid=90 tim=350419454301 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324892138824:c=0,e=43,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350419454299

*** 2012-12-19 18:54:58.885
FETCH #18446741324892138824:c=3410000,e=3411092,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350422865518
STAT #18446741324892138824 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=3411088 us)'
STAT #18446741324892138824 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3141762 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324892138824:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350422866041
CLOSE #18446741324892138824:c=0,e=19,dep=0,type=1,tim=350422874570
=====================
PARSING IN CURSOR #18446741324883614008 len=29 dep=0 uid=90 oct=3 lid=90 tim=350422907253 hv=3667306631 ad='c0448b28' sqlid='5ymq7bbd9da47'
select count(pad)
  from test
END OF STMT
EXEC #18446741324883614008:c=0,e=37,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,plh=1950795681,tim=350422907251

*** 2012-12-19 18:55:05.214
FETCH #18446741324883614008:c=6280000,e=6287230,p=0,cr=17874,cu=0,mis=0,r=1,dep=0,og=1,plh=1950795681,tim=350429194813
STAT #18446741324883614008 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17874 pr=0 pw=0 time=6287226 us)'
STAT #18446741324883614008 id=2 cnt=10000000 pid=1 pos=1 obj=77897 op='TABLE ACCESS FULL TEST (cr=17874 pr=0 pw=0 time=3356391 us cost=5022 size=20000000 card=10000000)'
FETCH #18446741324883614008:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,plh=1950795681,tim=350429195336
CLOSE #18446741324883614008:c=0,e=14,dep=0,type=1,tim=350429198427