Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601100642\orcl_w000_12360_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:37193M/63366M, Ph+PgF:44086M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12360, image: ORACLE.EXE (W000) *** 2025-06-01 10:06:42.897 *** SESSION ID:(177.17169) 2025-06-01 10:06:42.897 *** 2025-06-01 10:06:42.897 Process diagnostic dump for ORACLE.EXE (W000), OS id=12360, pid: 22, proc_ser: 197, sid: 177, sess_ser: 17169 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=1.384615 sec, exc=1.384615 sec, total=1.384615 sec wait times: max=5.000000 sec, heur=2 min 11 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.000033 sec since current wait 0: 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.003324 sec, exc=5.003324 sec, total=5.003324 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.000308 sec, exc=5.000308 sec, total=5.000308 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.008716 sec, exc=5.008716 sec, total=5.008716 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.004270 sec, exc=5.004270 sec, total=5.004270 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=5.014764 sec, exc=5.014764 sec, total=5.014764 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=5.014737 sec, exc=5.014737 sec, total=5.014737 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=19 seq_num=20 snap_id=1 wait times: snap=5.010145 sec, exc=5.010145 sec, total=5.010145 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=18 seq_num=19 snap_id=1 wait times: snap=5.015026 sec, exc=5.015026 sec, total=5.015026 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=5.015193 sec, exc=5.015193 sec, total=5.015193 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 177 serial 17169 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 10:04:42 - 10:06:42] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-01 10:06:42.897 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12360, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-01 07:34:10.899502 :9EC7F4DE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 07:34:10.899502 :9EC7F4DF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 07:34:10.899997 :9EC7F4E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 07:34:10.900051 :9EC7F4E6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 07:44:11.828862 :9EC8BA5E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 07:44:12.203137 :9EC8BA9E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 07:44:12.198 2025-06-01 07:44:12.203138 :9EC8BA9F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10584 pso_num=22 pso_serial#=184 2025-06-01 07:44:12.203320 :9EC8BAA0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10584 osp_idx=0 osp_ver=254236 osp_pg=0 (spawn #240509) 2025-06-01 07:44:12.203321 :9EC8BAA1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 07:44:12.203321 :9EC8BAA2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=135 time=1739610844 2025-06-01 07:44:12.203322 :9EC8BAA3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 07:44:12.203323 :9EC8BAA4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 07:44:12.203323 :9EC8BAA5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 07:44:12.203324 :9EC8BAA6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 07:44:12.203324 :9EC8BAA7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 07:44:12.203869 :9EC8BAA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 07:44:12.203916 :9EC8BAAF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 07:54:13.210337 :9EC980D5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 07:54:13.496461 :9EC9810D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 07:54:13.492 2025-06-01 07:54:13.496463 :9EC9810E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14424 pso_num=22 pso_serial#=185 2025-06-01 07:54:13.496609 :9EC9810F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14424 osp_idx=0 osp_ver=254237 osp_pg=0 (spawn #240532) 2025-06-01 07:54:13.496610 :9EC98110:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 07:54:13.496610 :9EC98111:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=1740212141 2025-06-01 07:54:13.496611 :9EC98112:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 07:54:13.496611 :9EC98113:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 07:54:13.496611 :9EC98114:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 07:54:13.496612 :9EC98115:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 07:54:13.496612 :9EC98116:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 07:54:13.497110 :9EC98117:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 07:54:13.497154 :9EC9811F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 08:04:14.464181 :9ECA490A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 08:04:14.797189 :9ECA4950:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 08:04:14.792 2025-06-01 08:04:14.797191 :9ECA4951:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14396 pso_num=22 pso_serial#=186 2025-06-01 08:04:14.797327 :9ECA4952:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14396 osp_idx=0 osp_ver=254238 osp_pg=0 (spawn #240557) 2025-06-01 08:04:14.797328 :9ECA4953:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 08:04:14.797328 :9ECA4954:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=137 time=1740813438 2025-06-01 08:04:14.797328 :9ECA4955:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 08:04:14.797329 :9ECA4956:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 08:04:14.797329 :9ECA4957:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 08:04:14.797330 :9ECA4958:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 08:04:14.797330 :9ECA4959:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 08:04:14.797855 :9ECA495A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 08:04:14.797911 :9ECA4963:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 08:14:15.675898 :9ECB1880:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 08:14:16.118128 :9ECB18E4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 08:14:16.113 2025-06-01 08:14:16.118130 :9ECB18E5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14864 pso_num=22 pso_serial#=187 2025-06-01 08:14:16.118285 :9ECB18E6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14864 osp_idx=0 osp_ver=254239 osp_pg=0 (spawn #240580) 2025-06-01 08:14:16.118286 :9ECB18E7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 08:14:16.118287 :9ECB18E8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=138 time=1741414766 2025-06-01 08:14:16.118288 :9ECB18E9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 08:14:16.118288 :9ECB18EA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 08:14:16.118289 :9ECB18EB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 08:14:16.118289 :9ECB18EC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 08:14:16.118290 :9ECB18ED:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 08:14:16.118836 :9ECB18EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 08:14:16.118887 :9ECB18F6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 08:24:17.000776 :9ECC1522:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 08:24:17.380535 :9ECC1568:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 08:24:17.375 2025-06-01 08:24:17.380537 :9ECC1569:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13392 pso_num=22 pso_serial#=188 2025-06-01 08:24:17.380680 :9ECC156A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13392 osp_idx=0 osp_ver=254240 osp_pg=0 (spawn #240608) 2025-06-01 08:24:17.380681 :9ECC156B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 08:24:17.380681 :9ECC156C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=139 time=1742016032 2025-06-01 08:24:17.380682 :9ECC156D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 08:24:17.380682 :9ECC156E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 08:24:17.380682 :9ECC156F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 08:24:17.380683 :9ECC1570:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 08:24:17.380683 :9ECC1571:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 08:24:17.381178 :9ECC1572:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 08:24:17.381226 :9ECC157A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 08:34:18.236845 :9ECCDAB6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 08:34:18.788569 :9ECCDAF1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 08:34:18.784 2025-06-01 08:34:18.788571 :9ECCDAF2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13532 pso_num=22 pso_serial#=189 2025-06-01 08:34:18.788708 :9ECCDAF3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13532 osp_idx=0 osp_ver=254241 osp_pg=0 (spawn #240630) 2025-06-01 08:34:18.788708 :9ECCDAF4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 08:34:18.788709 :9ECCDAF5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=140 time=1742617438 2025-06-01 08:34:18.788709 :9ECCDAF6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 08:34:18.788710 :9ECCDAF7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 08:34:18.788710 :9ECCDAF8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 08:34:18.788710 :9ECCDAF9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 08:34:18.788711 :9ECCDAFA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 08:34:18.789183 :9ECCDAFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 08:34:18.789228 :9ECCDB03:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 08:44:19.721583 :9ECDA1CB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 08:44:20.257868 :9ECDA204:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 08:44:20.253 2025-06-01 08:44:20.257870 :9ECDA205:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12796 pso_num=22 pso_serial#=190 2025-06-01 08:44:20.258017 :9ECDA206:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12796 osp_idx=0 osp_ver=254242 osp_pg=0 (spawn #240653) 2025-06-01 08:44:20.258018 :9ECDA207:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 08:44:20.258018 :9ECDA208:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=141 time=1743218907 2025-06-01 08:44:20.258019 :9ECDA209:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 08:44:20.258019 :9ECDA20A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 08:44:20.258020 :9ECDA20B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 08:44:20.258020 :9ECDA20C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 08:44:20.258020 :9ECDA20D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 08:44:20.258532 :9ECDA20E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 08:44:20.258579 :9ECDA216:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 08:54:21.195458 :9ECE66E4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 08:54:21.653433 :9ECE672A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 08:54:21.649 2025-06-01 08:54:21.653435 :9ECE672B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14968 pso_num=22 pso_serial#=191 2025-06-01 08:54:21.653573 :9ECE672C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14968 osp_idx=0 osp_ver=254243 osp_pg=0 (spawn #240676) 2025-06-01 08:54:21.653574 :9ECE672D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 08:54:21.653575 :9ECE672E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=142 time=1743820297 2025-06-01 08:54:21.653575 :9ECE672F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 08:54:21.653575 :9ECE6730:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 08:54:21.653576 :9ECE6731:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 08:54:21.653576 :9ECE6732:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 08:54:21.653577 :9ECE6733:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 08:54:21.654055 :9ECE6734:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 08:54:21.654102 :9ECE673C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 09:04:22.575780 :9ECF3A70:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 09:04:23.064892 :9ECF3AA5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 09:04:23.060 2025-06-01 09:04:23.064895 :9ECF3AA6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12524 pso_num=22 pso_serial#=192 2025-06-01 09:04:23.065037 :9ECF3AA7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12524 osp_idx=0 osp_ver=254244 osp_pg=0 (spawn #240701) 2025-06-01 09:04:23.065038 :9ECF3AA8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 09:04:23.065038 :9ECF3AA9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=143 time=1744421719 2025-06-01 09:04:23.065039 :9ECF3AAA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 09:04:23.065039 :9ECF3AAB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 09:04:23.065040 :9ECF3AAC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 09:04:23.065040 :9ECF3AAD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 09:04:23.065040 :9ECF3AAE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 09:04:23.065553 :9ECF3AAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 09:04:23.065599 :9ECF3AB6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 09:14:23.919455 :9ED006C4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 09:14:24.439778 :9ED006F9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 09:14:24.435 2025-06-01 09:14:24.439780 :9ED006FA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15064 pso_num=22 pso_serial#=193 2025-06-01 09:14:24.439914 :9ED006FB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15064 osp_idx=0 osp_ver=254245 osp_pg=0 (spawn #240724) 2025-06-01 09:14:24.439914 :9ED006FC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 09:14:24.439915 :9ED006FD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=144 time=1745023094 2025-06-01 09:14:24.439915 :9ED006FE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 09:14:24.439915 :9ED006FF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 09:14:24.439916 :9ED00700:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 09:14:24.439916 :9ED00701:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 09:14:24.439916 :9ED00702:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 09:14:24.440464 :9ED00703:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 09:14:24.440523 :9ED0070C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 09:20:45.831839 :9ED084A8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 09:24:25.921174 :9ED0CD9A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-01 09:34:26.889270 :9ED19332:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 09:34:27.268971 :9ED1936B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 09:34:27.264 2025-06-01 09:34:27.268973 :9ED1936C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3448 pso_num=22 pso_serial#=194 2025-06-01 09:34:27.269106 :9ED1936D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=3448 osp_idx=0 osp_ver=254246 osp_pg=0 (spawn #240766) 2025-06-01 09:34:27.269107 :9ED1936E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 09:34:27.269107 :9ED1936F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=145 time=1746225922 2025-06-01 09:34:27.269108 :9ED19370:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 09:34:27.269108 :9ED19371:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 09:34:27.269108 :9ED19372:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 09:34:27.269109 :9ED19373:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 09:34:27.269109 :9ED19374:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 09:34:27.269606 :9ED19375:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 09:34:27.271104 :9ED1937F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 09:44:28.241578 :9ED25947:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 09:44:28.701556 :9ED2597D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 09:44:28.696 2025-06-01 09:44:28.701558 :9ED2597E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13572 pso_num=22 pso_serial#=195 2025-06-01 09:44:28.701698 :9ED2597F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13572 osp_idx=0 osp_ver=254247 osp_pg=0 (spawn #240789) 2025-06-01 09:44:28.701699 :9ED25980:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 09:44:28.701699 :9ED25981:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=146 time=1746827344 2025-06-01 09:44:28.701700 :9ED25982:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 09:44:28.701700 :9ED25983:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 09:44:28.701701 :9ED25984:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 09:44:28.701701 :9ED25985:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 09:44:28.701701 :9ED25986:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 09:44:28.702184 :9ED25987:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 09:44:28.702229 :9ED25991:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 09:54:29.605957 :9ED31E74:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 09:54:30.017076 :9ED31EAE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 09:54:30.012 2025-06-01 09:54:30.017078 :9ED31EAF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10700 pso_num=22 pso_serial#=196 2025-06-01 09:54:30.017215 :9ED31EB0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10700 osp_idx=0 osp_ver=254248 osp_pg=0 (spawn #240812) 2025-06-01 09:54:30.017215 :9ED31EB1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 09:54:30.017216 :9ED31EB2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=147 time=1747428657 2025-06-01 09:54:30.017216 :9ED31EB3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 09:54:30.017217 :9ED31EB4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 09:54:30.017217 :9ED31EB5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 09:54:30.017218 :9ED31EB6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 09:54:30.017218 :9ED31EB7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 09:54:30.017711 :9ED31EB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 09:54:30.017756 :9ED31EBF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-01 10:04:30.948474 :9ED3E609:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-01 10:04:31.307011 :9ED3E643:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-01 10:04:31.302 2025-06-01 10:04:31.307013 :9ED3E644:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12360 pso_num=22 pso_serial#=197 2025-06-01 10:04:31.307144 :9ED3E645:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12360 osp_idx=0 osp_ver=254249 osp_pg=0 (spawn #240837) 2025-06-01 10:04:31.307144 :9ED3E646:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-01 10:04:31.307145 :9ED3E647:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=148 time=1748029954 2025-06-01 10:04:31.307145 :9ED3E648:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 10:04:31.307146 :9ED3E649:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 10:04:31.307146 :9ED3E64A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 10:04:31.307146 :9ED3E64B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 10:04:31.307146 :9ED3E64C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 10:04:31.307682 :9ED3E64D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-01 10:04:31.307734 :9ED3E654:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 12360, W000)