Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511140405\orcl_p025_3372_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:37501M/63366M, Ph+PgF:44892M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 48 Windows thread id: 3372, image: ORACLE.EXE (P025) *** 2025-05-11 14:04:05.187 *** 2025-05-11 14:04:05.187 Process diagnostic dump for ORACLE.EXE (P025), OS id=3372, pid: 48, 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-11 14:04:05.187 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 48 (osid: 3372, P025) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-03-23 11:26:20.598048 :80000763:db_trace:kst.c@673:kstipg(): [10280:48:0] kst: process state object created on 03-23 11:26:20.592 2025-03-23 11:26:20.598049 :80000764:db_trace:kst.c@679:kstipg(): [10280:48:0] kst: process info: ospid=3372 pso_num=48 pso_serial#=1 2025-03-23 11:26:20.598116 :80000765:db_trace:kso.c@4093:ksonfy(): [10420:48:0] kso: new process: pid=3372 osp_idx=46 osp_ver=0 osp_pg=0 (spawn #47) 2025-03-23 11:26:20.598117 :80000766:db_trace:kso.c@4099:ksonfy(): [10420:48:0] kso: new process: first_req_pid=3724 spawner_pid=3660 sga_crt_ts=x8A8A 2025-03-23 11:26:20.598117 :80000767:db_trace:kso.c@4103:ksonfy(): [10420:48:0] kso: new process: pso_index=48 reservation=1 time=40625 2025-03-23 11:26:20.598117 :80000768:db_trace:kso.c@4109:ksonfy(): [10420:48:0] kso: spawn diagnostics: queue time=0 secs 2025-03-23 11:26:20.598117 :80000769:db_trace:kso.c@4111:ksonfy(): [10420:48:0] kso: spawn diagnostics: fork time=0 secs 2025-03-23 11:26:20.598117 :8000076A:db_trace:kso.c@4113:ksonfy(): [10420:48:0] kso: spawn diagnostics: exec time=0 secs 2025-03-23 11:26:20.598118 :8000076B:db_trace:kso.c@4115:ksonfy(): [10420:48:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-03-23 11:26:20.598118 :8000076C:db_trace:kso.c@4117:ksonfy(): [10420:48:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-03-23 11:26:20.598149 :8000076D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48: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.611521 :80000840:db_trace:ksl2.c@12699:ksliwat(): [10005:48: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.611605 :80000851:db_trace:ksl2.c@2324:kslwtbctx(): [10005:48: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.611643 :80000857:db_trace:ksl2.c@2611:kslwtectx(): [10005:48: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.611645 :80000858:db_trace:ksl2.c@2619:kslwtectx(): [10005:48:0] KSL WAIT END wait times (usecs) - snap=36, exc=36, tot=36 2025-03-23 11:26:20.613598 :80000876:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.622540 :800008D0:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.636915 :8000090E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.639100 :80000963:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.639106 :8000096D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.644866 :80000A41:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.666608 :80000AAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.736192 :800021D3:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.740241 :800021E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.740626 :80002381:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.747069 :80002388:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.863434 :80002AE4:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.863441 :80002AEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.873028 :80002C8A:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.875905 :80002CA9:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-03-23 11:26:20.882958 :80002CFB:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] KSL POST RCVD poster=26 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:20.883361 :80002CFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] KSL POST SENT postee=27 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 2025-03-23 11:26:20.883415 :80002D00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.887865 :80002D62:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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 :80002D6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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 :80002D73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.890138 :80002DB0:db_trace:ksl2.c@12699:ksliwat(): [10005:48:130] 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.890146 :80002DBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:48:130] 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.890445 :80002E0E:db_trace:ksl2.c@2324:kslwtbctx(): [10005:48: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:48: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 :80002E24:db_trace:ksl2.c@2619:kslwtectx(): [10005:48:0] KSL WAIT END wait times (usecs) - snap=29, exc=29, tot=29 2025-03-23 11:26:21.764413 :800030A8:db_trace:ksl2.c@12699:ksliwat(): [10005:48: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.396515 :80009AF7:db_trace:ksl2.c@2324:kslwtbctx(): [10005:48: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.396741 :80009B0A:db_trace:ksl2.c@2611:kslwtectx(): [10005:48: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.396743 :80009B0B:db_trace:ksl2.c@2619:kslwtectx(): [10005:48:0] KSL WAIT END wait times (usecs) - snap=224, exc=224, tot=224 2025-03-23 11:31:22.397867 :80009B46:db_trace:kst.c@698:kstpsodel(): [10280:48:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 48 (osid: 3372, P025)