Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250622140913\orcl_p029_3416_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:36388M/63366M, Ph+PgF:42671M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 52 Windows thread id: 3416, image: ORACLE.EXE (P029) *** 2025-06-22 14:09:13.951 *** 2025-06-22 14:09:13.951 Process diagnostic dump for ORACLE.EXE (P029), OS id=3416, pid: 52, 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-06-22 14:09:13.951 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 52 (osid: 3416, P029) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-03-23 11:26:20.608206 :800007DF:db_trace:kst.c@673:kstipg(): [10280:52:0] kst: process state object created on 03-23 11:26:20.608 2025-03-23 11:26:20.608207 :800007E0:db_trace:kst.c@679:kstipg(): [10280:52:0] kst: process info: ospid=3416 pso_num=52 pso_serial#=1 2025-03-23 11:26:20.608275 :800007E1:db_trace:kso.c@4093:ksonfy(): [10420:52:0] kso: new process: pid=3416 osp_idx=50 osp_ver=0 osp_pg=0 (spawn #51) 2025-03-23 11:26:20.608275 :800007E2:db_trace:kso.c@4099:ksonfy(): [10420:52:0] kso: new process: first_req_pid=3724 spawner_pid=3660 sga_crt_ts=x8A8A 2025-03-23 11:26:20.608275 :800007E3:db_trace:kso.c@4103:ksonfy(): [10420:52:0] kso: new process: pso_index=52 reservation=1 time=40640 2025-03-23 11:26:20.608276 :800007E4:db_trace:kso.c@4109:ksonfy(): [10420:52:0] kso: spawn diagnostics: queue time=0 secs 2025-03-23 11:26:20.608276 :800007E5:db_trace:kso.c@4111:ksonfy(): [10420:52:0] kso: spawn diagnostics: fork time=0 secs 2025-03-23 11:26:20.608276 :800007E6:db_trace:kso.c@4113:ksonfy(): [10420:52:0] kso: spawn diagnostics: exec time=0 secs 2025-03-23 11:26:20.608276 :800007E7:db_trace:kso.c@4115:ksonfy(): [10420:52:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-03-23 11:26:20.608276 :800007E8:db_trace:kso.c@4117:ksonfy(): [10420:52:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-03-23 11:26:20.608307 :800007E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52: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.611545 :80000847:db_trace:ksl2.c@12699:ksliwat(): [10005:52: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.613643 :80000879:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.622550 :800008D3:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.635678 :800008EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.639106 :8000096D:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.639113 :80000972:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.645750 :80000A61:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.666567 :80000AAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.736198 :800021DA:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.740331 :800021F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.740629 :80002384:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.747074 :8000238A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.863444 :80002AF0:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.863454 :80002AF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.873042 :80002C91:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.875973 :80002CAB:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-03-23 11:26:20.883716 :80002D03:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] KSL POST RCVD poster=27 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.884107 :80002D06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] KSL POST SENT postee=51 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.884160 :80002D08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.887874 :80002D69:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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.890063 :80002D6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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 :80002D70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.890131 :80002DAA:db_trace:ksl2.c@12699:ksliwat(): [10005:52:161] 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 :80002DB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] 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.890176 :80002DDC:db_trace:ksl2.c@2477:kslwtbctx(): [10005:52:161] KSL WAIT BEG [latch free] 136586856/0x8242668 375/0x177 0/0x0 wait_id=54 seq_num=55 snap_id=1 2025-03-23 11:26:20.890481 :80002E26:db_trace:ksl2.c@2611:kslwtectx(): [10005:52:161] KSL WAIT END [latch free] 136586856/0x8242668 375/0x177 0/0x0 wait_id=54 seq_num=55 snap_id=1 2025-03-23 11:26:20.890482 :80002E27:db_trace:ksl2.c@2619:kslwtectx(): [10005:52:161] KSL WAIT END wait times (usecs) - snap=304, exc=304, tot=304 2025-03-23 11:26:20.890573 :80002E42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:52:161] KSL POST SENT postee=22 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.890655 :80002E5D:db_trace:ksl2.c@2324:kslwtbctx(): [10005:52: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.891055 :80002E85:db_trace:ksl2.c@2611:kslwtectx(): [10005:52: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.891056 :80002E86:db_trace:ksl2.c@2619:kslwtectx(): [10005:52:0] KSL WAIT END wait times (usecs) - snap=400, exc=400, tot=400 2025-03-23 11:26:21.764406 :800030A0:db_trace:ksl2.c@12699:ksliwat(): [10005:52: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.395631 :80009AB6:db_trace:ksl2.c@2324:kslwtbctx(): [10005:52: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.395949 :80009AD1:db_trace:ksl2.c@2611:kslwtectx(): [10005:52: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.395953 :80009AD2:db_trace:ksl2.c@2619:kslwtectx(): [10005:52:0] KSL WAIT END wait times (usecs) - snap=318, exc=318, tot=318 2025-03-23 11:31:22.397213 :80009B23:db_trace:kst.c@698:kstpsodel(): [10280:52:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 52 (osid: 3416, P029)