Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250822220005\orcl_p030_3432_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:35295M/63366M, Ph+PgF:40456M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 53 Windows thread id: 3432, image: ORACLE.EXE (P030) *** 2025-08-22 22:00:05.318 *** 2025-08-22 22:00:05.318 Process diagnostic dump for ORACLE.EXE (P030), OS id=3432, pid: 53, 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-08-22 22:00:05.318 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 53 (osid: 3432, P030) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-03-23 11:26:20.610965 :800007FE:db_trace:kst.c@673:kstipg(): [10280:53:0] kst: process state object created on 03-23 11:26:20.608 2025-03-23 11:26:20.610967 :800007FF:db_trace:kst.c@679:kstipg(): [10280:53:0] kst: process info: ospid=3432 pso_num=53 pso_serial#=1 2025-03-23 11:26:20.611174 :80000800:db_trace:kso.c@4093:ksonfy(): [10420:53:0] kso: new process: pid=3432 osp_idx=51 osp_ver=0 osp_pg=0 (spawn #52) 2025-03-23 11:26:20.611175 :80000801:db_trace:kso.c@4099:ksonfy(): [10420:53:0] kso: new process: first_req_pid=3724 spawner_pid=3660 sga_crt_ts=x8A8A 2025-03-23 11:26:20.611175 :80000802:db_trace:kso.c@4103:ksonfy(): [10420:53:0] kso: new process: pso_index=53 reservation=1 time=40640 2025-03-23 11:26:20.611176 :80000803:db_trace:kso.c@4109:ksonfy(): [10420:53:0] kso: spawn diagnostics: queue time=0 secs 2025-03-23 11:26:20.611176 :80000804:db_trace:kso.c@4111:ksonfy(): [10420:53:0] kso: spawn diagnostics: fork time=0 secs 2025-03-23 11:26:20.611177 :80000805:db_trace:kso.c@4113:ksonfy(): [10420:53:0] kso: spawn diagnostics: exec time=0 secs 2025-03-23 11:26:20.611177 :80000806:db_trace:kso.c@4115:ksonfy(): [10420:53:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-03-23 11:26:20.611178 :80000807:db_trace:kso.c@4117:ksonfy(): [10420:53:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-03-23 11:26:20.611276 :80000808:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53: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.611556 :80000849:db_trace:ksl2.c@12699:ksliwat(): [10005:53: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.613528 :80000870:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.622545 :800008D2:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.631477 :800008D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.639108 :8000096F:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.639114 :80000973:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.645968 :80000A68:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.666236 :80000A9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.736199 :800021DB:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.740396 :80002205:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.740581 :8000234F:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.740581*:80002358:CACHE_RCV:kcrp.c@1354:kcrpclaim(): kcrpclaim: Entering kcrpclaim()2025-03-23 11:26:20.740581*:80002359:CACHE_RCV:kcrp.c@1374:kcrpclaim(): kcrpclaim: claim dba 0x80f918 (2,63768) at version 0x0000.0d1dfb63.0x01 2025-03-23 11:26:20.744821*:800023A4:CACHE_RCV:kcrp.c@1466:kcrpclaim(): kcrpclaim: Claim issued for dba 0x80f918 (AFN 2, BNO 63768)2025-03-23 11:26:20.744821*:800023A5:CACHE_RCV:kcrp.c@1354:kcrpclaim(): kcrpclaim: Entering kcrpclaim()2025-03-23 11:26:20.744821*:800023A7:CACHE_RCV:kcrp.c@1374:kcrpclaim(): kcrpclaim: claim dba 0x80f91c (2,63772) at version 0x0000.0d1dfb93.0x01 2025-03-23 11:26:20.744821*:800023B6:CACHE_RCV:kcrp.c@1466:kcrpclaim(): kcrpclaim: Claim issued for dba 0x80f91c (AFN 2, BNO 63772)2025-03-23 11:26:20.747177 :800023BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.834649 :80002469:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.839941 :8000277A:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.839941*:800027B2: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*:8000285F:CACHE_RCV:kcb.c@23450:kcbtema(): kcbtema: the last CV (DBA 0x 80f918, AFN 2, BNO 63768)2025-03-23 11:26:20.854196*:80002860:CACHE_RCV:kcb.c@23464:kcbtema(): kcbtema: buffer is dirty, moving to req queue2025-03-23 11:26:20.854196*:80002862: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*:80002867: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*:80002869:CACHE_RCV:kcb.c@23450:kcbtema(): kcbtema: the last CV (DBA 0x 80f91c, AFN 2, BNO 63772)2025-03-23 11:26:20.854196*:80002873:CACHE_RCV:kcb.c@23464:kcbtema(): kcbtema: buffer is dirty, moving to req queue2025-03-23 11:26:20.854196*:80002874: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.863445 :80002AF1:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.863925 :80002BF8:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.863936 :80002C15:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.863938 :80002C1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.873043 :80002C92:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.875366 :80002C97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] KSL POST SENT postee=44 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.876171 :80002CB5:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-03-23 11:26:20.887360 :80002D2B:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] KSL POST RCVD poster=37 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.887759 :80002D2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.890062 :80002D6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.890065 :80002D71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.890070 :80002D79:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.890130 :80002DA8:db_trace:ksl2.c@12699:ksliwat(): [10005:53:169] 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.890140 :80002DB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:53:169] 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.890425 :80002DF5:db_trace:ksl2.c@2324:kslwtbctx(): [10005:53: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.890441 :80002E07:db_trace:ksl2.c@2611:kslwtectx(): [10005:53: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.890442 :80002E09:db_trace:ksl2.c@2619:kslwtectx(): [10005:53:0] KSL WAIT END wait times (usecs) - snap=17, exc=17, tot=17 2025-03-23 11:26:21.764404 :8000309E:db_trace:ksl2.c@12699:ksliwat(): [10005:53: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.396232 :80009AE2:db_trace:ksl2.c@2324:kslwtbctx(): [10005:53: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.396397 :80009AED:db_trace:ksl2.c@2611:kslwtectx(): [10005:53: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.396398 :80009AEE:db_trace:ksl2.c@2619:kslwtectx(): [10005:53:0] KSL WAIT END wait times (usecs) - snap=167, exc=167, tot=167 2025-03-23 11:31:22.396455 :80009AF1:db_trace:ksl2.c@2324:kslwtbctx(): [10005:53: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.396712 :80009B06:db_trace:ksl2.c@2611:kslwtectx(): [10005:53: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.396713 :80009B07:db_trace:ksl2.c@2619:kslwtectx(): [10005:53:0] KSL WAIT END wait times (usecs) - snap=257, exc=257, tot=257 2025-03-23 11:31:22.398085 :80009B5E:db_trace:kst.c@698:kstpsodel(): [10280:53:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 53 (osid: 3432, P030)