/u01/oracle/product/10.2.0/db/admin/orcl/udump/orcl_ora_2069.trc Oracle Database 10g Enterprise Edition Release 10.2.0.5.0 - 64bit Production With the Partitioning, Data Mining and Real Application Testing options ORACLE_HOME = /u01/oracle/product/10.2.0/db System name: SunOS Node name: sol10 Release: 5.10 Version: Generic_141445-09 Machine: i86pc Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Unix process pid: 2069, image: oracle@sol10 *** ACTION NAME:() 2012-12-19 16:11:34.538 *** MODULE NAME:(SQL*Plus) 2012-12-19 16:11:34.538 *** SERVICE NAME:(orcl) 2012-12-19 16:11:34.538 *** SESSION ID:(141.85) 2012-12-19 16:11:34.538 ===================== PARSING IN CURSOR #1 len=32 dep=0 uid=62 oct=42 lid=62 tim=16546629745 hv=0 ad='fdb4fc08' alter session set sql_trace=true END OF STMT EXEC #1:c=0,e=60,p=0,cr=0,cu=0,mis=1,r=0,dep=0,og=1,tim=16546629740 ===================== PARSING IN CURSOR #2 len=29 dep=0 uid=62 oct=3 lid=62 tim=16546632108 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #2:c=0,e=62,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16546632104 EXEC #2:c=0,e=56,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16546632229 FETCH #2:c=770000,e=746929,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16547379206 FETCH #2:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16547379657 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=746948 us)' STAT #2 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=77 us)' ===================== PARSING IN CURSOR #1 len=29 dep=0 uid=62 oct=3 lid=62 tim=16547383505 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #1:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16547383503 EXEC #1:c=0,e=34,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16547383568 FETCH #1:c=660000,e=642780,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16548026377 FETCH #1:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16548026834 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=642778 us)' STAT #1 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=45 us)' ===================== PARSING IN CURSOR #2 len=75 dep=0 uid=62 oct=3 lid=62 tim=16548030219 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #2:c=0,e=259,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548030217 EXEC #2:c=0,e=85,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548030337 ===================== PARSING IN CURSOR #1 len=158 dep=1 uid=62 oct=3 lid=62 tim=16548031021 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #1:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548031020 EXEC #1:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548031067 FETCH #1:c=0,e=138,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16548031258 FETCH #1:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548031310 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=136 us)' STAT #1 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=152 us)' STAT #1 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=5 us)' ===================== PARSING IN CURSOR #1 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16548031607 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #1:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548031605 EXEC #1:c=0,e=71,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548031771 ===================== PARSING IN CURSOR #3 len=110 dep=1 uid=62 oct=3 lid=62 tim=16548031932 hv=4293044569 ad='b8cc41e8' SELECT PLAN_TABLE_OUTPUT FROM TABLE(CAST(DBMS_XPLAN.PREPARE_RECORDS(:B1 , :B2 ) AS SYS.DBMS_XPLAN_TYPE_TABLE)) END OF STMT PARSE #3:c=0,e=114,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548031930 EXEC #3:c=0,e=44,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548032007 FETCH #1:c=0,e=398,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548032623 FETCH #1:c=0,e=66,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548032761 ===================== PARSING IN CURSOR #4 len=448 dep=2 uid=62 oct=3 lid=62 tim=16548033152 hv=3191283681 ad='b8cb7010' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ extractvalue(xmlval, '/*/info[@type = "sql_profile"]'), extractvalue(xmlval, '/*/info[@type = "outline"]'), extractvalue(xmlval, '/*/info[@type = "dynamic_sampling"]'), extractvalue(xmlval, '/*/info[@type = "row_shipping"]'), extractvalue(xmlval, '/*/info[@type = "index_size"]'), extractvalue(xmlval,'/*/info[@type = "plan_hash"]') from (select xmltype(:v_other_xml) xmlval from dual) END OF STMT PARSE #4:c=0,e=138,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16548033150 EXEC #4:c=0,e=350,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16548033555 ===================== PARSING IN CURSOR #6 len=47 dep=3 uid=0 oct=3 lid=0 tim=16548037203 hv=1023521005 ad='bfa87640' select metadata from kopm$ where name='DB_FDO' END OF STMT PARSE #6:c=0,e=51,p=0,cr=0,cu=0,mis=0,r=0,dep=3,og=4,tim=16548037201 EXEC #6:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=0,dep=3,og=4,tim=16548037335 FETCH #6:c=0,e=23,p=0,cr=2,cu=0,mis=0,r=1,dep=3,og=4,tim=16548037375 STAT #6 id=1 cnt=1 pid=0 pos=1 obj=364 op='TABLE ACCESS BY INDEX ROWID KOPM$ (cr=2 pr=0 pw=0 time=24 us)' STAT #6 id=2 cnt=1 pid=1 pos=1 obj=365 op='INDEX UNIQUE SCAN I_KOPM1 (cr=1 pr=0 pw=0 time=8 us)' FETCH #4:c=20000,e=15896,p=0,cr=8,cu=0,mis=0,r=1,dep=2,og=1,tim=16548049474 FETCH #1:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548049767 FETCH #1:c=0,e=58,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16548049869 FETCH #3:c=20000,e=18223,p=0,cr=8,cu=0,mis=0,r=9,dep=1,og=1,tim=16548050244 ===================== PARSING IN CURSOR #6 len=179 dep=1 uid=62 oct=3 lid=62 tim=16548050416 hv=3761447641 ad='b8cb4378' select /* EXEC_FROM_DBMS_XPLAN */ rtrim(sql_text) from v$sql where SQL_ID=:v_sql_id and child_number=:v_child_number END OF STMT PARSE #6:c=0,e=68,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548050414 EXEC #6:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548050524 FETCH #6:c=0,e=127,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16548050665 FETCH #2:c=20000,e=20361,p=0,cr=8,cu=0,mis=0,r=1,dep=0,og=1,tim=16548050732 STAT #1 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=409 us)' STAT #1 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=345 us)' FETCH #2:c=0,e=119,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16548051227 STAT #2 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=8 pr=0 pw=0 time=20366 us)' ===================== PARSING IN CURSOR #1 len=52 dep=0 uid=62 oct=42 lid=62 tim=16548058397 hv=119769663 ad='0' alter session set "_rowsource_statistics_sampfreq"=0 END OF STMT PARSE #1:c=0,e=21,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16548058395 EXEC #1:c=0,e=46,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16548058471 ===================== PARSING IN CURSOR #2 len=29 dep=0 uid=62 oct=3 lid=62 tim=16548064655 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #2:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548064650 EXEC #2:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548064778 FETCH #2:c=590000,e=577023,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16548641835 FETCH #2:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16548642304 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=0 us)' STAT #2 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=0 us)' ===================== PARSING IN CURSOR #1 len=75 dep=0 uid=62 oct=3 lid=62 tim=16548649997 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #1:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548649995 EXEC #1:c=0,e=30,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548650055 ===================== PARSING IN CURSOR #2 len=158 dep=1 uid=62 oct=3 lid=62 tim=16548650348 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #2:c=0,e=14,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548650345 EXEC #2:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548650413 FETCH #2:c=0,e=148,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16548650590 FETCH #2:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548650646 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=0 us)' STAT #2 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=0 us)' STAT #2 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=0 us)' ===================== PARSING IN CURSOR #2 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16548650768 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #2:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548650767 EXEC #2:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548650887 EXEC #3:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548650926 FETCH #2:c=0,e=172,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548651228 FETCH #2:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548651323 EXEC #4:c=0,e=33,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16548651420 FETCH #4:c=10000,e=3456,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548654888 FETCH #2:c=0,e=58,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16548655134 FETCH #2:c=0,e=41,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16548655220 FETCH #3:c=10000,e=4621,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16548655557 EXEC #6:c=0,e=24,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16548655676 FETCH #6:c=0,e=61,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16548655750 FETCH #1:c=10000,e=5738,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16548655822 STAT #2 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=0 us)' STAT #2 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=0 us)' FETCH #1:c=0,e=200,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16548656551 STAT #1 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=0 pr=0 pw=0 time=0 us)' ===================== PARSING IN CURSOR #2 len=54 dep=0 uid=62 oct=42 lid=62 tim=16548676385 hv=2352624425 ad='0' alter session set "_rowsource_statistics_sampfreq"=128 END OF STMT PARSE #2:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16548676383 EXEC #2:c=0,e=14,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16548676427 ===================== PARSING IN CURSOR #1 len=29 dep=0 uid=62 oct=3 lid=62 tim=16548683494 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #1:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548683489 EXEC #1:c=0,e=35,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16548683579 FETCH #1:c=660000,e=651696,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16549335305 FETCH #1:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16549335756 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=651693 us)' STAT #1 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=55 us)' ===================== PARSING IN CURSOR #2 len=75 dep=0 uid=62 oct=3 lid=62 tim=16549343787 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #2:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16549343786 EXEC #2:c=0,e=31,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16549343846 ===================== PARSING IN CURSOR #1 len=158 dep=1 uid=62 oct=3 lid=62 tim=16549344103 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #1:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549344100 EXEC #1:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549344152 FETCH #1:c=0,e=130,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16549344312 FETCH #1:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549344751 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=127 us)' STAT #1 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=150 us)' STAT #1 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=3 us)' ===================== PARSING IN CURSOR #1 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16549344942 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #1:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549344941 EXEC #1:c=0,e=28,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549345218 EXEC #3:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549345288 FETCH #1:c=0,e=164,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16549345607 FETCH #1:c=10000,e=44,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16549345702 EXEC #4:c=0,e=51,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16549345816 FETCH #4:c=0,e=2578,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16549348412 FETCH #1:c=0,e=41,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16549348683 FETCH #1:c=0,e=44,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16549348777 FETCH #3:c=10000,e=3739,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16549349037 EXEC #6:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16549349126 FETCH #6:c=0,e=56,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16549349194 FETCH #2:c=10000,e=5376,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16549349253 STAT #1 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=156 us)' STAT #1 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=134 us)' FETCH #2:c=0,e=171,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16549349837 STAT #2 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=0 pr=0 pw=0 time=5369 us)' ===================== PARSING IN CURSOR #1 len=53 dep=0 uid=62 oct=42 lid=62 tim=16549368765 hv=3674036439 ad='0' alter session set "_rowsource_statistics_sampfreq"=16 END OF STMT PARSE #1:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16549368763 EXEC #1:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16549368827 ===================== PARSING IN CURSOR #2 len=29 dep=0 uid=62 oct=3 lid=62 tim=16549380162 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #2:c=0,e=23,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16549380156 EXEC #2:c=0,e=38,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16549380256 FETCH #2:c=1130000,e=1101292,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16550481580 FETCH #2:c=0,e=2,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16550482006 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=1101289 us)' STAT #2 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=47 us)' ===================== PARSING IN CURSOR #1 len=75 dep=0 uid=62 oct=3 lid=62 tim=16550490695 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #1:c=0,e=24,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16550490692 EXEC #1:c=0,e=54,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16550490844 ===================== PARSING IN CURSOR #2 len=158 dep=1 uid=62 oct=3 lid=62 tim=16550491072 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #2:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550491070 EXEC #2:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550491116 FETCH #2:c=0,e=133,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16550491275 FETCH #2:c=0,e=28,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550491326 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=132 us)' STAT #2 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=148 us)' STAT #2 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=3 us)' ===================== PARSING IN CURSOR #2 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16550491451 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #2:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550491449 EXEC #2:c=0,e=25,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550491866 EXEC #3:c=0,e=23,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550491915 FETCH #2:c=0,e=164,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16550492182 FETCH #2:c=10000,e=47,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16550492280 EXEC #4:c=0,e=34,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16550492389 FETCH #4:c=0,e=3069,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16550495471 FETCH #2:c=0,e=77,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16550495743 FETCH #2:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16550495852 FETCH #3:c=10000,e=4215,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16550496140 EXEC #6:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16550496237 FETCH #6:c=0,e=58,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16550496307 FETCH #1:c=10000,e=7610,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16550498491 STAT #2 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=152 us)' STAT #2 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=130 us)' FETCH #1:c=0,e=142,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16550499061 STAT #1 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=0 pr=0 pw=0 time=7603 us)' ===================== PARSING IN CURSOR #2 len=52 dep=0 uid=62 oct=42 lid=62 tim=16550523442 hv=1901644751 ad='0' alter session set "_rowsource_statistics_sampfreq"=8 END OF STMT PARSE #2:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16550523440 EXEC #2:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16550523486 ===================== PARSING IN CURSOR #1 len=29 dep=0 uid=62 oct=3 lid=62 tim=16550534259 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #1:c=0,e=25,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16550534253 EXEC #1:c=0,e=38,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16550534409 FETCH #1:c=1680000,e=1646018,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16552180461 FETCH #1:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16552180942 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=1646018 us)' STAT #1 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=50 us)' ===================== PARSING IN CURSOR #2 len=75 dep=0 uid=62 oct=3 lid=62 tim=16552189542 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #2:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16552189539 EXEC #2:c=0,e=33,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16552189606 ===================== PARSING IN CURSOR #1 len=158 dep=1 uid=62 oct=3 lid=62 tim=16552189836 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #1:c=0,e=11,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552189834 EXEC #1:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552189895 FETCH #1:c=0,e=124,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16552190046 FETCH #1:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552190094 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=123 us)' STAT #1 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=137 us)' STAT #1 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=4 us)' ===================== PARSING IN CURSOR #1 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16552190203 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #1:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552190202 EXEC #1:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552190315 EXEC #3:c=0,e=49,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552190384 FETCH #1:c=0,e=151,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16552190634 FETCH #1:c=0,e=51,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16552190730 EXEC #4:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16552190847 FETCH #4:c=0,e=2737,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16552193599 FETCH #1:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16552193833 FETCH #1:c=0,e=38,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16552193914 FETCH #3:c=0,e=3760,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16552194161 EXEC #6:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16552194265 FETCH #6:c=0,e=57,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16552194335 FETCH #2:c=0,e=4752,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16552194393 STAT #1 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=143 us)' STAT #1 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=123 us)' FETCH #2:c=0,e=114,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16552194881 STAT #2 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=0 pr=0 pw=0 time=4757 us)' ===================== PARSING IN CURSOR #1 len=52 dep=0 uid=62 oct=42 lid=62 tim=16552226857 hv=2011143002 ad='0' alter session set "_rowsource_statistics_sampfreq"=4 END OF STMT PARSE #1:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16552226855 EXEC #1:c=0,e=14,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16552226902 ===================== PARSING IN CURSOR #2 len=29 dep=0 uid=62 oct=3 lid=62 tim=16552240010 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #2:c=0,e=21,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16552240006 EXEC #2:c=0,e=35,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16552240094 FETCH #2:c=2500000,e=2442010,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16554682134 FETCH #2:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16554682592 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=2442009 us)' STAT #2 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=54 us)' ===================== PARSING IN CURSOR #1 len=75 dep=0 uid=62 oct=3 lid=62 tim=16554697120 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #1:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16554697118 EXEC #1:c=0,e=28,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16554697201 ===================== PARSING IN CURSOR #2 len=158 dep=1 uid=62 oct=3 lid=62 tim=16554697411 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #2:c=0,e=10,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554697409 EXEC #2:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554697453 FETCH #2:c=0,e=127,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16554697604 FETCH #2:c=0,e=25,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554697651 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=124 us)' STAT #2 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=139 us)' STAT #2 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=3 us)' ===================== PARSING IN CURSOR #2 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16554697782 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #2:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554697781 EXEC #2:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554697884 EXEC #3:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554697923 FETCH #2:c=0,e=243,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16554698253 FETCH #2:c=0,e=45,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16554698351 EXEC #4:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16554698450 FETCH #4:c=0,e=2503,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16554700965 FETCH #2:c=0,e=38,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16554701236 FETCH #2:c=0,e=41,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16554701320 FETCH #3:c=0,e=3648,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16554701580 EXEC #6:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16554701663 FETCH #6:c=0,e=52,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16554701726 FETCH #1:c=0,e=4550,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16554701780 STAT #2 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=235 us)' STAT #2 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=214 us)' FETCH #1:c=0,e=132,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16554702852 STAT #1 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=0 pr=0 pw=0 time=4555 us)' ===================== PARSING IN CURSOR #2 len=52 dep=0 uid=62 oct=42 lid=62 tim=16554732622 hv=2092216607 ad='0' alter session set "_rowsource_statistics_sampfreq"=2 END OF STMT PARSE #2:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16554732620 EXEC #2:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16554732665 ===================== PARSING IN CURSOR #1 len=29 dep=0 uid=62 oct=3 lid=62 tim=16554745234 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #1:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16554745229 EXEC #1:c=0,e=32,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16554745326 *** 2012-12-19 16:11:47.292 FETCH #1:c=4440000,e=4339831,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16559085187 FETCH #1:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16559085708 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=4339828 us)' STAT #1 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=54 us)' ===================== PARSING IN CURSOR #2 len=75 dep=0 uid=62 oct=3 lid=62 tim=16559094556 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #2:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16559094554 EXEC #2:c=0,e=73,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16559094660 ===================== PARSING IN CURSOR #1 len=158 dep=1 uid=62 oct=3 lid=62 tim=16559094932 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #1:c=0,e=11,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559094930 EXEC #1:c=0,e=17,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559094989 FETCH #1:c=0,e=131,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16559095218 FETCH #1:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559095279 STAT #1 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=128 us)' STAT #1 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=142 us)' STAT #1 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=4 us)' ===================== PARSING IN CURSOR #1 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16559095519 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #1:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559095517 EXEC #1:c=0,e=26,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559095708 EXEC #3:c=0,e=22,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559095752 FETCH #1:c=0,e=297,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16559096150 FETCH #1:c=0,e=53,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16559096264 EXEC #4:c=0,e=71,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16559096408 FETCH #4:c=0,e=2878,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16559099302 FETCH #1:c=0,e=46,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16559099532 FETCH #1:c=0,e=38,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16559099611 FETCH #3:c=0,e=4102,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16559099864 EXEC #6:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16559099997 FETCH #6:c=0,e=66,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16559100075 FETCH #2:c=0,e=5432,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16559100132 STAT #1 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=337 us)' STAT #1 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=269 us)' FETCH #2:c=0,e=159,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16559100816 STAT #2 id=1 cnt=14 pid=0 pos=1 obj=0 op='COLLECTION ITERATOR PICKLER FETCH DISPLAY_CURSOR (cr=0 pr=0 pw=0 time=5422 us)' ===================== PARSING IN CURSOR #1 len=52 dep=0 uid=62 oct=42 lid=62 tim=16559115652 hv=4230847953 ad='0' alter session set "_rowsource_statistics_sampfreq"=1 END OF STMT PARSE #1:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16559115651 EXEC #1:c=0,e=13,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16559115692 ===================== PARSING IN CURSOR #2 len=29 dep=0 uid=62 oct=3 lid=62 tim=16559118940 hv=3667306631 ad='b8ccad60' select count(pad) from test END OF STMT PARSE #2:c=0,e=21,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16559118935 EXEC #2:c=0,e=46,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16559119039 FETCH #2:c=8180000,e=8004823,p=0,cr=17856,cu=0,mis=0,r=1,dep=0,og=1,tim=16567123900 FETCH #2:c=0,e=1,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=0,tim=16567124361 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='SORT AGGREGATE (cr=17856 pr=0 pw=0 time=8004828 us)' STAT #2 id=2 cnt=10000000 pid=1 pos=1 obj=58407 op='TABLE ACCESS FULL TEST (cr=17856 pr=0 pw=0 time=54 us)' ===================== PARSING IN CURSOR #1 len=75 dep=0 uid=62 oct=3 lid=62 tim=16567127918 hv=3722109354 ad='b8cca7b0' select * from table(dbms_xplan.display_cursor(null, null, 'allstats last')) END OF STMT PARSE #1:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16567127915 EXEC #1:c=0,e=36,p=0,cr=0,cu=0,mis=0,r=0,dep=0,og=1,tim=16567127986 ===================== PARSING IN CURSOR #2 len=158 dep=1 uid=62 oct=3 lid=62 tim=16567128229 hv=1197953942 ad='b8cc3960' select /* EXEC_FROM_DBMS_XPLAN */ 1, prev_sql_id, prev_child_number from v$session where sid=userenv('sid') and username is not null and prev_hash_value <> 0 END OF STMT PARSE #2:c=0,e=12,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567128227 EXEC #2:c=0,e=16,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567128271 FETCH #2:c=0,e=121,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16567128441 FETCH #2:c=0,e=69,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567128532 STAT #2 id=1 cnt=1 pid=0 pos=1 obj=0 op='NESTED LOOPS (cr=0 pr=0 pw=0 time=188 us)' STAT #2 id=2 cnt=1 pid=1 pos=1 obj=0 op='FIXED TABLE FULL X$KSUSE (cr=0 pr=0 pw=0 time=175 us)' STAT #2 id=3 cnt=1 pid=1 pos=2 obj=0 op='FIXED TABLE FIXED INDEX X$KSLED (ind:2) (cr=0 pr=0 pw=0 time=5 us)' ===================== PARSING IN CURSOR #2 len=2135 dep=1 uid=62 oct=3 lid=62 tim=16567128647 hv=769128084 ad='b8cad230' SELECT /*+ opt_param('parallel_execution_enabled', 'false') */ /* EXEC_FROM_DBMS_XPLAN */ id, position, depth , operation, options, object_name, cardinality, bytes, temp_space, cost, io_cost, cpu_cost, time, partition_start, partition_stop, object_node, other_tag, distribution, null, access_predicates, filter_predicates, null, null, null, other_xml, starts, outrows, crgets, cugets, reads, writes, etime, mem_opt, mem_one, last_mem_used, last_mem_usage, opt_cnt, one_cnt, multi_cnt, max_tmp, last_tmp from (select /*+ no_merge */ id, depth, position, operation, options, cost, time, cardinality, bytes, object_node, object_name, other_tag, partition_start, partition_stop, distribution, temp_space, io_cost, cpu_cost, filter_predicates, access_predicates, other, projection, qblock_name, object_alias, other_xml, last_starts starts, last_output_rows outrows, last_cr_buffer_gets crgets, last_cu_buffer_gets cugets, last_disk_reads reads, last_disk_writes writes, last_elapsed_time etime, estimated_optimal_size mem_opt, estimated_onepass_size mem_one, last_memory_used last_mem_used, last_execution last_mem_usage, optimal_executions opt_cnt, onepass_executions one_cnt, multipasses_executions multi_cnt, max_tempseg_size max_tmp, last_tempseg_size last_tmp from V$SQL_PLAN_STATISTICS_ALL vp where SQL_ID = '5ymq7bbd9da47' and child_number=1) END OF STMT PARSE #2:c=0,e=15,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567128646 EXEC #2:c=0,e=20,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567128773 EXEC #3:c=0,e=19,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567128811 FETCH #2:c=0,e=155,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16567129105 FETCH #2:c=0,e=43,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16567129194 EXEC #4:c=0,e=30,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16567129281 FETCH #4:c=0,e=2532,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16567131830 FETCH #2:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=1,dep=2,og=1,tim=16567132058 FETCH #2:c=0,e=48,p=0,cr=0,cu=0,mis=0,r=0,dep=2,og=1,tim=16567132149 FETCH #3:c=0,e=3609,p=0,cr=0,cu=0,mis=0,r=9,dep=1,og=1,tim=16567132429 EXEC #6:c=0,e=18,p=0,cr=0,cu=0,mis=0,r=0,dep=1,og=1,tim=16567132513 FETCH #6:c=0,e=53,p=0,cr=0,cu=0,mis=0,r=1,dep=1,og=1,tim=16567132576 FETCH #1:c=0,e=4595,p=0,cr=0,cu=0,mis=0,r=1,dep=0,og=1,tim=16567132630 STAT #2 id=1 cnt=3 pid=0 pos=1 obj=0 op='VIEW (cr=0 pr=0 pw=0 time=256 us)' STAT #2 id=2 cnt=3 pid=1 pos=1 obj=0 op='FIXED TABLE FIXED INDEX X$QESRSTATALL (ind:4) (cr=0 pr=0 pw=0 time=200 us)' FETCH #1:c=10000,e=146,p=0,cr=0,cu=0,mis=0,r=13,dep=0,og=1,tim=16567133198