Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250609222310\orcl_w000_14696_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:36805M/63366M, Ph+PgF:43375M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 14696, image: ORACLE.EXE (W000) *** 2025-06-09 22:23:10.340 *** SESSION ID:(177.37537) 2025-06-09 22:23:10.340 *** 2025-06-09 22:23:10.340 Process diagnostic dump for ORACLE.EXE (W000), OS id=14696, pid: 22, proc_ser: 242, sid: 177, sess_ser: 37537 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=3.633471 sec, exc=3.633471 sec, total=3.633471 sec wait times: max=5.000000 sec, heur=3 min 38 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=5.000779 sec, exc=5.000779 sec, total=5.000779 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=5.008003 sec, exc=5.008003 sec, total=5.008003 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=40 seq_num=41 snap_id=1 wait times: snap=5.009183 sec, exc=5.009183 sec, total=5.009183 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=5.005141 sec, exc=5.005141 sec, total=5.005141 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=38 seq_num=39 snap_id=1 wait times: snap=5.003369 sec, exc=5.003369 sec, total=5.003369 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=5.001791 sec, exc=5.001791 sec, total=5.001791 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=5.007945 sec, exc=5.007945 sec, total=5.007945 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.004636 sec, exc=5.004636 sec, total=5.004636 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.014719 sec, exc=5.014719 sec, total=5.014719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 37537 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 22:21:10 - 22:23:10] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-09 22:23:10.340 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 14696, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-09 20:46:47.525160 :A289141F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 20:46:47.525832 :A2891434:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:46:47.536917 :A2891451:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-09 20:46:47.537376 :A2891452:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-09 20:46:47.539768 :A2891453:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-09 20:46:47.540122 :A2891454:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:46:47.540160 :A2891457:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-09 20:46:47.541022 :A289145D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:46:47.541148 :A2891466:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-06-09 20:47:17.672740 :A2891E2A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:47:22.628013 :A2891FDB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:47:22.620 2025-06-09 20:47:22.628015 :A2891FDC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7880 pso_num=22 pso_serial#=229 2025-06-09 20:47:22.628149 :A2891FDD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7880 (legacy spawn) 2025-06-09 20:47:22.634682 :A2891FDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:47:22.634858 :A2891FE3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-06-09 20:47:22.635662 :A2891FE8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:47:32.823927 :A28922CF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:47:32.815 2025-06-09 20:47:32.823929 :A28922D0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12508 pso_num=22 pso_serial#=230 2025-06-09 20:47:32.824071 :A28922D1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12508 (legacy spawn) 2025-06-09 20:47:32.830524 :A28922D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:47:32.830719 :A28922D7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-06-09 20:47:32.831523 :A28922DC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:47:45.651906 :A28926E6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:47:45.645 2025-06-09 20:47:45.651908 :A28926E7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13936 pso_num=22 pso_serial#=231 2025-06-09 20:47:45.652041 :A28926E8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13936 (legacy spawn) 2025-06-09 20:47:45.658570 :A28926E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:47:45.658751 :A28926F2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-06-09 20:47:45.659583 :A28926F3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:47:47.619671 :A289277C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:47:47.614 2025-06-09 20:47:47.619673 :A289277D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12620 pso_num=22 pso_serial#=232 2025-06-09 20:47:47.619816 :A289277E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12620 osp_idx=0 osp_ver=290494 osp_pg=0 (spawn #268984) 2025-06-09 20:47:47.619817 :A289277F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 20:47:47.619817 :A2892780:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=212 time=2477810813 2025-06-09 20:47:47.619818 :A2892781:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 20:47:47.619818 :A2892782:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 20:47:47.619818 :A2892783:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 20:47:47.619818 :A2892784:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 20:47:47.619819 :A2892785:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 20:47:47.620318 :A2892786:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 20:47:47.620961 :A289279B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:47:47.628461 :A28927B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-09 20:47:47.628826 :A28927B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:47:47.628860 :A28927BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-09 20:47:47.629718 :A28927C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:47:47.629830 :A28927CA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-06-09 20:48:12.653960 :A289307B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 20:48:42.707304 :A2893A0E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:48:47.690479 :A2893B79:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:48:47.685 2025-06-09 20:48:47.690481 :A2893B7A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13888 pso_num=22 pso_serial#=233 2025-06-09 20:48:47.690615 :A2893B7B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13888 osp_idx=0 osp_ver=290495 osp_pg=0 (spawn #268986) 2025-06-09 20:48:47.690615 :A2893B7C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 20:48:47.690616 :A2893B7D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=213 time=2477870875 2025-06-09 20:48:47.690616 :A2893B7E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 20:48:47.690616 :A2893B7F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 20:48:47.690617 :A2893B80:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 20:48:47.690617 :A2893B81:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 20:48:47.690618 :A2893B82:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 20:48:47.691147 :A2893B83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 20:48:47.691794 :A2893B98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:48:47.702752 :A2893BB4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-09 20:48:47.703283 :A2893BB5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-09 20:48:47.705617 :A2893BB6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-09 20:48:47.705947 :A2893BB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:48:47.705985 :A2893BBA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-09 20:48:47.706825 :A2893BC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-09 20:48:47.706945 :A2893BC9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-06-09 20:49:17.769204 :A289456D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:49:21.133232 :A289468A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:49:21.128 2025-06-09 20:49:21.133234 :A289468B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15624 pso_num=22 pso_serial#=234 2025-06-09 20:49:21.133370 :A289468C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15624 osp_idx=0 osp_ver=290496 osp_pg=0 (spawn #268988) 2025-06-09 20:49:21.133370 :A289468D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 20:49:21.133371 :A289468E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=214 time=2477904329 2025-06-09 20:49:21.133371 :A289468F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 20:49:21.133371 :A2894690:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 20:49:21.133372 :A2894691:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 20:49:21.133372 :A2894692:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 20:49:21.133372 :A2894693:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 20:49:21.133874 :A2894694:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 20:49:21.133919 :A289469D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 20:59:21.939561 :A28A0A8D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 20:59:22.241173 :A28A0AD6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 20:59:22.236 2025-06-09 20:59:22.241175 :A28A0AD7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13916 pso_num=22 pso_serial#=235 2025-06-09 20:59:22.241308 :A28A0AD8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13916 osp_idx=0 osp_ver=290497 osp_pg=0 (spawn #269010) 2025-06-09 20:59:22.241309 :A28A0AD9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 20:59:22.241309 :A28A0ADA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=215 time=2478505438 2025-06-09 20:59:22.241310 :A28A0ADB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 20:59:22.241310 :A28A0ADC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 20:59:22.241310 :A28A0ADD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 20:59:22.241311 :A28A0ADE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 20:59:22.241311 :A28A0ADF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 20:59:22.241806 :A28A0AE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 20:59:22.241852 :A28A0AE7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 21:09:23.156522 :A28AD69D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 21:09:23.395574 :A28AD6D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 21:09:23.390 2025-06-09 21:09:23.395576 :A28AD6D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15820 pso_num=22 pso_serial#=236 2025-06-09 21:09:23.395713 :A28AD6DA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15820 osp_idx=0 osp_ver=290498 osp_pg=0 (spawn #269036) 2025-06-09 21:09:23.395714 :A28AD6DB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 21:09:23.395714 :A28AD6DC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=216 time=2479106579 2025-06-09 21:09:23.395716 :A28AD6DD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 21:09:23.395716 :A28AD6DE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 21:09:23.395717 :A28AD6DF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 21:09:23.395717 :A28AD6E0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 21:09:23.395717 :A28AD6E1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 21:09:23.396209 :A28AD6E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 21:09:23.396254 :A28AD6E9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 21:10:24.139378 :A28AEAFD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-09 21:19:24.544652 :A28B9E5A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-09 21:29:25.388839 :A28C61CA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 21:29:25.674704 :A28C6208:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 21:29:25.670 2025-06-09 21:29:25.674706 :A28C6209:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16324 pso_num=22 pso_serial#=237 2025-06-09 21:29:25.674841 :A28C620A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16324 osp_idx=0 osp_ver=290499 osp_pg=0 (spawn #269080) 2025-06-09 21:29:25.674841 :A28C620B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 21:29:25.674842 :A28C620C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=217 time=2480308860 2025-06-09 21:29:25.674842 :A28C620D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 21:29:25.674842 :A28C620E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 21:29:25.674843 :A28C620F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 21:29:25.674843 :A28C6210:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 21:29:25.674843 :A28C6211:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 21:29:25.675385 :A28C6212:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 21:29:25.675430 :A28C621A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 21:39:26.569467 :A28D276F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 21:39:26.871102 :A28D27AC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 21:39:26.866 2025-06-09 21:39:26.871104 :A28D27AD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13524 pso_num=22 pso_serial#=238 2025-06-09 21:39:26.871246 :A28D27AE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13524 osp_idx=0 osp_ver=290500 osp_pg=0 (spawn #269103) 2025-06-09 21:39:26.871247 :A28D27AF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 21:39:26.871248 :A28D27B0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=218 time=2480910063 2025-06-09 21:39:26.871248 :A28D27B1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 21:39:26.871248 :A28D27B2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 21:39:26.871249 :A28D27B3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 21:39:26.871249 :A28D27B4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 21:39:26.871249 :A28D27B5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 21:39:26.871757 :A28D27B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 21:39:26.871801 :A28D27BE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 21:49:27.716195 :A28DEC15:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 21:49:28.049120 :A28DEC52:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 21:49:28.044 2025-06-09 21:49:28.049122 :A28DEC53:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12820 pso_num=22 pso_serial#=239 2025-06-09 21:49:28.049262 :A28DEC54:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12820 osp_idx=0 osp_ver=290501 osp_pg=0 (spawn #269126) 2025-06-09 21:49:28.049263 :A28DEC55:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 21:49:28.049263 :A28DEC56:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=219 time=2481511235 2025-06-09 21:49:28.049264 :A28DEC57:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 21:49:28.049264 :A28DEC58:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 21:49:28.049264 :A28DEC59:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 21:49:28.049265 :A28DEC5A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 21:49:28.049265 :A28DEC5B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 21:49:28.049773 :A28DEC5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 21:49:28.049817 :A28DEC64:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 21:59:28.919405 :A28EB01B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 21:59:29.205368 :A28EB066:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 21:59:29.201 2025-06-09 21:59:29.205370 :A28EB067:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15780 pso_num=22 pso_serial#=240 2025-06-09 21:59:29.205508 :A28EB068:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15780 osp_idx=0 osp_ver=290502 osp_pg=0 (spawn #269148) 2025-06-09 21:59:29.205509 :A28EB069:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 21:59:29.205509 :A28EB06A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=220 time=2482112391 2025-06-09 21:59:29.205510 :A28EB06B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 21:59:29.205510 :A28EB06C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 21:59:29.205511 :A28EB06D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 21:59:29.205511 :A28EB06E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 21:59:29.205511 :A28EB06F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 21:59:29.205988 :A28EB070:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 21:59:29.206037 :A28EB079:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 22:09:30.028703 :A28F8FA4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 22:09:30.330248 :A28F8FDE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 22:09:30.325 2025-06-09 22:09:30.330250 :A28F8FDF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14768 pso_num=22 pso_serial#=241 2025-06-09 22:09:30.330390 :A28F8FE0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14768 osp_idx=0 osp_ver=290503 osp_pg=0 (spawn #269180) 2025-06-09 22:09:30.330391 :A28F8FE1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 22:09:30.330391 :A28F8FE2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=221 time=2482713516 2025-06-09 22:09:30.330392 :A28F8FE3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 22:09:30.330392 :A28F8FE4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 22:09:30.330392 :A28F8FE5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 22:09:30.330393 :A28F8FE6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 22:09:30.330393 :A28F8FE7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 22:09:30.330877 :A28F8FE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 22:09:30.330922 :A28F8FEF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-09 22:19:31.127674 :A29054F4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-09 22:19:31.444715 :A2905532:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-09 22:19:31.440 2025-06-09 22:19:31.444717 :A2905533:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14696 pso_num=22 pso_serial#=242 2025-06-09 22:19:31.444853 :A2905534:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14696 osp_idx=0 osp_ver=290504 osp_pg=0 (spawn #269203) 2025-06-09 22:19:31.444853 :A2905535:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-09 22:19:31.444854 :A2905536:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=222 time=2483314641 2025-06-09 22:19:31.444854 :A2905537:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-09 22:19:31.444855 :A2905538:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-09 22:19:31.444855 :A2905539:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-09 22:19:31.444855 :A290553A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-09 22:19:31.444856 :A290553B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-09 22:19:31.445339 :A290553C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-09 22:19:31.445388 :A2905544:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 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 22 (osid: 14696, W000)