Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250720180631\orcl_w000_17544_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:36137M/63366M, Ph+PgF:42024M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 17544, image: ORACLE.EXE (W000) *** 2025-07-20 18:06:31.166 *** SESSION ID:(209.59045) 2025-07-20 18:06:31.166 *** 2025-07-20 18:06:31.166 Process diagnostic dump for ORACLE.EXE (W000), OS id=17544, pid: 26, proc_ser: 70, sid: 209, sess_ser: 59045 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.478400 sec, exc=0.478400 sec, total=0.478400 sec wait times: max=5.000000 sec, heur=2 min 55 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.000029 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.004493 sec, exc=5.004493 sec, total=5.004493 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.010166 sec, exc=5.010166 sec, total=5.010166 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.014745 sec, exc=5.014745 sec, total=5.014745 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=32 seq_num=33 snap_id=1 wait times: snap=5.015252 sec, exc=5.015252 sec, total=5.015252 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: 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.013340 sec, exc=5.013340 sec, total=5.013340 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=30 seq_num=31 snap_id=1 wait times: snap=5.010440 sec, exc=5.010440 sec, total=5.010440 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 6: 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.014731 sec, exc=5.014731 sec, total=5.014731 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=28 seq_num=29 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: 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.002181 sec, exc=5.002181 sec, total=5.002181 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: 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.007247 sec, exc=5.007247 sec, total=5.007247 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 209 serial 59045 --------------------------------------------------- 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, 18:04:31 - 18:06:31] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-20 18:06:31.166 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 17544, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-20 15:23:14.387146 :B49BB9B1:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 15:25:07.964818 :B49BDEA8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 15:33:15.705453 :B49C82EA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 15:43:16.647711 :B49D4B6A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 15:43:17.011510 :B49D4B9B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 15:43:17.007 2025-07-20 15:43:17.011512 :B49D4B9C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6652 pso_num=26 pso_serial#=57 2025-07-20 15:43:17.011644 :B49D4B9D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6652 osp_idx=24 osp_ver=650307 osp_pg=0 (spawn #404962) 2025-07-20 15:43:17.011644 :B49D4B9E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 15:43:17.011645 :B49D4B9F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=163 time=1706972908 2025-07-20 15:43:17.011645 :B49D4BA0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 15:43:17.011645 :B49D4BA1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 15:43:17.011646 :B49D4BA2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 15:43:17.011646 :B49D4BA3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 15:43:17.011646 :B49D4BA4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 15:43:17.012179 :B49D4BA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 15:43:17.012224 :B49D4BAE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 15:53:17.929692 :B49E13B4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 15:53:18.278039 :B49E13FC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 15:53:18.273 2025-07-20 15:53:18.278041 :B49E13FD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18904 pso_num=26 pso_serial#=58 2025-07-20 15:53:18.278185 :B49E13FE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18904 osp_idx=24 osp_ver=650308 osp_pg=0 (spawn #404985) 2025-07-20 15:53:18.278186 :B49E13FF:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 15:53:18.278186 :B49E1400:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=164 time=1707574173 2025-07-20 15:53:18.278186 :B49E1401:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 15:53:18.278187 :B49E1402:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 15:53:18.278187 :B49E1403:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 15:53:18.278187 :B49E1404:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 15:53:18.278188 :B49E1405:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 15:53:18.278677 :B49E1406:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 15:53:18.278718 :B49E140D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 16:03:19.185280 :B49EDE13:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 16:03:19.550045 :B49EDE8D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 16:03:19.545 2025-07-20 16:03:19.550047 :B49EDE8E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6992 pso_num=26 pso_serial#=59 2025-07-20 16:03:19.550185 :B49EDE8F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6992 osp_idx=24 osp_ver=650309 osp_pg=0 (spawn #405011) 2025-07-20 16:03:19.550186 :B49EDE90:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 16:03:19.550186 :B49EDE91:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=165 time=1708175439 2025-07-20 16:03:19.550187 :B49EDE92:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 16:03:19.550187 :B49EDE93:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 16:03:19.550187 :B49EDE94:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 16:03:19.550188 :B49EDE95:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 16:03:19.550188 :B49EDE96:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 16:03:19.550693 :B49EDE97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 16:03:19.550742 :B49EDE9E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 16:13:20.507152 :B49FAE45:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 16:13:20.804134 :B49FAEB1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 16:13:20.799 2025-07-20 16:13:20.804136 :B49FAEB2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14620 pso_num=26 pso_serial#=60 2025-07-20 16:13:20.804278 :B49FAEB3:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14620 osp_idx=24 osp_ver=650310 osp_pg=0 (spawn #405034) 2025-07-20 16:13:20.804278 :B49FAEB4:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 16:13:20.804279 :B49FAEB5:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=166 time=1708776704 2025-07-20 16:13:20.804279 :B49FAEB6:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 16:13:20.804279 :B49FAEB7:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 16:13:20.804280 :B49FAEB8:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 16:13:20.804280 :B49FAEB9:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 16:13:20.804280 :B49FAEBA:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 16:13:20.804793 :B49FAEBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 16:13:20.804837 :B49FAEC2:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 16:23:21.871627 :B4A07617:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 16:23:22.173011 :B4A0765E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 16:23:22.168 2025-07-20 16:23:22.173013 :B4A0765F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18872 pso_num=26 pso_serial#=61 2025-07-20 16:23:22.173148 :B4A07660:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18872 osp_idx=24 osp_ver=650311 osp_pg=0 (spawn #405057) 2025-07-20 16:23:22.173149 :B4A07661:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 16:23:22.173149 :B4A07662:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=167 time=1709378064 2025-07-20 16:23:22.173149 :B4A07663:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 16:23:22.173150 :B4A07664:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 16:23:22.173150 :B4A07665:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 16:23:22.173151 :B4A07666:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 16:23:22.173151 :B4A07667:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 16:23:22.173712 :B4A07668:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 16:23:22.173756 :B4A07671:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 16:33:23.043895 :B4A13DF9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 16:33:23.392048 :B4A13E3E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 16:33:23.387 2025-07-20 16:33:23.392050 :B4A13E3F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19164 pso_num=26 pso_serial#=62 2025-07-20 16:33:23.392182 :B4A13E40:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19164 osp_idx=24 osp_ver=650312 osp_pg=0 (spawn #405079) 2025-07-20 16:33:23.392183 :B4A13E41:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 16:33:23.392183 :B4A13E42:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=168 time=1709979283 2025-07-20 16:33:23.392184 :B4A13E43:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 16:33:23.392184 :B4A13E44:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 16:33:23.392184 :B4A13E45:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 16:33:23.392184 :B4A13E46:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 16:33:23.392184 :B4A13E47:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 16:33:23.392682 :B4A13E48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 16:33:23.392729 :B4A13E51:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 16:43:24.347844 :B4A2051E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 16:43:24.665299 :B4A2054F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 16:43:24.659 2025-07-20 16:43:24.665301 :B4A20550:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18740 pso_num=26 pso_serial#=63 2025-07-20 16:43:24.665452 :B4A20551:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18740 osp_idx=24 osp_ver=650313 osp_pg=0 (spawn #405102) 2025-07-20 16:43:24.665453 :B4A20552:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 16:43:24.665453 :B4A20553:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=169 time=1710580564 2025-07-20 16:43:24.665454 :B4A20554:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 16:43:24.665454 :B4A20555:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 16:43:24.665454 :B4A20556:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 16:43:24.665455 :B4A20557:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 16:43:24.665455 :B4A20558:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 16:43:24.666001 :B4A20559:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 16:43:24.666059 :B4A2055F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 16:53:25.579545 :B4A2CCF9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 16:53:25.990514 :B4A2CD13:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 16:53:25.985 2025-07-20 16:53:25.990516 :B4A2CD14:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19012 pso_num=26 pso_serial#=64 2025-07-20 16:53:25.990650 :B4A2CD15:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19012 osp_idx=24 osp_ver=650314 osp_pg=0 (spawn #405125) 2025-07-20 16:53:25.990651 :B4A2CD16:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 16:53:25.990651 :B4A2CD17:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=170 time=1711181892 2025-07-20 16:53:25.990652 :B4A2CD18:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 16:53:25.990652 :B4A2CD19:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 16:53:25.990652 :B4A2CD1A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 16:53:25.990653 :B4A2CD1B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 16:53:25.990653 :B4A2CD1C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 16:53:25.991148 :B4A2CD1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 16:53:25.991193 :B4A2CD24:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:03:26.980000 :B4A39716:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 17:03:27.344119 :B4A3973C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 17:03:27.339 2025-07-20 17:03:27.344122 :B4A3973D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18700 pso_num=26 pso_serial#=65 2025-07-20 17:03:27.344260 :B4A3973E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18700 osp_idx=24 osp_ver=650315 osp_pg=0 (spawn #405150) 2025-07-20 17:03:27.344260 :B4A3973F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 17:03:27.344261 :B4A39740:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=171 time=1711783236 2025-07-20 17:03:27.344261 :B4A39741:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 17:03:27.344262 :B4A39742:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 17:03:27.344262 :B4A39743:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 17:03:27.344262 :B4A39744:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 17:03:27.344262 :B4A39745:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 17:03:27.344756 :B4A39746:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 17:03:27.344801 :B4A3974F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:13:28.334799 :B4A46975:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 17:13:28.693267 :B4A4699C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 17:13:28.688 2025-07-20 17:13:28.693269 :B4A4699D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12540 pso_num=26 pso_serial#=66 2025-07-20 17:13:28.693408 :B4A4699E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12540 osp_idx=24 osp_ver=650316 osp_pg=0 (spawn #405173) 2025-07-20 17:13:28.693409 :B4A4699F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 17:13:28.693409 :B4A469A0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=172 time=1712384579 2025-07-20 17:13:28.693410 :B4A469A1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 17:13:28.693410 :B4A469A2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 17:13:28.693410 :B4A469A3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 17:13:28.693411 :B4A469A4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 17:13:28.693411 :B4A469A5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 17:13:28.693928 :B4A469A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 17:13:28.693979 :B4A469AE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:23:29.646463 :B4A5323E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 17:23:29.995056 :B4A53264:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 17:23:29.990 2025-07-20 17:23:29.995058 :B4A53265:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15056 pso_num=26 pso_serial#=67 2025-07-20 17:23:29.995205 :B4A53266:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15056 osp_idx=24 osp_ver=650317 osp_pg=0 (spawn #405196) 2025-07-20 17:23:29.995206 :B4A53267:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 17:23:29.995206 :B4A53268:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=173 time=1712985892 2025-07-20 17:23:29.995206 :B4A53269:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 17:23:29.995207 :B4A5326A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 17:23:29.995207 :B4A5326B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 17:23:29.995207 :B4A5326C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 17:23:29.995208 :B4A5326D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 17:23:29.995715 :B4A5326E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 17:23:29.995760 :B4A53276:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:25:08.710691 :B4A552ED:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:33:31.272012 :B4A5FBB8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:43:32.175454 :B4A6C487:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 17:43:32.674432 :B4A6C4AD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 17:43:32.670 2025-07-20 17:43:32.674434 :B4A6C4AE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15404 pso_num=26 pso_serial#=68 2025-07-20 17:43:32.674571 :B4A6C4AF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15404 osp_idx=24 osp_ver=650318 osp_pg=0 (spawn #405240) 2025-07-20 17:43:32.674572 :B4A6C4B0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 17:43:32.674572 :B4A6C4B1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=174 time=1714188564 2025-07-20 17:43:32.674572 :B4A6C4B2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 17:43:32.674573 :B4A6C4B3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 17:43:32.674573 :B4A6C4B4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 17:43:32.674573 :B4A6C4B5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 17:43:32.674574 :B4A6C4B6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 17:43:32.675125 :B4A6C4B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 17:43:32.675185 :B4A6C4BD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 17:53:33.575064 :B4A78DA3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 17:53:34.095404 :B4A78DBD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 17:53:34.090 2025-07-20 17:53:34.095406 :B4A78DBE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15096 pso_num=26 pso_serial#=69 2025-07-20 17:53:34.095554 :B4A78DBF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15096 osp_idx=24 osp_ver=650319 osp_pg=0 (spawn #405263) 2025-07-20 17:53:34.095555 :B4A78DC0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 17:53:34.095555 :B4A78DC1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=175 time=1714789986 2025-07-20 17:53:34.095555 :B4A78DC2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 17:53:34.095556 :B4A78DC3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 17:53:34.095556 :B4A78DC4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 17:53:34.095556 :B4A78DC5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 17:53:34.095556 :B4A78DC6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 17:53:34.096056 :B4A78DC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 17:53:34.096099 :B4A78DCF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-20 18:03:35.061641 :B4A85782:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 18:03:35.425606 :B4A8579C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 18:03:35.421 2025-07-20 18:03:35.425608 :B4A8579D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17544 pso_num=26 pso_serial#=70 2025-07-20 18:03:35.425744 :B4A8579E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17544 osp_idx=24 osp_ver=650320 osp_pg=0 (spawn #405288) 2025-07-20 18:03:35.425745 :B4A8579F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 18:03:35.425746 :B4A857A0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=176 time=1715391314 2025-07-20 18:03:35.425746 :B4A857A1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 18:03:35.425746 :B4A857A2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 18:03:35.425747 :B4A857A3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 18:03:35.425747 :B4A857A4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 18:03:35.425747 :B4A857A5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 18:03:35.426227 :B4A857A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 18:03:35.426282 :B4A857B0:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 17544, W000)