Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250729220008\orcl_w000_4120_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:35888M/63366M, Ph+PgF:41583M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 4120, image: ORACLE.EXE (W000) *** 2025-07-29 22:00:08.559 *** SESSION ID:(177.52567) 2025-07-29 22:00:08.559 *** 2025-07-29 22:00:08.559 Process diagnostic dump for ORACLE.EXE (W000), OS id=4120, pid: 22, proc_ser: 107, sid: 177, sess_ser: 52567 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=239 seq_num=240 snap_id=1 wait times: snap=0.787392 sec, exc=0.787392 sec, total=0.787392 sec wait times: max=5.000000 sec, heur=18 min 17 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=238 seq_num=239 snap_id=1 wait times: snap=5.007256 sec, exc=5.007256 sec, total=5.007256 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=237 seq_num=238 snap_id=1 wait times: snap=5.001454 sec, exc=5.001454 sec, total=5.001454 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=236 seq_num=237 snap_id=1 wait times: snap=5.001846 sec, exc=5.001846 sec, total=5.001846 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=235 seq_num=236 snap_id=1 wait times: snap=5.007359 sec, exc=5.007359 sec, total=5.007359 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=234 seq_num=235 snap_id=1 wait times: snap=5.004623 sec, exc=5.004623 sec, total=5.004623 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=233 seq_num=234 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.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=232 seq_num=233 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=231 seq_num=232 snap_id=1 wait times: snap=5.001718 sec, exc=5.001718 sec, total=5.001718 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=230 seq_num=231 snap_id=1 wait times: snap=5.001135 sec, exc=5.001135 sec, total=5.001135 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=229 seq_num=230 snap_id=1 wait times: snap=5.003138 sec, exc=5.003138 sec, total=5.003138 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 177 serial 52567 --------------------------------------------------- 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, 21:58:08 - 22:00:08] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-29 22:00:08.559 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 4120, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-29 19:30:10.366419 :B8AB186D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 19:30:10.647610 :B8AB18CC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 19:30:10.642 2025-07-29 19:30:10.647612 :B8AB18CD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18548 pso_num=22 pso_serial#=93 2025-07-29 19:30:10.647766 :B8AB18CE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18548 osp_idx=0 osp_ver=463245 osp_pg=0 (spawn #435477) 2025-07-29 19:30:10.647767 :B8AB18CF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 19:30:10.647768 :B8AB18D0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=131 time=2498186548 2025-07-29 19:30:10.647768 :B8AB18D1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 19:30:10.647768 :B8AB18D2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 19:30:10.647769 :B8AB18D3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 19:30:10.647769 :B8AB18D4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 19:30:10.647770 :B8AB18D5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 19:30:10.648352 :B8AB18D6: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-07-29 19:30:10.648419 :B8AB18E0: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-07-29 19:40:11.503309 :B8ABDD4A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 19:40:11.804956 :B8ABDD9E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 19:40:11.799 2025-07-29 19:40:11.804958 :B8ABDD9F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19380 pso_num=22 pso_serial#=94 2025-07-29 19:40:11.805095 :B8ABDDA0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19380 osp_idx=0 osp_ver=463246 osp_pg=0 (spawn #435500) 2025-07-29 19:40:11.805096 :B8ABDDA1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 19:40:11.805096 :B8ABDDA2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=132 time=2498787704 2025-07-29 19:40:11.805097 :B8ABDDA3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 19:40:11.805097 :B8ABDDA4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 19:40:11.805097 :B8ABDDA5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 19:40:11.805097 :B8ABDDA6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 19:40:11.805098 :B8ABDDA7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 19:40:11.805595 :B8ABDDA8: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-07-29 19:40:11.805639 :B8ABDDB0: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-07-29 19:41:50.103768 :B8ABFDAD: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-07-29 19:50:13.086767 :B8ACA138: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-07-29 20:00:13.996059 :B8AD6490:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:00:14.281897 :B8AD64CE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:00:14.276 2025-07-29 20:00:14.281899 :B8AD64CF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16300 pso_num=22 pso_serial#=95 2025-07-29 20:00:14.282040 :B8AD64D0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16300 osp_idx=0 osp_ver=463247 osp_pg=0 (spawn #435545) 2025-07-29 20:00:14.282041 :B8AD64D1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 20:00:14.282041 :B8AD64D2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=133 time=2499990173 2025-07-29 20:00:14.282042 :B8AD64D3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 20:00:14.282042 :B8AD64D4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 20:00:14.282042 :B8AD64D5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 20:00:14.282043 :B8AD64D6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 20:00:14.282043 :B8AD64D7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 20:00:14.282553 :B8AD64D8: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-07-29 20:00:14.282600 :B8AD64DE: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-07-29 20:10:15.108345 :B8AE3061:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:10:15.319887 :B8AE3064:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:10:15.311 2025-07-29 20:10:15.319889 :B8AE3065:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17880 pso_num=22 pso_serial#=96 2025-07-29 20:10:15.320052 :B8AE3066:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17880 (legacy spawn) 2025-07-29 20:10:15.326526 :B8AE3067: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-07-29 20:10:15.326740 :B8AE306C: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-07-29 20:10:15.327566 :B8AE3071:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:10:15.336148 :B8AE3074:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:10:15.327 2025-07-29 20:10:15.336151 :B8AE3075:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19044 pso_num=22 pso_serial#=97 2025-07-29 20:10:15.336283 :B8AE3076:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19044 (legacy spawn) 2025-07-29 20:10:15.342734 :B8AE3077: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-07-29 20:10:15.342969 :B8AE3080: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-07-29 20:10:15.343842 :B8AE3081:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:10:15.487963 :B8AE30CF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:10:15.483 2025-07-29 20:10:15.487966 :B8AE30D0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17844 pso_num=22 pso_serial#=98 2025-07-29 20:10:15.488102 :B8AE30D1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17844 osp_idx=0 osp_ver=463250 osp_pg=0 (spawn #435571) 2025-07-29 20:10:15.488103 :B8AE30D2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 20:10:15.488104 :B8AE30D3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=134 time=2500591376 2025-07-29 20:10:15.488104 :B8AE30D4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 20:10:15.488105 :B8AE30D5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 20:10:15.488105 :B8AE30D6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 20:10:15.488105 :B8AE30D7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 20:10:15.488106 :B8AE30D8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 20:10:15.488604 :B8AE30D9: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-07-29 20:10:15.488657 :B8AE30E3: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-07-29 20:20:16.229959 :B8AEF703:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:20:16.588765 :B8AEF742:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:20:16.583 2025-07-29 20:20:16.588767 :B8AEF743:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1216 pso_num=22 pso_serial#=99 2025-07-29 20:20:16.588906 :B8AEF744:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1216 osp_idx=0 osp_ver=463251 osp_pg=0 (spawn #435594) 2025-07-29 20:20:16.588907 :B8AEF745:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 20:20:16.588907 :B8AEF746:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=135 time=2501192486 2025-07-29 20:20:16.588908 :B8AEF747:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 20:20:16.588908 :B8AEF748:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 20:20:16.588909 :B8AEF749:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 20:20:16.588909 :B8AEF74A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 20:20:16.588910 :B8AEF74B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 20:20:16.589412 :B8AEF74C: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-07-29 20:20:16.589459 :B8AEF754: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-07-29 20:30:17.412188 :B8AFBAD3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:30:17.682547 :B8AFBB20:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:30:17.677 2025-07-29 20:30:17.682550 :B8AFBB21:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8628 pso_num=22 pso_serial#=100 2025-07-29 20:30:17.682695 :B8AFBB22:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8628 osp_idx=0 osp_ver=463252 osp_pg=0 (spawn #435618) 2025-07-29 20:30:17.682695 :B8AFBB23:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 20:30:17.682696 :B8AFBB24:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=2501793579 2025-07-29 20:30:17.682696 :B8AFBB25:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 20:30:17.682696 :B8AFBB26:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 20:30:17.682697 :B8AFBB27:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 20:30:17.682697 :B8AFBB28:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 20:30:17.682697 :B8AFBB29:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 20:30:17.683194 :B8AFBB2A: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-07-29 20:30:17.683240 :B8AFBB34: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-07-29 20:40:18.583079 :B8B07EB5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:40:18.931650 :B8B07EF4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:40:18.926 2025-07-29 20:40:18.931652 :B8B07EF5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12568 pso_num=22 pso_serial#=101 2025-07-29 20:40:18.931799 :B8B07EF6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12568 osp_idx=0 osp_ver=463253 osp_pg=0 (spawn #435639) 2025-07-29 20:40:18.931800 :B8B07EF7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 20:40:18.931800 :B8B07EF8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=137 time=2502394829 2025-07-29 20:40:18.931800 :B8B07EF9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 20:40:18.931801 :B8B07EFA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 20:40:18.931801 :B8B07EFB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 20:40:18.931801 :B8B07EFC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 20:40:18.931802 :B8B07EFD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 20:40:18.932324 :B8B07EFE: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-07-29 20:40:18.932368 :B8B07F06: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-07-29 20:50:19.788799 :B8B142F9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 20:50:20.012433 :B8B1435E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 20:50:20.007 2025-07-29 20:50:20.012436 :B8B1435F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17920 pso_num=22 pso_serial#=102 2025-07-29 20:50:20.012587 :B8B14360:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17920 osp_idx=0 osp_ver=463254 osp_pg=0 (spawn #435662) 2025-07-29 20:50:20.012588 :B8B14361:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 20:50:20.012588 :B8B14362:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=138 time=2502995908 2025-07-29 20:50:20.012589 :B8B14363:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 20:50:20.012589 :B8B14364:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 20:50:20.012589 :B8B14365:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 20:50:20.012590 :B8B14366:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 20:50:20.012590 :B8B14367:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 20:50:20.013093 :B8B14368: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-07-29 20:50:20.013135 :B8B14370: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-07-29 21:00:20.815676 :B8B206AD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 21:00:21.196633 :B8B20717:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 21:00:21.190 2025-07-29 21:00:21.196636 :B8B20718:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17392 pso_num=22 pso_serial#=103 2025-07-29 21:00:21.196807 :B8B20719:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17392 osp_idx=0 osp_ver=463255 osp_pg=0 (spawn #435684) 2025-07-29 21:00:21.196808 :B8B2071A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 21:00:21.196808 :B8B2071B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=139 time=2503597095 2025-07-29 21:00:21.196809 :B8B2071C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 21:00:21.196809 :B8B2071D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 21:00:21.196810 :B8B2071E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 21:00:21.196810 :B8B2071F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 21:00:21.196810 :B8B20720:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 21:00:21.197416 :B8B20721: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-07-29 21:00:21.197486 :B8B2072B: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-07-29 21:10:22.015987 :B8B2D233:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 21:10:22.411524 :B8B2D271:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 21:10:22.406 2025-07-29 21:10:22.411526 :B8B2D272:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9904 pso_num=22 pso_serial#=104 2025-07-29 21:10:22.411666 :B8B2D273:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9904 osp_idx=0 osp_ver=463256 osp_pg=0 (spawn #435710) 2025-07-29 21:10:22.411667 :B8B2D274:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 21:10:22.411667 :B8B2D275:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=140 time=2504198298 2025-07-29 21:10:22.411668 :B8B2D276:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 21:10:22.411668 :B8B2D277:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 21:10:22.411668 :B8B2D278:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 21:10:22.411669 :B8B2D279:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 21:10:22.411669 :B8B2D27A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 21:10:22.412157 :B8B2D27B: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-07-29 21:10:22.412204 :B8B2D283: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-07-29 21:20:23.278589 :B8B397EF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 21:20:23.533439 :B8B3983E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 21:20:23.528 2025-07-29 21:20:23.533441 :B8B3983F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16104 pso_num=22 pso_serial#=105 2025-07-29 21:20:23.533578 :B8B39840:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16104 osp_idx=0 osp_ver=463257 osp_pg=0 (spawn #435733) 2025-07-29 21:20:23.533578 :B8B39841:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 21:20:23.533579 :B8B39842:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=141 time=2504799423 2025-07-29 21:20:23.533579 :B8B39843:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 21:20:23.533580 :B8B39844:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 21:20:23.533580 :B8B39845:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 21:20:23.533580 :B8B39846:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 21:20:23.533581 :B8B39847:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 21:20:23.534067 :B8B39848: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-07-29 21:20:23.534114 :B8B39851: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-07-29 21:30:24.325094 :B8B45C4D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 21:30:24.673637 :B8B45C8C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 21:30:24.669 2025-07-29 21:30:24.673639 :B8B45C8D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6364 pso_num=22 pso_serial#=106 2025-07-29 21:30:24.673774 :B8B45C8E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6364 osp_idx=0 osp_ver=463258 osp_pg=0 (spawn #435757) 2025-07-29 21:30:24.673774 :B8B45C8F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 21:30:24.673775 :B8B45C90:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=142 time=2505400564 2025-07-29 21:30:24.673775 :B8B45C91:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 21:30:24.673775 :B8B45C92:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 21:30:24.673776 :B8B45C93:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 21:30:24.673776 :B8B45C94:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 21:30:24.673776 :B8B45C95:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 21:30:24.674263 :B8B45C96: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-07-29 21:30:24.674310 :B8B45CA0: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-07-29 21:40:25.536526 :B8B51F9D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-29 21:40:25.854735 :B8B51FDE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-29 21:40:25.848 2025-07-29 21:40:25.854737 :B8B51FDF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4120 pso_num=22 pso_serial#=107 2025-07-29 21:40:25.854895 :B8B51FE0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4120 osp_idx=0 osp_ver=463259 osp_pg=0 (spawn #435778) 2025-07-29 21:40:25.854896 :B8B51FE1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-29 21:40:25.854896 :B8B51FE2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=143 time=2506001751 2025-07-29 21:40:25.854897 :B8B51FE3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-29 21:40:25.854898 :B8B51FE4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-29 21:40:25.854898 :B8B51FE5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-29 21:40:25.854899 :B8B51FE6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-29 21:40:25.854899 :B8B51FE7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-29 21:40:25.855492 :B8B51FE8: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-07-29 21:40:25.855566 :B8B51FF2: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-07-29 21:41:50.774310 :B8B53C07: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-07-29 21:50:26.974865 :B8B5E3EF: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 4120, W000)