Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250830060008\orcl_w000_13816_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:35087M/63366M, Ph+PgF:40055M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 13816, image: ORACLE.EXE (W000) *** 2025-08-30 06:00:08.782 *** SESSION ID:(201.62627) 2025-08-30 06:00:08.782 *** 2025-08-30 06:00:08.782 Process diagnostic dump for ORACLE.EXE (W000), OS id=13816, pid: 25, proc_ser: 115, sid: 201, sess_ser: 62627 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=3.970157 sec, exc=3.970157 sec, total=3.970157 sec wait times: max=5.000000 sec, heur=4 min 49 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.000020 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.010151 sec, exc=5.010151 sec, total=5.010151 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.009721 sec, exc=5.009721 sec, total=5.009721 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.003227 sec, exc=5.003227 sec, total=5.003227 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.002499 sec, exc=5.002499 sec, total=5.002499 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.002762 sec, exc=5.002762 sec, total=5.002762 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 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.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=5.005133 sec, exc=5.005133 sec, total=5.005133 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=5.003527 sec, exc=5.003527 sec, total=5.003527 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.000315 sec, exc=5.000315 sec, total=5.000315 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=5.000324 sec, exc=5.000324 sec, total=5.000324 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time Sampled Session History of session 201 serial 62627 --------------------------------------------------- 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, 05:58:08 - 06:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-30 06:00:08.782 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 13816, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-30 03:45:03.516701 :C67B99C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21252 pso_num=25 pso_serial#=100 2025-08-30 03:45:03.516837 :C67B99C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21252 osp_idx=23 osp_ver=266432 osp_pg=0 (spawn #539945) 2025-08-30 03:45:03.516838 :C67B99C5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 03:45:03.516838 :C67B99C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=911154815 2025-08-30 03:45:03.516839 :C67B99C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 03:45:03.516839 :C67B99C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 03:45:03.516840 :C67B99C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 03:45:03.516840 :C67B99CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 03:45:03.516840 :C67B99CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 03:45:03.517324 :C67B99CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 03:45:03.517366 :C67B99D4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 03:55:04.277696 :C67C6191:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 03:55:04.594908 :C67C61C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 03:55:04.590 2025-08-30 03:55:04.594910 :C67C61C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17868 pso_num=25 pso_serial#=101 2025-08-30 03:55:04.595043 :C67C61C7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17868 osp_idx=23 osp_ver=266433 osp_pg=0 (spawn #539967) 2025-08-30 03:55:04.595043 :C67C61C8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 03:55:04.595044 :C67C61C9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=911755893 2025-08-30 03:55:04.595044 :C67C61CA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 03:55:04.595044 :C67C61CB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 03:55:04.595045 :C67C61CC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 03:55:04.595045 :C67C61CD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 03:55:04.595046 :C67C61CE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 03:55:04.595532 :C67C61CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 03:55:04.595579 :C67C61D6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:05:05.461857 :C67D2B5F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 04:05:05.763836 :C67D2B97:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 04:05:05.758 2025-08-30 04:05:05.763838 :C67D2B98:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21868 pso_num=25 pso_serial#=102 2025-08-30 04:05:05.763988 :C67D2B99:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21868 osp_idx=23 osp_ver=266434 osp_pg=0 (spawn #539993) 2025-08-30 04:05:05.763988 :C67D2B9A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 04:05:05.763989 :C67D2B9B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=912357065 2025-08-30 04:05:05.763989 :C67D2B9C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 04:05:05.763989 :C67D2B9D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 04:05:05.763990 :C67D2B9E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 04:05:05.763990 :C67D2B9F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 04:05:05.763990 :C67D2BA0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 04:05:05.764521 :C67D2BA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 04:05:05.764569 :C67D2BA8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:15:06.617311 :C67DFC5F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 04:15:07.014771 :C67DFC98:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 04:15:07.009 2025-08-30 04:15:07.014774 :C67DFC99:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22340 pso_num=25 pso_serial#=103 2025-08-30 04:15:07.014916 :C67DFC9A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22340 osp_idx=23 osp_ver=266435 osp_pg=0 (spawn #540016) 2025-08-30 04:15:07.014917 :C67DFC9B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 04:15:07.014918 :C67DFC9C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=912958315 2025-08-30 04:15:07.014918 :C67DFC9D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 04:15:07.014919 :C67DFC9E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 04:15:07.014919 :C67DFC9F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 04:15:07.014920 :C67DFCA0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 04:15:07.014920 :C67DFCA1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 04:15:07.015415 :C67DFCA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 04:15:07.015459 :C67DFCAA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:25:07.893226 :C67EC4AE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 04:25:08.273074 :C67EC4F2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 04:25:08.268 2025-08-30 04:25:08.273076 :C67EC4F3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22412 pso_num=25 pso_serial#=104 2025-08-30 04:25:08.273226 :C67EC4F4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22412 osp_idx=23 osp_ver=266436 osp_pg=0 (spawn #540038) 2025-08-30 04:25:08.273226 :C67EC4F5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 04:25:08.273227 :C67EC4F6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=17 time=913559580 2025-08-30 04:25:08.273227 :C67EC4F7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 04:25:08.273228 :C67EC4F8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 04:25:08.273229 :C67EC4F9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 04:25:08.273229 :C67EC4FA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 04:25:08.273229 :C67EC4FB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 04:25:08.273742 :C67EC4FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 04:25:08.273797 :C67EC502:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:35:09.047012 :C67F8CD2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 04:35:09.411221 :C67F8D0F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 04:35:09.406 2025-08-30 04:35:09.411223 :C67F8D10:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21796 pso_num=25 pso_serial#=105 2025-08-30 04:35:09.411356 :C67F8D11:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21796 osp_idx=23 osp_ver=266437 osp_pg=0 (spawn #540061) 2025-08-30 04:35:09.411357 :C67F8D12:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 04:35:09.411357 :C67F8D13:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=18 time=914160721 2025-08-30 04:35:09.411358 :C67F8D14:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 04:35:09.411358 :C67F8D15:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 04:35:09.411358 :C67F8D16:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 04:35:09.411359 :C67F8D17:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 04:35:09.411359 :C67F8D18:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 04:35:09.411848 :C67F8D19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 04:35:09.411894 :C67F8D20:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:45:10.220620 :C6805614:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 04:45:10.522136 :C680564D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 04:45:10.517 2025-08-30 04:45:10.522138 :C680564E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22956 pso_num=25 pso_serial#=106 2025-08-30 04:45:10.522269 :C680564F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22956 osp_idx=23 osp_ver=266438 osp_pg=0 (spawn #540084) 2025-08-30 04:45:10.522269 :C6805650:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 04:45:10.522270 :C6805651:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=19 time=914761830 2025-08-30 04:45:10.522271 :C6805652:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 04:45:10.522271 :C6805653:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 04:45:10.522271 :C6805654:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 04:45:10.522272 :C6805655:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 04:45:10.522272 :C6805656:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 04:45:10.522765 :C6805657:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 04:45:10.522817 :C680565E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:50:19.683450 :C680BCD3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 04:55:11.606020 :C6811E25:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:05:12.410236 :C681EB06:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:05:12.727417 :C681EB3A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:05:12.722 2025-08-30 05:05:12.727418 :C681EB3B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10320 pso_num=25 pso_serial#=107 2025-08-30 05:05:12.727555 :C681EB3C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10320 osp_idx=23 osp_ver=266439 osp_pg=0 (spawn #540131) 2025-08-30 05:05:12.727556 :C681EB3D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:05:12.727556 :C681EB3E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=20 time=915964033 2025-08-30 05:05:12.727556 :C681EB3F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:05:12.727557 :C681EB40:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:05:12.727557 :C681EB41:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:05:12.727558 :C681EB42:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:05:12.727558 :C681EB43:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:05:12.728103 :C681EB44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:05:12.728146 :C681EB4C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:15:13.578464 :C682B770:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:15:14.114355 :C682B7C1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:15:14.110 2025-08-30 05:15:14.114358 :C682B7C2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16692 pso_num=25 pso_serial#=108 2025-08-30 05:15:14.114494 :C682B7C3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16692 osp_idx=23 osp_ver=266440 osp_pg=0 (spawn #540154) 2025-08-30 05:15:14.114494 :C682B7C4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:15:14.114495 :C682B7C5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=21 time=916565424 2025-08-30 05:15:14.114495 :C682B7C6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:15:14.114495 :C682B7C7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:15:14.114496 :C682B7C8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:15:14.114496 :C682B7C9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:15:14.114497 :C682B7CA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:15:14.114993 :C682B7CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:15:14.115037 :C682B7D2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:25:14.921331 :C68382A3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:25:15.472837 :C68382DB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:25:15.468 2025-08-30 05:25:15.472838 :C68382DC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20616 pso_num=25 pso_serial#=109 2025-08-30 05:25:15.472979 :C68382DD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20616 osp_idx=23 osp_ver=266441 osp_pg=0 (spawn #540176) 2025-08-30 05:25:15.472979 :C68382DE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:25:15.472980 :C68382DF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=22 time=917166783 2025-08-30 05:25:15.472980 :C68382E0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:25:15.472981 :C68382E1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:25:15.472981 :C68382E2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:25:15.472981 :C68382E3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:25:15.472982 :C68382E4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:25:15.473467 :C68382E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:25:15.473513 :C68382EE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:35:16.260792 :C6844B1A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:35:16.361975 :C6844B1D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:35:16.354 2025-08-30 05:35:16.361977 :C6844B1E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22248 pso_num=25 pso_serial#=110 2025-08-30 05:35:16.362119 :C6844B1F:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22248 (legacy spawn) 2025-08-30 05:35:16.368630 :C6844B20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 05:35:16.368804 :C6844B29:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:35:16.369595 :C6844B2A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:35:16.655968 :C6844B6F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:35:16.651 2025-08-30 05:35:16.655969 :C6844B70:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21068 pso_num=25 pso_serial#=111 2025-08-30 05:35:16.656111 :C6844B71:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21068 osp_idx=23 osp_ver=266443 osp_pg=0 (spawn #540199) 2025-08-30 05:35:16.656111 :C6844B72:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:35:16.656112 :C6844B73:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=23 time=917767955 2025-08-30 05:35:16.656112 :C6844B74:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:35:16.656113 :C6844B75:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:35:16.656113 :C6844B76:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:35:16.656113 :C6844B77:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:35:16.656114 :C6844B78:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:35:16.656598 :C6844B79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:35:16.656642 :C6844B81:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:45:17.429319 :C685149F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:45:17.991057 :C68514E7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:45:17.986 2025-08-30 05:45:17.991059 :C68514E8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18376 pso_num=25 pso_serial#=112 2025-08-30 05:45:17.991193 :C68514E9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18376 osp_idx=23 osp_ver=266444 osp_pg=0 (spawn #540222) 2025-08-30 05:45:17.991193 :C68514EA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:45:17.991194 :C68514EB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=24 time=918369299 2025-08-30 05:45:17.991194 :C68514EC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:45:17.991195 :C68514ED:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:45:17.991195 :C68514EE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:45:17.991195 :C68514EF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:45:17.991195 :C68514F0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:45:17.991680 :C68514F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:45:17.991730 :C68514F9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:55:18.796605 :C685DD9D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:55:19.092094 :C685DDDB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:55:19.078 2025-08-30 05:55:19.092095 :C685DDDC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21896 pso_num=25 pso_serial#=113 2025-08-30 05:55:19.092235 :C685DDDD:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21896 (legacy spawn) 2025-08-30 05:55:19.098759 :C685DDDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 05:55:19.098933 :C685DDE7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:55:19.099727 :C685DDE8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:55:19.108467 :C685DDEB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:55:19.094 2025-08-30 05:55:19.108469 :C685DDEC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16984 pso_num=25 pso_serial#=114 2025-08-30 05:55:19.108603 :C685DDED:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16984 (legacy spawn) 2025-08-30 05:55:19.115043 :C685DDEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 05:55:19.115213 :C685DDF7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08-30 05:55:19.116029 :C685DDF8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-30 05:55:19.457536 :C685DE2F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-30 05:55:19.453 2025-08-30 05:55:19.457538 :C685DE30:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13816 pso_num=25 pso_serial#=115 2025-08-30 05:55:19.457678 :C685DE31:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13816 osp_idx=23 osp_ver=266447 osp_pg=0 (spawn #540244) 2025-08-30 05:55:19.457679 :C685DE32:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 05:55:19.457680 :C685DE33:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=25 time=918970768 2025-08-30 05:55:19.457680 :C685DE34:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 05:55:19.457680 :C685DE35:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 05:55:19.457680 :C685DE36:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 05:55:19.457681 :C685DE37:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 05:55:19.457681 :C685DE38:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 05:55:19.458187 :C685DE39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 05:55:19.458229 :C685DE41:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 13816, W000)