Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915220005\orcl_p019_3324_bucket.trc Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options Windows NT Version V6.2 CPU : 32 - type 8664, 16 Physical Cores Process Affinity : 0x0x0000000000000000 Memory (Avail/Total): Ph:34716M/63366M, Ph+PgF:39306M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 42 Windows thread id: 3324, image: ORACLE.EXE (P019) *** 2025-09-15 22:00:05.286 *** 2025-09-15 22:00:05.286 Process diagnostic dump for ORACLE.EXE (P019), OS id=3324, pid: 42, proc_ser: 1, sid: ------------------------------------------------------------------------------- Skipping session state dump because target process has no session. ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-15 22:00:05.286 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 42 (osid: 3324, P019) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-03-23 11:26:20.582490 :800006A9:db_trace:kst.c@673:kstipg(): [10280:42:0] kst: process state object created on 03-23 11:26:20.577 2025-03-23 11:26:20.582491 :800006AA:db_trace:kst.c@679:kstipg(): [10280:42:0] kst: process info: ospid=3324 pso_num=42 pso_serial#=1 2025-03-23 11:26:20.582561 :800006AB:db_trace:kso.c@4093:ksonfy(): [10420:42:0] kso: new process: pid=3324 osp_idx=40 osp_ver=0 osp_pg=0 (spawn #41) 2025-03-23 11:26:20.582561 :800006AC:db_trace:kso.c@4099:ksonfy(): [10420:42:0] kso: new process: first_req_pid=3724 spawner_pid=3660 sga_crt_ts=x8A8A 2025-03-23 11:26:20.582561 :800006AD:db_trace:kso.c@4103:ksonfy(): [10420:42:0] kso: new process: pso_index=42 reservation=1 time=40609 2025-03-23 11:26:20.582561 :800006AE:db_trace:kso.c@4109:ksonfy(): [10420:42:0] kso: spawn diagnostics: queue time=0 secs 2025-03-23 11:26:20.582562 :800006AF:db_trace:kso.c@4111:ksonfy(): [10420:42:0] kso: spawn diagnostics: fork time=0 secs 2025-03-23 11:26:20.582562 :800006B0:db_trace:kso.c@4113:ksonfy(): [10420:42:0] kso: spawn diagnostics: exec time=0 secs 2025-03-23 11:26:20.582562 :800006B1:db_trace:kso.c@4115:ksonfy(): [10420:42:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-03-23 11:26:20.582562 :800006B2:db_trace:kso.c@4117:ksonfy(): [10420:42:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-03-23 11:26:20.582595 :800006B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:0] KSL POST SENT postee=22 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.611484 :80000834:db_trace:ksl2.c@12699:ksliwat(): [10005:42:0] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.613712 :80000880:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.622519 :800008BD:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.637006 :80000910:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.639089 :80000952:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.639095 :8000095C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=10 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.643525 :80000A11:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=10 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.665865 :80000A95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.736180 :800021C7:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.740355 :800021F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.740568 :80002339:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.740568*:80002355:CACHE_RCV:kcrp.c@1354:kcrpclaim(): kcrpclaim: Entering kcrpclaim()2025-03-23 11:26:20.740568*:80002357:CACHE_RCV:kcrp.c@1374:kcrpclaim(): kcrpclaim: claim dba 0x4007d9 (1,2009) at version 0x0000.0d1dfbfb.0x01 2025-03-23 11:26:20.744821*:8000240E:CACHE_RCV:kcrp.c@1466:kcrpclaim(): kcrpclaim: Claim issued for dba 0x4007d9 (AFN 1, BNO 2009)2025-03-23 11:26:20.744821*:8000240F:CACHE_RCV:kcrp.c@1354:kcrpclaim(): kcrpclaim: Entering kcrpclaim()2025-03-23 11:26:20.744821*:80002410:CACHE_RCV:kcrp.c@1374:kcrpclaim(): kcrpclaim: claim dba 0x4007e9 (1,2025) at version 0x0000.0d1dfbfb.0x02 2025-03-23 11:26:20.744821*:80002411:CACHE_RCV:kcrp.c@1466:kcrpclaim(): kcrpclaim: Claim issued for dba 0x4007e9 (AFN 1, BNO 2025)2025-03-23 11:26:20.747487 :80002412:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.833168 :80002467:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.839924 :80002770:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.839924*:800027E4:CACHE_RCV:kcrp.c@1741:kcrpap(): kcrpap: CV was either applied to the buffer or ignored (look for traces from kcbtema for ignore cases)2025-03-23 11:26:20.839924*:80002814:CACHE_RCV:kcrp.c@1741:kcrpap(): kcrpap: CV was either applied to the buffer or ignored (look for traces from kcbtema for ignore cases)2025-03-23 11:26:20.839924*:80002817:CACHE_RCV:kcb.c@23450:kcbtema(): kcbtema: the last CV (DBA 0x 4007d9, AFN 1, BNO 2009)2025-03-23 11:26:20.839924*:80002818:CACHE_RCV:kcb.c@23464:kcbtema(): kcbtema: buffer is dirty, moving to req queue2025-03-23 11:26:20.839924*:80002819:CACHE_RCV:kcrp.c@1741:kcrpap(): kcrpap: CV was either applied to the buffer or ignored (look for traces from kcbtema for ignore cases)2025-03-23 11:26:20.854196*:80002885:CACHE_RCV:kcrp.c@1741:kcrpap(): kcrpap: CV was either applied to the buffer or ignored (look for traces from kcbtema for ignore cases)2025-03-23 11:26:20.854196*:8000288E:CACHE_RCV:kcb.c@23450:kcbtema(): kcbtema: the last CV (DBA 0x 4007e9, AFN 1, BNO 2025)2025-03-23 11:26:20.854196*:8000288F:CACHE_RCV:kcb.c@23464:kcbtema(): kcbtema: buffer is dirty, moving to req queue2025-03-23 11:26:20.854196*:80002890:CACHE_RCV:kcrp.c@1741:kcrpap(): kcrpap: CV was either applied to the buffer or ignored (look for traces from kcbtema for ignore cases)2025-03-23 11:26:20.863420 :80002ACB:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.863923 :80002BF2:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=15 loc='ksl2.h LINE:2165 ID:kslpsr' id1=87 id2=0 name=EV type=0 fac#=0 facpost=1 2025-03-23 11:26:20.863925 :80002BFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=12 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.863940 :80002C25:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=15 loc='kcbk.h LINE:2507 ID:kcbkcpa' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.863944 :80002C2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.863946 :80002C35:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=15 loc='ksl2.h LINE:2165 ID:kslpsr' id1=87 id2=0 name=EV type=0 fac#=0 facpost=1 2025-03-23 11:26:20.873014 :80002C7F:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.875836 :80002CA6:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-03-23 11:26:20.881841 :80002CEF:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=28 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.882212 :80002CF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=23 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.882276 :80002CF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.887840 :80002D55:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='ksu.h LINE:12733 ID:ksusig' error and flags=2147494035 id2=136 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.890110 :80002DA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4295 ID:kxfpqr: QC' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.890111 :80002DA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.890151 :80002DC2:db_trace:ksl2.c@12699:ksliwat(): [10005:42:82] KSL POST RCVD poster=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.890162 :80002DCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:42:82] KSL POST SENT postee=22 loc='kxfp.h LINE:4291 ID:kxfprienq: slave' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.890424 :80002DF4:db_trace:ksl2.c@2324:kslwtbctx(): [10005:42:0] KSL WAIT BEG [latch free] 136431760/0x821c890 70/0x46 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.890430 :80002E01:db_trace:ksl2.c@2611:kslwtectx(): [10005:42:0] KSL WAIT END [latch free] 136431760/0x821c890 70/0x46 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.890430 :80002E02:db_trace:ksl2.c@2619:kslwtectx(): [10005:42:0] KSL WAIT END wait times (usecs) - snap=5, exc=5, tot=5 2025-03-23 11:26:20.890458 :80002E16:db_trace:ksl2.c@2324:kslwtbctx(): [10005:42:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.890475 :80002E21:db_trace:ksl2.c@2611:kslwtectx(): [10005:42:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.890476 :80002E23:db_trace:ksl2.c@2619:kslwtectx(): [10005:42:0] KSL WAIT END wait times (usecs) - snap=15, exc=15, tot=15 2025-03-23 11:26:20.890482 :80002E28:db_trace:ksl2.c@2324:kslwtbctx(): [10005:42:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.890795 :80002E6F:db_trace:ksl2.c@2611:kslwtectx(): [10005:42:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.890795 :80002E70:db_trace:ksl2.c@2619:kslwtectx(): [10005:42:0] KSL WAIT END wait times (usecs) - snap=312, exc=312, tot=312 2025-03-23 11:26:21.764424 :800030B3:db_trace:ksl2.c@12699:ksliwat(): [10005:42:0] KSL POST RCVD poster=22 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:31:22.395830 :80009AC5:db_trace:ksl2.c@2324:kslwtbctx(): [10005:42:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:31:22.396149 :80009ADC:db_trace:ksl2.c@2611:kslwtectx(): [10005:42:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:31:22.396149 :80009ADD:db_trace:ksl2.c@2619:kslwtectx(): [10005:42:0] KSL WAIT END wait times (usecs) - snap=323, exc=323, tot=323 2025-03-23 11:31:22.396214 :80009ADF:db_trace:ksl2.c@2324:kslwtbctx(): [10005:42:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:31:22.396612 :80009AFD:db_trace:ksl2.c@2611:kslwtectx(): [10005:42:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:31:22.396615 :80009AFE:db_trace:ksl2.c@2619:kslwtectx(): [10005:42:0] KSL WAIT END wait times (usecs) - snap=396, exc=396, tot=396 2025-03-23 11:31:22.398078 :80009B5C:db_trace:kst.c@698:kstpsodel(): [10280:42:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 42 (osid: 3324, P019)