Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250507082248\orcl_j004_4608_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:37437M/63366M, Ph+PgF:44872M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 60 Windows thread id: 4608, image: ORACLE.EXE (J004) *** 2025-05-07 08:22:49.142 *** 2025-05-07 08:22:49.142 Process diagnostic dump for ORACLE.EXE (J004), OS id=4608, pid: 60, 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-07 08:22:49.142 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 60 (osid: 4608, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-03-23 11:26:28.295699 :800038C9:db_trace:kst.c@673:kstipg(): [10280:60:0] kst: process state object created on 03-23 11:26:28.295 2025-03-23 11:26:28.295700 :800038CA:db_trace:kst.c@679:kstipg(): [10280:60:0] kst: process info: ospid=4608 pso_num=60 pso_serial#=1 2025-03-23 11:26:28.295769 :800038CF:db_trace:kso.c@4093:ksonfy(): [10420:60:0] kso: new process: pid=4608 osp_idx=58 osp_ver=0 osp_pg=0 (spawn #68) 2025-03-23 11:26:28.295769 :800038D0:db_trace:kso.c@4099:ksonfy(): [10420:60:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-03-23 11:26:28.295769 :800038D1:db_trace:kso.c@4103:ksonfy(): [10420:60:0] kso: new process: pso_index=60 reservation=1 time=48312 2025-03-23 11:26:28.295769 :800038D2:db_trace:kso.c@4109:ksonfy(): [10420:60:0] kso: spawn diagnostics: queue time=0 secs 2025-03-23 11:26:28.295770 :800038D3:db_trace:kso.c@4111:ksonfy(): [10420:60:0] kso: spawn diagnostics: fork time=0 secs 2025-03-23 11:26:28.295770 :800038D4:db_trace:kso.c@4113:ksonfy(): [10420:60:0] kso: spawn diagnostics: exec time=0 secs 2025-03-23 11:26:28.295770 :800038D5:db_trace:kso.c@4115:ksonfy(): [10420:60:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-03-23 11:26:28.295770 :800038D6:db_trace:kso.c@4117:ksonfy(): [10420:60:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-03-23 11:26:28.295996 :800038DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.298916 :8000390E:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.298993 :80003910:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.302422 :80003912:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.307498 :80003914:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.307824 :80003916:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.314724 :8000391F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.314841 :80003921:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=56 loc='kgl.h LINE:8469 ID:kgllkdl: post after freeing latch' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.335037 :80003930:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:60:225] atxpus:jslv.c:9203 2025-03-23 11:26:28.335053 :80003931:db_trace:ksl2.c@2477:kslwtbctx(): [10005:60:225] KSL WAIT BEG [latch free] 33760215528/0x7dc4401e8 15/0xf 0/0x0 wait_id=3 seq_num=4 snap_id=1 2025-03-23 11:26:28.335089 :80003937:db_trace:ksl2.c@2611:kslwtectx(): [10005:60:225] KSL WAIT END [latch free] 33760215528/0x7dc4401e8 15/0xf 0/0x0 wait_id=3 seq_num=4 snap_id=1 2025-03-23 11:26:28.335089 :80003938:db_trace:ksl2.c@2619:kslwtectx(): [10005:60:225] KSL WAIT END wait times (usecs) - snap=36, exc=36, tot=36 2025-03-23 11:26:28.335177 :80003939:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.335280 :8000393B:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.336192 :8000393E:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.336207 :8000393F:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.386467 :80003943:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.401732 :80003945:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.401796 :80003948:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.434854 :8000394A:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=56 loc='kgl.h LINE:8483 ID:kgllldl: post after freeing latch' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-03-23 11:26:28.630935 :80003994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.640286 :8000399C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.641265 :800039A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.641367 :800039AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:60:225] atxpop:jslv.c:9203 2025-03-23 11:26:28.655282 :800039AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.655303 :800039B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:60:225] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-03-23 11:26:28.655343 :800039BD:db_trace:ksl2.c@12699:ksliwat(): [10005:60:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-03-23 11:26:58.713455 :80004601:db_trace:kst.c@698:kstpsodel(): [10280:60:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 60 (osid: 4608, J004)