Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250909222215\orcl_m002_21720_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:34916M/63366M, Ph+PgF:39667M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 21720, image: ORACLE.EXE (M002) *** 2025-09-09 22:22:15.626 *** SESSION ID:(193.46425) 2025-09-09 22:22:15.626 *** 2025-09-09 22:22:15.626 Process diagnostic dump for ORACLE.EXE (M002), OS id=21720, pid: 24, proc_ser: 103, sid: 193, sess_ser: 46425 ------------------------------------------------------------------------------- current sql: Current Wait Stack: Not in wait; last wait ended 0.004754 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.004781 sec since last wait 0: waited for 'RMAN backup & recovery I/O' count=0x1, intr=0x100, timeout=0x7fffffff wait_id=1152 seq_num=1153 snap_id=1 wait times: snap=0.000133 sec, exc=0.000133 sec, total=0.000133 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000523 sec of elapsed time 1: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x2, filetype=0x2 wait_id=1151 seq_num=1152 snap_id=1 wait times: snap=0.000342 sec, exc=0.000342 sec, total=0.000342 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000063 sec of elapsed time 2: waited for 'control file sequential read' file#=0x0, block#=0x119, blocks=0x1 wait_id=1150 seq_num=1151 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 3: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=1149 seq_num=1150 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 4: waited for 'control file sequential read' file#=0x0, block#=0x10, blocks=0x1 wait_id=1148 seq_num=1149 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'control file sequential read' file#=0x0, block#=0x1, blocks=0x1 wait_id=1147 seq_num=1148 snap_id=1 wait times: snap=0.000052 sec, exc=0.000052 sec, total=0.000052 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000019 sec of elapsed time 6: waited for 'rdbms ipc reply' from_process=0xf, timeout=0x147ae14, =0x0 wait_id=1146 seq_num=1147 snap_id=1 wait times: snap=0.000022 sec, exc=0.000022 sec, total=0.000022 sec wait times: max=2.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x1, blocks=0x1 wait_id=1145 seq_num=1146 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 8: waited for 'control file sequential read' file#=0x0, block#=0x18, blocks=0x1 wait_id=1144 seq_num=1145 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x12, blocks=0x1 wait_id=1143 seq_num=1144 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time Sampled Session History of session 193 serial 46425 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [1 sample, 22:22:15] idle wait at each sample [session created at: 22:22:15] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-09 22:22:15.626 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 21720, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-09 22:10:35.202918 :CB440096:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:10:35.202918 :CB440097:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:10:35.202919 :CB440098:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:10:35.203409 :CB440099:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:11:05.228720 :CB440A82:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:11:35.289720 :CB441475:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:11:35.278 2025-09-09 22:11:35.289722 :CB441476:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24128 pso_num=24 pso_serial#=90 2025-09-09 22:11:35.289853 :CB441477:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24128 osp_idx=22 osp_ver=869229 osp_pg=0 (spawn #575862) 2025-09-09 22:11:35.289853 :CB441478:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:11:35.289854 :CB441479:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=123 time=1841546580 2025-09-09 22:11:35.289854 :CB44147A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:11:35.289855 :CB44147B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:11:35.289855 :CB44147C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:11:35.289855 :CB44147D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:11:35.289856 :CB44147E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:11:35.290423 :CB44147F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:12:05.377260 :CB441F4E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:12:35.351209 :CB44295E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:12:35.340 2025-09-09 22:12:35.351211 :CB44295F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23404 pso_num=24 pso_serial#=91 2025-09-09 22:12:35.351340 :CB442960:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23404 osp_idx=22 osp_ver=869230 osp_pg=0 (spawn #575864) 2025-09-09 22:12:35.351341 :CB442961:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:12:35.351341 :CB442962:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=124 time=1841606643 2025-09-09 22:12:35.351342 :CB442963:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:12:35.351342 :CB442964:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:12:35.351343 :CB442965:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:12:35.351343 :CB442966:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:12:35.351343 :CB442967:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:12:35.351825 :CB442968:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:13:05.445811 :CB44335C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:13:18.520705 :CB4437DD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:13:18.508 2025-09-09 22:13:18.520707 :CB4437DE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19388 pso_num=24 pso_serial#=92 2025-09-09 22:13:18.520847 :CB4437DF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19388 osp_idx=22 osp_ver=869231 osp_pg=0 (spawn #575866) 2025-09-09 22:13:18.520848 :CB4437E0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:13:18.520848 :CB4437E1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=125 time=1841649815 2025-09-09 22:13:18.520848 :CB4437E2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:13:18.520849 :CB4437E3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:13:18.520849 :CB4437E4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:13:18.520849 :CB4437E5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:13:18.520850 :CB4437E6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:13:18.521359 :CB4437E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:13:18.521402 :CB4437ED:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 22:13:18.525463 :CB443816:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-09 22:13:18.525510 :CB443817:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-09 22:13:18.525763 :CB443818:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-09 22:13:18.525779 :CB443819:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-09 22:13:18.525995 :CB44381A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-09 22:13:18.526009 :CB44381B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-09 22:13:18.526212 :CB44381C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-09 22:13:18.526225 :CB44381D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-09 22:13:18.526437 :CB44381E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-09 22:13:18.526450 :CB44381F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-09 22:13:18.526657 :CB443820:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517 2025-09-09 22:13:18.526670 :CB443821:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517 2025-09-09 22:13:18.529109 :CB443822:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:13:35.465504 :CB443DAD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:13:35.454 2025-09-09 22:13:35.465506 :CB443DAE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24564 pso_num=24 pso_serial#=93 2025-09-09 22:13:35.465627 :CB443DAF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24564 osp_idx=22 osp_ver=869232 osp_pg=0 (spawn #575868) 2025-09-09 22:13:35.465628 :CB443DB0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:13:35.465628 :CB443DB1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=126 time=1841666768 2025-09-09 22:13:35.465628 :CB443DB2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:13:35.465628 :CB443DB3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:13:35.465629 :CB443DB4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:13:35.465629 :CB443DB5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:13:35.465629 :CB443DB6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:13:35.466120 :CB443DB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:14:05.536167 :CB44474A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:14:20.491755 :CB444C6A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:14:20.480 2025-09-09 22:14:20.491757 :CB444C6B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21328 pso_num=24 pso_serial#=94 2025-09-09 22:14:20.491887 :CB444C6C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21328 osp_idx=22 osp_ver=869233 osp_pg=0 (spawn #575870) 2025-09-09 22:14:20.491888 :CB444C6D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:14:20.491889 :CB444C6E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=127 time=1841711783 2025-09-09 22:14:20.491889 :CB444C6F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:14:20.491890 :CB444C70:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:14:20.491890 :CB444C71:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:14:20.491890 :CB444C72:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:14:20.491891 :CB444C73:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:14:20.492368 :CB444C74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:14:35.493528 :CB445135:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 22:15:05.554142 :CB445B08:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:15:35.600928 :CB4464E7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:15:35.589 2025-09-09 22:15:35.600930 :CB4464E8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21936 pso_num=24 pso_serial#=95 2025-09-09 22:15:35.601053 :CB4464E9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21936 osp_idx=22 osp_ver=869234 osp_pg=0 (spawn #575872) 2025-09-09 22:15:35.601054 :CB4464EA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:15:35.601054 :CB4464EB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=128 time=1841786893 2025-09-09 22:15:35.601054 :CB4464EC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:15:35.601055 :CB4464ED:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:15:35.601055 :CB4464EE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:15:35.601056 :CB4464EF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:15:35.601056 :CB4464F0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:15:35.601612 :CB4464F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:16:05.660274 :CB446ECA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:16:35.671004 :CB4478A5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:16:35.660 2025-09-09 22:16:35.671007 :CB4478A6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23136 pso_num=24 pso_serial#=96 2025-09-09 22:16:35.671176 :CB4478A7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23136 osp_idx=22 osp_ver=869235 osp_pg=0 (spawn #575875) 2025-09-09 22:16:35.671177 :CB4478A8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:16:35.671177 :CB4478A9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=129 time=1841846971 2025-09-09 22:16:35.671178 :CB4478AA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:16:35.671178 :CB4478AB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:16:35.671179 :CB4478AC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:16:35.671179 :CB4478AD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:16:35.671180 :CB4478AE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:16:35.671698 :CB4478AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:17:05.769871 :CB448375:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:17:35.739754 :CB448D3F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:17:35.729 2025-09-09 22:17:35.739756 :CB448D40:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22880 pso_num=24 pso_serial#=97 2025-09-09 22:17:35.739875 :CB448D41:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22880 osp_idx=22 osp_ver=869236 osp_pg=0 (spawn #575877) 2025-09-09 22:17:35.739875 :CB448D42:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:17:35.739876 :CB448D43:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=130 time=1841907033 2025-09-09 22:17:35.739876 :CB448D44:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:17:35.739877 :CB448D45:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:17:35.739877 :CB448D46:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:17:35.739877 :CB448D47:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:17:35.739877 :CB448D48:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:17:35.740365 :CB448D49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:18:05.795127 :CB4496E4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:18:35.809973 :CB44A0C3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:18:35.798 2025-09-09 22:18:35.809975 :CB44A0C4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16340 pso_num=24 pso_serial#=98 2025-09-09 22:18:35.810103 :CB44A0C5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16340 osp_idx=22 osp_ver=869237 osp_pg=0 (spawn #575879) 2025-09-09 22:18:35.810104 :CB44A0C6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:18:35.810105 :CB44A0C7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=131 time=1841967112 2025-09-09 22:18:35.810105 :CB44A0C8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:18:35.810105 :CB44A0C9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:18:35.810106 :CB44A0CA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:18:35.810106 :CB44A0CB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:18:35.810107 :CB44A0CC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:18:35.810576 :CB44A0CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:19:05.877032 :CB44AA70:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:19:35.850433 :CB44B444:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:19:35.838 2025-09-09 22:19:35.850436 :CB44B445:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23940 pso_num=24 pso_serial#=99 2025-09-09 22:19:35.850556 :CB44B446:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23940 osp_idx=22 osp_ver=869238 osp_pg=0 (spawn #575881) 2025-09-09 22:19:35.850557 :CB44B447:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:19:35.850557 :CB44B448:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=132 time=1842027143 2025-09-09 22:19:35.850557 :CB44B449:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:19:35.850558 :CB44B44A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:19:35.850558 :CB44B44B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:19:35.850558 :CB44B44C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:19:35.850559 :CB44B44D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:19:35.851054 :CB44B44E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:20:05.889938 :CB44BE01:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:20:35.939097 :CB44C7FC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:20:35.928 2025-09-09 22:20:35.939099 :CB44C7FD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19212 pso_num=24 pso_serial#=100 2025-09-09 22:20:35.939233 :CB44C7FE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19212 osp_idx=22 osp_ver=869239 osp_pg=0 (spawn #575883) 2025-09-09 22:20:35.939234 :CB44C7FF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:20:35.939236 :CB44C800:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=133 time=1842087237 2025-09-09 22:20:35.939236 :CB44C801:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:20:35.939237 :CB44C802:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:20:35.939237 :CB44C803:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:20:35.939237 :CB44C804:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:20:35.939237 :CB44C805:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:20:35.939694 :CB44C806:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:21:06.026351 :CB44D1D4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:21:36.032729 :CB44DBC3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:21:36.021 2025-09-09 22:21:36.032732 :CB44DBC4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19592 pso_num=24 pso_serial#=101 2025-09-09 22:21:36.032889 :CB44DBC5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19592 osp_idx=22 osp_ver=869240 osp_pg=0 (spawn #575885) 2025-09-09 22:21:36.032889 :CB44DBC6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:21:36.032890 :CB44DBC7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=134 time=1842147330 2025-09-09 22:21:36.032891 :CB44DBC8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:21:36.032891 :CB44DBC9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:21:36.032892 :CB44DBCA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:21:36.032892 :CB44DBCB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:21:36.032893 :CB44DBCC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:21:36.033434 :CB44DBCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:22:06.136178 :CB44E60F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:22:14.211172 :CB44ED93:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:22:14.204 2025-09-09 22:22:14.211174 :CB44ED94:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23088 pso_num=24 pso_serial#=102 2025-09-09 22:22:14.211340 :CB44ED95:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23088 osp_idx=22 osp_ver=869241 osp_pg=0 (spawn #575887) 2025-09-09 22:22:14.211340 :CB44ED96:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:22:14.211341 :CB44ED97:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=135 time=1842185518 2025-09-09 22:22:14.211341 :CB44ED98:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:22:14.211341 :CB44ED99:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:22:14.211342 :CB44ED9A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:22:14.211342 :CB44ED9B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:22:14.211342 :CB44ED9C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:22:14.212257 :CB44ED9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:22:14.212298 :CB44EDA3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-09 22:22:14.306289 :CB44EDD5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-09-09 22:22:15.462224 :CB44EE5E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 22:22:15.454 2025-09-09 22:22:15.462227 :CB44EE5F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21720 pso_num=24 pso_serial#=103 2025-09-09 22:22:15.462381 :CB44EE60:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21720 osp_idx=22 osp_ver=869242 osp_pg=0 (spawn #575889) 2025-09-09 22:22:15.462381 :CB44EE61:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-09 22:22:15.462382 :CB44EE62:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=136 time=1842186768 2025-09-09 22:22:15.462382 :CB44EE63:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-09-09 22:22:15.462383 :CB44EE64:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-09-09 22:22:15.462383 :CB44EE65:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-09-09 22:22:15.462383 :CB44EE66:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-09 22:22:15.462384 :CB44EE67:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-09 22:22:15.462903 :CB44EE68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-09 22:22:15.462944 :CB44EE6E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 21720, M002)