Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250517100704\orcl_p023_3380_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:37356M/63366M, Ph+PgF:44567M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 46 Windows thread id: 3380, image: ORACLE.EXE (P023) *** 2025-05-17 10:07:04.238 *** 2025-05-17 10:07:04.238 Process diagnostic dump for ORACLE.EXE (P023), OS id=3380, pid: 46, 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-05-17 10:07:04.238 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 46 (osid: 3380, P023) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-03-23 11:26:20.592799 :80000725:db_trace:kst.c@673:kstipg(): [10280:46:0] kst: process state object created on 03-23 11:26:20.592 2025-03-23 11:26:20.592799 :80000726:db_trace:kst.c@679:kstipg(): [10280:46:0] kst: process info: ospid=3380 pso_num=46 pso_serial#=1 2025-03-23 11:26:20.592867 :80000727:db_trace:kso.c@4093:ksonfy(): [10420:46:0] kso: new process: pid=3380 osp_idx=44 osp_ver=0 osp_pg=0 (spawn #45) 2025-03-23 11:26:20.592867 :80000728:db_trace:kso.c@4099:ksonfy(): [10420:46:0] kso: new process: first_req_pid=3724 spawner_pid=3660 sga_crt_ts=x8A8A 2025-03-23 11:26:20.592868 :80000729:db_trace:kso.c@4103:ksonfy(): [10420:46:0] kso: new process: pso_index=46 reservation=1 time=40625 2025-03-23 11:26:20.592868 :8000072A:db_trace:kso.c@4109:ksonfy(): [10420:46:0] kso: spawn diagnostics: queue time=0 secs 2025-03-23 11:26:20.592868 :8000072B:db_trace:kso.c@4111:ksonfy(): [10420:46:0] kso: spawn diagnostics: fork time=0 secs 2025-03-23 11:26:20.592868 :8000072C:db_trace:kso.c@4113:ksonfy(): [10420:46:0] kso: spawn diagnostics: exec time=0 secs 2025-03-23 11:26:20.592868 :8000072D:db_trace:kso.c@4115:ksonfy(): [10420:46:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-03-23 11:26:20.592869 :8000072E:db_trace:kso.c@4117:ksonfy(): [10420:46:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-03-23 11:26:20.592899 :8000072F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46: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.611504 :8000083C:db_trace:ksl2.c@12699:ksliwat(): [10005:46: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.611619 :80000855:db_trace:ksl2.c@2324:kslwtbctx(): [10005:46:0] KSL WAIT BEG [latch: shared pool] 322819464/0x133dd588 293/0x125 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.611697 :8000085D:db_trace:ksl2.c@2611:kslwtectx(): [10005:46:0] KSL WAIT END [latch: shared pool] 322819464/0x133dd588 293/0x125 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-03-23 11:26:20.611698 :8000085E:db_trace:ksl2.c@2619:kslwtectx(): [10005:46:0] KSL WAIT END wait times (usecs) - snap=77, exc=77, tot=77 2025-03-23 11:26:20.613434 :80000865:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.622530 :800008C8:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.636795 :80000903:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.639096 :8000095D:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.639102 :80000967:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.644423 :80000A31:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.665794 :80000A8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.736188 :800021CF:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.740254 :800021EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.740574 :80002343:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.740574*:8000235E:CACHE_RCV:kcrp.c@1354:kcrpclaim(): kcrpclaim: Entering kcrpclaim()2025-03-23 11:26:20.740574*:80002360:CACHE_RCV:kcrp.c@1374:kcrpclaim(): kcrpclaim: claim dba 0xc0160c (3,5644) at version 0x0000.0d1dfbf3.0x01 2025-03-23 11:26:20.744821*:800023B8:CACHE_RCV:kcrp.c@1466:kcrpclaim(): kcrpclaim: Claim issued for dba 0xc0160c (AFN 3, BNO 5644)2025-03-23 11:26:20.747235 :800023E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.835497 :80002471:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.839932 :80002775:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.839932*:80002780: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.839932*:80002782: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.839932*:80002783: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.839932*:80002784:CACHE_RCV:kcb.c@23450:kcbtema(): kcbtema: the last CV (DBA 0x c0160c, AFN 3, BNO 5644)2025-03-23 11:26:20.839932*:80002787:CACHE_RCV:kcb.c@23464:kcbtema(): kcbtema: buffer is dirty, moving to req queue2025-03-23 11:26:20.845831 :800027B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.845831*:800027B9: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.863429 :80002AD4:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.863436 :80002AE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.873024 :80002C87:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.875255 :80002C96:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-03-23 11:26:20.876561 :80002CB7:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] KSL POST RCVD poster=44 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.876912 :80002CBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] KSL POST SENT postee=33 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.876971 :80002CBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.887854 :80002D5D:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.890083 :80002D8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.890083 :80002D8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.890142 :80002DB6:db_trace:ksl2.c@12699:ksliwat(): [10005:46:114] 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.890152 :80002DC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:46:114] 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.890175 :80002DDA:db_trace:ksl2.c@2477:kslwtbctx(): [10005:46:114] KSL WAIT BEG [latch free] 136586856/0x8242668 375/0x177 0/0x0 wait_id=55 seq_num=56 snap_id=1 2025-03-23 11:26:20.890178 :80002DDE:db_trace:ksl2.c@2611:kslwtectx(): [10005:46:114] KSL WAIT END [latch free] 136586856/0x8242668 375/0x177 0/0x0 wait_id=55 seq_num=56 snap_id=1 2025-03-23 11:26:20.890179 :80002DDF:db_trace:ksl2.c@2619:kslwtectx(): [10005:46:114] KSL WAIT END wait times (usecs) - snap=4, exc=4, tot=4 2025-03-23 11:26:20.890453 :80002E13:db_trace:ksl2.c@2324:kslwtbctx(): [10005:46: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 :80002E20:db_trace:ksl2.c@2611:kslwtectx(): [10005:46: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 :80002E22:db_trace:ksl2.c@2619:kslwtectx(): [10005:46:0] KSL WAIT END wait times (usecs) - snap=20, exc=20, tot=20 2025-03-23 11:26:20.890478 :80002E25:db_trace:ksl2.c@2324:kslwtbctx(): [10005:46: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.890770 :80002E6D:db_trace:ksl2.c@2611:kslwtectx(): [10005:46: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.890771 :80002E6E:db_trace:ksl2.c@2619:kslwtectx(): [10005:46:0] KSL WAIT END wait times (usecs) - snap=292, exc=292, tot=292 2025-03-23 11:26:21.764417 :800030AC:db_trace:ksl2.c@12699:ksliwat(): [10005:46: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.396251 :80009AE3:db_trace:ksl2.c@2324:kslwtbctx(): [10005:46: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.396624 :80009AFF:db_trace:ksl2.c@2611:kslwtectx(): [10005:46: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.396625 :80009B00:db_trace:ksl2.c@2619:kslwtectx(): [10005:46:0] KSL WAIT END wait times (usecs) - snap=374, exc=374, tot=374 2025-03-23 11:31:22.398054 :80009B58:db_trace:kst.c@698:kstpsodel(): [10280:46:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 46 (osid: 3380, P023)