Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250917082641\orcl_m002_21696_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:35971M/63366M, Ph+PgF:40274M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 21696, image: ORACLE.EXE (M002) *** 2025-09-17 08:26:41.513 *** 2025-09-17 08:26:41.513 Process diagnostic dump for ORACLE.EXE (M002), OS id=21696, pid: 30, proc_ser: 176, 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-09-17 08:26:41.513 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 21696, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-16 00:01:21.015296 :CDF4E48E:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 00:01:21.015828 :CDF4E48F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 00:01:21.015883 :CDF4E495:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 00:01:21.024926 :CDF4E4AD:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-16 01:00:29.903707 :CDF99A79:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-16 01:00:29.889 2025-09-16 01:00:29.903710 :CDF99A7A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=22828 pso_num=30 pso_serial#=170 2025-09-16 01:00:29.903864 :CDF99A7B:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=22828 osp_idx=28 osp_ver=2209 osp_pg=0 (spawn #596267) 2025-09-16 01:00:29.903865 :CDF99A7C:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 01:00:29.903866 :CDF99A7D:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=106 time=2370081205 2025-09-16 01:00:29.903866 :CDF99A7E:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 01:00:29.903867 :CDF99A7F:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 01:00:29.903867 :CDF99A80:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 01:00:29.903868 :CDF99A81:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 01:00:29.903868 :CDF99A82:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 01:00:29.904434 :CDF99A83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.904476 :CDF99A89:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 01:00:29.912665 :CDF99A97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.913772 :CDF99A9F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.914330 :CDF99AA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.914871 :CDF99AAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.915325 :CDF99AB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.917845 :CDF99ABF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.918968 :CDF99AC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.971554 :CDF99ACF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.972552 :CDF99AD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.972837 :CDF99ADF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.973060 :CDF99AE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 01:00:29.975131 :CDF99AEF:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-16 01:01:28.637388 :CDF9AEF9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-16 01:01:28.632 2025-09-16 01:01:28.637389 :CDF9AEFA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24600 pso_num=30 pso_serial#=171 2025-09-16 01:01:28.637530 :CDF9AEFB:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=24600 osp_idx=28 osp_ver=2210 osp_pg=0 (spawn #596272) 2025-09-16 01:01:28.637533 :CDF9AEFC:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 01:01:28.637533 :CDF9AEFD:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=107 time=2370139924 2025-09-16 01:01:28.637534 :CDF9AEFE:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 01:01:28.637534 :CDF9AEFF:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 01:01:28.637535 :CDF9AF00:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 01:01:28.637535 :CDF9AF01:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 01:01:28.637535 :CDF9AF02:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 01:01:28.638065 :CDF9AF03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 01:01:28.638114 :CDF9AF09:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 01:01:28.647627 :CDF9AF21:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-16 22:00:02.096616 :CE5D7F2F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-16 22:00:02.096 2025-09-16 22:00:02.096617 :CE5D7F30:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23676 pso_num=30 pso_serial#=172 2025-09-16 22:00:02.096758 :CE5D7F31:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23676 osp_idx=28 osp_ver=2211 osp_pg=0 (spawn #599187) 2025-09-16 22:00:02.096759 :CE5D7F32:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 22:00:02.096759 :CE5D7F33:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=108 time=2445653393 2025-09-16 22:00:02.096760 :CE5D7F34:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 22:00:02.096760 :CE5D7F35:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 22:00:02.096761 :CE5D7F36:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 22:00:02.096761 :CE5D7F37:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 22:00:02.096762 :CE5D7F38:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 22:00:02.097490 :CE5D7F39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.102609 :CE5D7F4E:db_trace:ksl2.c@2477:kslwtbctx(): [10005:30:241] KSL WAIT BEG [latch: cache buffers chains] 33024829616/0x7b06ee8b0 150/0x96 0/0x0 wait_id=0 seq_num=1 snap_id=1 2025-09-16 22:00:02.102614 :CE5D7F4F:db_trace:ksl2.c@2611:kslwtectx(): [10005:30:241] KSL WAIT END [latch: cache buffers chains] 33024829616/0x7b06ee8b0 150/0x96 0/0x0 wait_id=0 seq_num=1 snap_id=1 2025-09-16 22:00:02.102615 :CE5D7F50:db_trace:ksl2.c@2619:kslwtectx(): [10005:30:241] KSL WAIT END wait times (usecs) - snap=5, exc=5, tot=5 2025-09-16 22:00:02.103174 :CE5D7F62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.103207 :CE5D7F65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-09-16 22:00:02.109027 :CE5D7FA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.340761 :CE5D8274:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.541231 :CE5D828C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.675529 :CE5D82A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:02.796377 :CE5D82B9:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=0 loc='No post' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 22:00:03.789265 :CE5D848B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.790244 :CE5D8493:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.794285 :CE5D849B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.797406 :CE5D84A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.799688 :CE5D84AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.801116 :CE5D84B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.802445 :CE5D84BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.823577 :CE5D84C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.824189 :CE5D84CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.824782 :CE5D84D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.825288 :CE5D84DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.828168 :CE5D84E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.829217 :CE5D84EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.829987 :CE5D84EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.830861 :CE5D84ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.831285 :CE5D84EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.831291 :CE5D84EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.831859 :CE5D84F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.832396 :CE5D84F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.832727 :CE5D84F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.833837 :CE5D84F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.894855 :CE5D84FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.894905 :CE5D8501:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.895849 :CE5D8507:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.896710 :CE5D850F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.897125 :CE5D8510:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.917902 :CE5D854F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.917952 :CE5D8552:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.918856 :CE5D8558:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.931936 :CE5D8560:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.932597 :CE5D8561:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.954737 :CE5D8569:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.954802 :CE5D856C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:03.955888 :CE5D8572:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.957052 :CE5D857A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:03.957626 :CE5D857B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.999778 :CE5D8587:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:03.999827 :CE5D858A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:04.000706 :CE5D8590:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.001561 :CE5D8593:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:04.001984 :CE5D8594:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.023786 :CE5D85C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.023837 :CE5D85C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:04.024706 :CE5D85CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.025740 :CE5D85D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 22:00:04.026244 :CE5D85D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.047349 :CE5D860A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.047601 :CE5D8612:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 22:00:04.048680 :CE5D8613:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.049266 :CE5D861B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.049706 :CE5D8623:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.049978 :CE5D862B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.050221 :CE5D8633:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.050414 :CE5D863B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-09-16 22:00:04.050951 :CE5D863C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.054954 :CE5D8644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.055044 :CE5D8647:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-16 22:00:04.055157 :CE5D8654:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-16 22:00:34.130281 :CE5D95CC:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-16 23:59:12.900305 :CE6717ED:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-16 23:59:12.893 2025-09-16 23:59:12.900307 :CE6717EE:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=24872 pso_num=30 pso_serial#=173 2025-09-16 23:59:12.900435 :CE6717EF:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=24872 osp_idx=28 osp_ver=2212 osp_pg=0 (spawn #599462) 2025-09-16 23:59:12.900436 :CE6717F0:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 23:59:12.900436 :CE6717F1:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=109 time=2452804205 2025-09-16 23:59:12.900436 :CE6717F2:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 23:59:12.900437 :CE6717F3:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 23:59:12.900437 :CE6717F4:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 23:59:12.900437 :CE6717F5:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 23:59:12.900438 :CE6717F6:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 23:59:12.901006 :CE6717F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-16 23:59:12.901647 :CE67180C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 23:59:12.907243 :CE671814:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 23:59:12.908664 :CE671830:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 23:59:12.908812 :CE671839:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-16 23:59:27.911094 :CE671D80:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-16 23:59:27.911119*:CE671D82:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000295ABB58 estart=1758038367 eid=17058877 ctx=0x000000077BD98A08 cwh=0x00000000293202A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-16 23:59:27.911119*:CE671D83:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000295ABB58 curCtx=0x000000002932F300 pubTabIdxCnt=0 2025-09-16 23:59:27.911119*:CE671D84:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000295ABB58 2025-09-16 23:59:27.911119*:CE671D85:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000295ABB58 2025-09-16 23:59:27.911119*:CE671D86:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000295ABB58 curCtx=0x000000002932F300 xsc->flg4=65792 flags=2 2025-09-16 23:59:27.911119*:CE671D87:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000295ABB58 2025-09-16 23:59:27.911646 :CE671D88:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 23:59:27.919210 :CE671D90:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-09-16 23:59:27.919603 :CE671D91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 23:59:27.919647 :CE671D94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-09-16 23:59:27.920584 :CE671D9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-16 23:59:27.920704 :CE671DA3:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-16 23:59:57.997033 :CE672902:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-17 00:00:00.414885 :CE6729CC:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-17 00:00:00.403 2025-09-17 00:00:00.414888 :CE6729CD:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23016 pso_num=30 pso_serial#=174 2025-09-17 00:00:00.415024 :CE6729CE:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=23016 (legacy spawn) 2025-09-17 00:00:00.430111 :CE6729CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-17 00:06:20.376168 :CE67B2BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-17 00:06:20.376349 :CE67B2C3:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-09-17 00:06:20.377612 :CE67B2C7:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-17 01:04:36.208447 :CE6C4358:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-17 01:04:36.203 2025-09-17 01:04:36.208449 :CE6C4359:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=23636 pso_num=30 pso_serial#=175 2025-09-17 01:04:36.208584 :CE6C435A:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=23636 osp_idx=28 osp_ver=2214 osp_pg=0 (spawn #599616) 2025-09-17 01:04:36.208585 :CE6C435B:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 01:04:36.208585 :CE6C435C:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=110 time=2456727518 2025-09-17 01:04:36.208586 :CE6C435D:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 01:04:36.208586 :CE6C435E:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 01:04:36.208587 :CE6C435F:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 01:04:36.208587 :CE6C4360:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 01:04:36.208588 :CE6C4361:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 01:04:36.209231 :CE6C4362:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 01:04:36.209278 :CE6C4368:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 01:04:36.218690 :CE6C4390:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-09-17 03:04:52.048387 :CE75A5BF:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 09-17 03:04:52.044 2025-09-17 03:04:52.048388 :CE75A5C0:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=21696 pso_num=30 pso_serial#=176 2025-09-17 03:04:52.048568 :CE75A5C1:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=21696 osp_idx=28 osp_ver=2215 osp_pg=0 (spawn #599894) 2025-09-17 03:04:52.048568 :CE75A5C2:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-17 03:04:52.048569 :CE75A5C3:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=111 time=2463943330 2025-09-17 03:04:52.048570 :CE75A5C4:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-09-17 03:04:52.048570 :CE75A5C5:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-09-17 03:04:52.048570 :CE75A5C6:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-09-17 03:04:52.048571 :CE75A5C7:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-17 03:04:52.048572 :CE75A5C8:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-17 03:04:52.049269 :CE75A5C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-17 03:04:52.049320 :CE75A5CF:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-17 03:04:52.058873 :CE75A5F4:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 21696, M002)