Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712100945\orcl_w000_11032_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:36426M/63366M, Ph+PgF:42502M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 11032, image: ORACLE.EXE (W000) *** 2025-07-12 10:09:45.562 *** SESSION ID:(193.48993) 2025-07-12 10:09:45.562 *** 2025-07-12 10:09:45.562 Process diagnostic dump for ORACLE.EXE (W000), OS id=11032, pid: 24, proc_ser: 175, sid: 193, sess_ser: 48993 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=3.096561 sec, exc=3.096561 sec, total=3.096561 sec wait times: max=5.000000 sec, heur=2 min 43 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.000030 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.013415 sec, exc=5.013415 sec, total=5.013415 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.007595 sec, exc=5.007595 sec, total=5.007595 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.014716 sec, exc=5.014716 sec, total=5.014716 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.004659 sec, exc=5.004659 sec, total=5.004659 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.001742 sec, exc=5.001742 sec, total=5.001742 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.001140 sec, exc=5.001140 sec, total=5.001140 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.015593 sec, exc=5.015593 sec, total=5.015593 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.009615 sec, exc=5.009615 sec, total=5.009615 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 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.000021 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 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 193 serial 48993 --------------------------------------------------- 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, 10:07:45 - 10:09:45] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-12 10:09:45.562 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 11032, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-12 07:46:12.559695 :B0E5DD58:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-12 07:46:12.562038 :B0E5DD59:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-12 07:46:12.562381 :B0E5DD5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 07:46:12.562414 :B0E5DD5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-12 07:46:12.563343 :B0E5DD63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 07:46:12.563482 :B0E5DD6B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 07:46:42.642912 :B0E5E72D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 07:46:42.775915 :B0E5E730:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 07:46:42.768 2025-07-12 07:46:42.775917 :B0E5E731:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18376 pso_num=24 pso_serial#=162 2025-07-12 07:46:42.776072 :B0E5E732:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18376 (legacy spawn) 2025-07-12 07:46:42.782524 :B0E5E733:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 07:46:42.782554 :B0E5E736:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 07:46:42.782715 :B0E5E739:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 07:46:42.783536 :B0E5E74A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 07:46:43.726585 :B0E5E80A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 07:46:43.721 2025-07-12 07:46:43.726587 :B0E5E80B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8904 pso_num=24 pso_serial#=163 2025-07-12 07:46:43.726723 :B0E5E80C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8904 osp_idx=22 osp_ver=561173 osp_pg=0 (spawn #377182) 2025-07-12 07:46:43.726724 :B0E5E80D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 07:46:43.726724 :B0E5E80E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=92 time=987179626 2025-07-12 07:46:43.726724 :B0E5E80F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 07:46:43.726724 :B0E5E810:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 07:46:43.726725 :B0E5E811:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 07:46:43.726725 :B0E5E812:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 07:46:43.726725 :B0E5E813:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 07:46:43.727201 :B0E5E814:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 07:46:43.727245 :B0E5E81C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 07:52:02.486683 :B0E651BE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 07:56:45.005553 :B0E6B09C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 08:06:45.920685 :B0E78042:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 08:06:46.300684 :B0E780D4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 08:06:46.295 2025-07-12 08:06:46.300686 :B0E780D5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15680 pso_num=24 pso_serial#=164 2025-07-12 08:06:46.300862 :B0E780D6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15680 osp_idx=22 osp_ver=561174 osp_pg=0 (spawn #377229) 2025-07-12 08:06:46.300863 :B0E780D7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 08:06:46.300864 :B0E780D8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=93 time=988382189 2025-07-12 08:06:46.300865 :B0E780D9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 08:06:46.300865 :B0E780DA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 08:06:46.300866 :B0E780DB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 08:06:46.300866 :B0E780DC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 08:06:46.300866 :B0E780DD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 08:06:46.301444 :B0E780DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 08:06:46.301493 :B0E780E5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 08:16:47.198681 :B0E898D2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 08:16:47.547059 :B0E89948:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 08:16:47.542 2025-07-12 08:16:47.547061 :B0E89949:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18108 pso_num=24 pso_serial#=165 2025-07-12 08:16:47.547203 :B0E8994A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18108 osp_idx=22 osp_ver=561175 osp_pg=0 (spawn #377257) 2025-07-12 08:16:47.547204 :B0E8994B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 08:16:47.547204 :B0E8994C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=94 time=988983439 2025-07-12 08:16:47.547205 :B0E8994D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 08:16:47.547205 :B0E8994E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 08:16:47.547205 :B0E8994F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 08:16:47.547206 :B0E89950:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 08:16:47.547206 :B0E89951:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 08:16:47.547711 :B0E89952:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 08:16:47.547759 :B0E89959:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 08:26:48.498918 :B0E984D8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 08:26:48.863168 :B0E98546:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 08:26:48.858 2025-07-12 08:26:48.863170 :B0E98547:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8984 pso_num=24 pso_serial#=166 2025-07-12 08:26:48.863311 :B0E98548:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8984 osp_idx=22 osp_ver=561176 osp_pg=0 (spawn #377280) 2025-07-12 08:26:48.863312 :B0E98549:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 08:26:48.863312 :B0E9854A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=95 time=989584751 2025-07-12 08:26:48.863313 :B0E9854B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 08:26:48.863313 :B0E9854C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 08:26:48.863314 :B0E9854D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 08:26:48.863314 :B0E9854E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 08:26:48.863314 :B0E9854F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 08:26:48.863813 :B0E98550:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 08:26:48.863859 :B0E98558:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 08:36:49.720975 :B0EA50D3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 08:36:50.132006 :B0EA5146:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 08:36:50.127 2025-07-12 08:36:50.132008 :B0EA5147:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17772 pso_num=24 pso_serial#=167 2025-07-12 08:36:50.132142 :B0EA5148:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17772 osp_idx=22 osp_ver=561177 osp_pg=0 (spawn #377302) 2025-07-12 08:36:50.132143 :B0EA5149:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 08:36:50.132143 :B0EA514A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=96 time=990186033 2025-07-12 08:36:50.132143 :B0EA514B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 08:36:50.132144 :B0EA514C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 08:36:50.132145 :B0EA514D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 08:36:50.132145 :B0EA514E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 08:36:50.132145 :B0EA514F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 08:36:50.132626 :B0EA5150:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 08:36:50.132675 :B0EA5157:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 08:46:50.992456 :B0EB1C91:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 08:46:51.419161 :B0EB1CFA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 08:46:51.414 2025-07-12 08:46:51.419163 :B0EB1CFB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17500 pso_num=24 pso_serial#=168 2025-07-12 08:46:51.419305 :B0EB1CFC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17500 osp_idx=22 osp_ver=561178 osp_pg=0 (spawn #377326) 2025-07-12 08:46:51.419306 :B0EB1CFD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 08:46:51.419306 :B0EB1CFE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=97 time=990787314 2025-07-12 08:46:51.419307 :B0EB1CFF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 08:46:51.419307 :B0EB1D00:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 08:46:51.419307 :B0EB1D01:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 08:46:51.419308 :B0EB1D02:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 08:46:51.419308 :B0EB1D03:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 08:46:51.419797 :B0EB1D04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 08:46:51.419840 :B0EB1D0B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 08:56:52.207218 :B0EBE73E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 08:56:52.789967 :B0EBE7D4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 08:56:52.785 2025-07-12 08:56:52.789969 :B0EBE7D5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17596 pso_num=24 pso_serial#=169 2025-07-12 08:56:52.790107 :B0EBE7D6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17596 osp_idx=22 osp_ver=561179 osp_pg=0 (spawn #377349) 2025-07-12 08:56:52.790108 :B0EBE7D7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 08:56:52.790108 :B0EBE7D8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=98 time=991388689 2025-07-12 08:56:52.790109 :B0EBE7D9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 08:56:52.790109 :B0EBE7DA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 08:56:52.790109 :B0EBE7DB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 08:56:52.790110 :B0EBE7DC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 08:56:52.790110 :B0EBE7DD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 08:56:52.790592 :B0EBE7DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 08:56:52.790637 :B0EBE7E5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:06:53.718587 :B0ECC8D2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 09:06:54.098410 :B0ECC937:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 09:06:54.094 2025-07-12 09:06:54.098412 :B0ECC938:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3280 pso_num=24 pso_serial#=170 2025-07-12 09:06:54.098556 :B0ECC939:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3280 osp_idx=22 osp_ver=561180 osp_pg=0 (spawn #377374) 2025-07-12 09:06:54.098557 :B0ECC93A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 09:06:54.098557 :B0ECC93B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=99 time=991989986 2025-07-12 09:06:54.098557 :B0ECC93C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 09:06:54.098558 :B0ECC93D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 09:06:54.098558 :B0ECC93E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 09:06:54.098558 :B0ECC93F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 09:06:54.098559 :B0ECC940:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 09:06:54.099053 :B0ECC941:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 09:06:54.099102 :B0ECC94A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:16:55.019837 :B0ED939B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 09:16:55.399361 :B0ED9421:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 09:16:55.394 2025-07-12 09:16:55.399364 :B0ED9422:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16200 pso_num=24 pso_serial#=171 2025-07-12 09:16:55.399499 :B0ED9423:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16200 osp_idx=22 osp_ver=561181 osp_pg=0 (spawn #377397) 2025-07-12 09:16:55.399499 :B0ED9424:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 09:16:55.399500 :B0ED9425:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=100 time=992591298 2025-07-12 09:16:55.399500 :B0ED9426:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 09:16:55.399501 :B0ED9427:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 09:16:55.399501 :B0ED9428:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 09:16:55.399501 :B0ED9429:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 09:16:55.399502 :B0ED942A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 09:16:55.399991 :B0ED942B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 09:16:55.400034 :B0ED9433:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:26:56.261506 :B0EE5AFF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 09:26:56.729353 :B0EE5BA4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 09:26:56.725 2025-07-12 09:26:56.729355 :B0EE5BA5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2964 pso_num=24 pso_serial#=172 2025-07-12 09:26:56.729493 :B0EE5BA6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=2964 osp_idx=22 osp_ver=561182 osp_pg=0 (spawn #377420) 2025-07-12 09:26:56.729493 :B0EE5BA7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 09:26:56.729494 :B0EE5BA8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=101 time=993192626 2025-07-12 09:26:56.729494 :B0EE5BA9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 09:26:56.729495 :B0EE5BAA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 09:26:56.729496 :B0EE5BAB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 09:26:56.729497 :B0EE5BAC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 09:26:56.729497 :B0EE5BAD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 09:26:56.729987 :B0EE5BAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 09:26:56.730036 :B0EE5BB6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:36:57.575747 :B0EF244D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 09:36:58.206387 :B0EF24E7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 09:36:58.201 2025-07-12 09:36:58.206389 :B0EF24E8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18404 pso_num=24 pso_serial#=173 2025-07-12 09:36:58.206525 :B0EF24E9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18404 osp_idx=22 osp_ver=561183 osp_pg=0 (spawn #377442) 2025-07-12 09:36:58.206526 :B0EF24EA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 09:36:58.206526 :B0EF24EB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=102 time=993794095 2025-07-12 09:36:58.206527 :B0EF24EC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 09:36:58.206527 :B0EF24ED:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 09:36:58.206527 :B0EF24EE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 09:36:58.206528 :B0EF24EF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 09:36:58.206528 :B0EF24F0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 09:36:58.207021 :B0EF24F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 09:36:58.207067 :B0EF24F9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:46:59.168119 :B0EFEACC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 09:46:59.547760 :B0EFEB5E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 09:46:59.543 2025-07-12 09:46:59.547762 :B0EFEB5F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14864 pso_num=24 pso_serial#=174 2025-07-12 09:46:59.547918 :B0EFEB60:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14864 osp_idx=22 osp_ver=561184 osp_pg=0 (spawn #377465) 2025-07-12 09:46:59.547918 :B0EFEB61:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 09:46:59.547919 :B0EFEB62:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=103 time=994395439 2025-07-12 09:46:59.547919 :B0EFEB63:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 09:46:59.547920 :B0EFEB64:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 09:46:59.547920 :B0EFEB65:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 09:46:59.547920 :B0EFEB66:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 09:46:59.547921 :B0EFEB67:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 09:46:59.548425 :B0EFEB68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 09:46:59.548468 :B0EFEB70:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:55:08.518731 :B0F08C8F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 09:57:00.849121 :B0F0B16F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-07-12 10:07:01.684739 :B0F17F91:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-12 10:07:02.189193 :B0F1802A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-12 10:07:02.184 2025-07-12 10:07:02.189195 :B0F1802B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11032 pso_num=24 pso_serial#=175 2025-07-12 10:07:02.189329 :B0F1802C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11032 osp_idx=22 osp_ver=561185 osp_pg=0 (spawn #377512) 2025-07-12 10:07:02.189330 :B0F1802D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:07:02.189330 :B0F1802E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=104 time=995598079 2025-07-12 10:07:02.189330 :B0F1802F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:07:02.189331 :B0F18030:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:07:02.189331 :B0F18031:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:07:02.189332 :B0F18032:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:07:02.189332 :B0F18033:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:07:02.189827 :B0F18034:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:07:02.189874 :B0F1803C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 11032, W000)