Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915082514\orcl_w000_16340_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:34735M/63366M, Ph+PgF:39315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 16340, image: ORACLE.EXE (W000) *** 2025-09-15 08:25:14.228 *** SESSION ID:(177.3627) 2025-09-15 08:25:14.228 *** 2025-09-15 08:25:14.228 Process diagnostic dump for ORACLE.EXE (W000), OS id=16340, pid: 22, proc_ser: 203, sid: 177, sess_ser: 3627 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=165 seq_num=166 snap_id=1 wait times: snap=1.537557 sec, exc=1.537557 sec, total=1.537557 sec wait times: max=5.000000 sec, heur=7 min 3 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=164 seq_num=165 snap_id=1 wait times: snap=5.014749 sec, exc=5.014749 sec, total=5.014749 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=163 seq_num=164 snap_id=1 wait times: snap=5.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=162 seq_num=163 snap_id=1 wait times: snap=5.015242 sec, exc=5.015242 sec, total=5.015242 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=161 seq_num=162 snap_id=1 wait times: snap=5.003212 sec, exc=5.003212 sec, total=5.003212 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=160 seq_num=161 snap_id=1 wait times: snap=5.000624 sec, exc=5.000624 sec, total=5.000624 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=159 seq_num=160 snap_id=1 wait times: snap=5.012671 sec, exc=5.012671 sec, total=5.012671 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=158 seq_num=159 snap_id=1 wait times: snap=5.012095 sec, exc=5.012095 sec, total=5.012095 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=157 seq_num=158 snap_id=1 wait times: snap=5.004634 sec, exc=5.004634 sec, total=5.004634 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=156 seq_num=157 snap_id=1 wait times: snap=5.014743 sec, exc=5.014743 sec, total=5.014743 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=155 seq_num=156 snap_id=1 wait times: snap=5.007216 sec, exc=5.007216 sec, total=5.007216 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time Sampled Session History of session 177 serial 3627 --------------------------------------------------- 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, 08:23:14 - 08:25:14] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-15 08:25:14.228 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 16340, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-15 05:41:21.124142 :CD9D81BC: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-09-15 05:51:21.988124 :CD9E4A02:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 05:51:22.508665 :CD9E4A62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 05:51:22.504 2025-09-15 05:51:22.508667 :CD9E4A63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24992 pso_num=22 pso_serial#=190 2025-09-15 05:51:22.508810 :CD9E4A64:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24992 osp_idx=0 osp_ver=609457 osp_pg=0 (spawn #593603) 2025-09-15 05:51:22.508810 :CD9E4A65:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 05:51:22.508811 :CD9E4A66:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=134 time=2301133815 2025-09-15 05:51:22.508811 :CD9E4A67:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 05:51:22.508812 :CD9E4A68:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 05:51:22.508812 :CD9E4A69:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 05:51:22.508813 :CD9E4A6A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 05:51:22.508813 :CD9E4A6B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 05:51:22.509300 :CD9E4A6C: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-09-15 05:51:22.509346 :CD9E4A74: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-09-15 06:01:23.437269 :CD9F13F0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 06:01:23.764561 :CD9F143F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 06:01:23.759 2025-09-15 06:01:23.764562 :CD9F1440:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23664 pso_num=22 pso_serial#=191 2025-09-15 06:01:23.764702 :CD9F1441:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23664 osp_idx=0 osp_ver=609458 osp_pg=0 (spawn #593628) 2025-09-15 06:01:23.764703 :CD9F1442:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:01:23.764703 :CD9F1443:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=135 time=2301735065 2025-09-15 06:01:23.764704 :CD9F1444:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:01:23.764704 :CD9F1445:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:01:23.764705 :CD9F1446:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:01:23.764705 :CD9F1447:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:01:23.764705 :CD9F1448:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:01:23.765210 :CD9F1449: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-09-15 06:01:23.765256 :CD9F1451: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-09-15 06:11:24.711953 :CD9FE5FA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 06:11:25.154241 :CD9FE652:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 06:11:25.149 2025-09-15 06:11:25.154243 :CD9FE653:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24420 pso_num=22 pso_serial#=192 2025-09-15 06:11:25.154378 :CD9FE654:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24420 osp_idx=0 osp_ver=609459 osp_pg=0 (spawn #593651) 2025-09-15 06:11:25.154379 :CD9FE655:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:11:25.154379 :CD9FE656:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=2302336455 2025-09-15 06:11:25.154380 :CD9FE657:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:11:25.154380 :CD9FE658:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:11:25.154380 :CD9FE659:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:11:25.154380 :CD9FE65A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:11:25.154381 :CD9FE65B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:11:25.154928 :CD9FE65C: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-09-15 06:11:25.154972 :CD9FE664: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-09-15 06:18:10.271204 :CDA06C03: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-09-15 06:21:26.513065 :CDA0AD24: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-09-15 06:31:27.354796 :CDA1752D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 06:31:27.953272 :CDA17584:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 06:31:27.948 2025-09-15 06:31:27.953274 :CDA17585:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24268 pso_num=22 pso_serial#=193 2025-09-15 06:31:27.953408 :CDA17586:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24268 osp_idx=0 osp_ver=609460 osp_pg=0 (spawn #593696) 2025-09-15 06:31:27.953409 :CDA17587:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:31:27.953409 :CDA17588:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=137 time=2303539252 2025-09-15 06:31:27.953410 :CDA17589:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:31:27.953410 :CDA1758A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:31:27.953410 :CDA1758B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:31:27.953411 :CDA1758C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:31:27.953411 :CDA1758D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:31:27.953895 :CDA1758E: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-09-15 06:31:27.953942 :CDA17595: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-09-15 06:41:28.862357 :CDA23DC2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 06:41:29.226540 :CDA23E0C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 06:41:29.221 2025-09-15 06:41:29.226542 :CDA23E0D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24340 pso_num=22 pso_serial#=194 2025-09-15 06:41:29.226675 :CDA23E0E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24340 osp_idx=0 osp_ver=609461 osp_pg=0 (spawn #593718) 2025-09-15 06:41:29.226676 :CDA23E0F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:41:29.226676 :CDA23E10:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=138 time=2304140533 2025-09-15 06:41:29.226676 :CDA23E11:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:41:29.226677 :CDA23E12:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:41:29.226677 :CDA23E13:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:41:29.226677 :CDA23E14:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:41:29.226678 :CDA23E15:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:41:29.227211 :CDA23E16: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-09-15 06:41:29.227258 :CDA23E1D: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-09-15 06:51:30.135540 :CDA3057A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 06:51:30.562171 :CDA305C5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 06:51:30.557 2025-09-15 06:51:30.562173 :CDA305C6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23352 pso_num=22 pso_serial#=195 2025-09-15 06:51:30.562304 :CDA305C7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23352 osp_idx=0 osp_ver=609462 osp_pg=0 (spawn #593741) 2025-09-15 06:51:30.562305 :CDA305C8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 06:51:30.562305 :CDA305C9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=139 time=2304741862 2025-09-15 06:51:30.562306 :CDA305CA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 06:51:30.562306 :CDA305CB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 06:51:30.562307 :CDA305CC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 06:51:30.562307 :CDA305CD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 06:51:30.562307 :CDA305CE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 06:51:30.562804 :CDA305CF: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-09-15 06:51:30.562848 :CDA305D8: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-09-15 07:01:31.565428 :CDA3CFE4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 07:01:31.867117 :CDA3D02F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 07:01:31.862 2025-09-15 07:01:31.867119 :CDA3D030:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24452 pso_num=22 pso_serial#=196 2025-09-15 07:01:31.867258 :CDA3D031:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24452 osp_idx=0 osp_ver=609463 osp_pg=0 (spawn #593766) 2025-09-15 07:01:31.867258 :CDA3D032:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:01:31.867259 :CDA3D033:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=140 time=2305343174 2025-09-15 07:01:31.867259 :CDA3D034:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:01:31.867259 :CDA3D035:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:01:31.867260 :CDA3D036:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:01:31.867260 :CDA3D037:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:01:31.867260 :CDA3D038:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:01:31.867746 :CDA3D039: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-09-15 07:01:31.867792 :CDA3D040: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-09-15 07:11:32.759943 :CDA49E31:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 07:11:33.140028 :CDA49E94:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 07:11:33.134 2025-09-15 07:11:33.140030 :CDA49E95:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25096 pso_num=22 pso_serial#=197 2025-09-15 07:11:33.140169 :CDA49E96:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25096 osp_idx=0 osp_ver=609464 osp_pg=0 (spawn #593789) 2025-09-15 07:11:33.140170 :CDA49E97:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:11:33.140170 :CDA49E98:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=141 time=2305944440 2025-09-15 07:11:33.140170 :CDA49E99:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:11:33.140171 :CDA49E9A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:11:33.140171 :CDA49E9B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:11:33.140171 :CDA49E9C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:11:33.140172 :CDA49E9D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:11:33.140727 :CDA49E9E: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-09-15 07:11:33.140779 :CDA49EA4: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-09-15 07:21:34.017084 :CDA568B5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 07:21:34.490459 :CDA56927:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 07:21:34.485 2025-09-15 07:21:34.490463 :CDA56928:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25268 pso_num=22 pso_serial#=198 2025-09-15 07:21:34.490607 :CDA56929:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25268 osp_idx=0 osp_ver=609465 osp_pg=0 (spawn #593812) 2025-09-15 07:21:34.490608 :CDA5692A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:21:34.490608 :CDA5692B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=142 time=2306545799 2025-09-15 07:21:34.490609 :CDA5692C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:21:34.490609 :CDA5692D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:21:34.490610 :CDA5692E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:21:34.490610 :CDA5692F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:21:34.490610 :CDA56930:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:21:34.491108 :CDA56931: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-09-15 07:21:34.491154 :CDA56939: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-09-15 07:31:35.392605 :CDA630BC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 07:31:35.773498 :CDA63138:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 07:31:35.769 2025-09-15 07:31:35.773500 :CDA63139:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21748 pso_num=22 pso_serial#=199 2025-09-15 07:31:35.773637 :CDA6313A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21748 osp_idx=0 osp_ver=609466 osp_pg=0 (spawn #593835) 2025-09-15 07:31:35.773637 :CDA6313B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:31:35.773638 :CDA6313C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=143 time=2307147080 2025-09-15 07:31:35.773638 :CDA6313D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:31:35.773638 :CDA6313E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:31:35.773638 :CDA6313F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:31:35.773639 :CDA63140:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:31:35.773639 :CDA63141:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:31:35.774129 :CDA63142: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-09-15 07:31:35.774186 :CDA63149: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-09-15 07:41:36.716127 :CDA6F943:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 07:41:37.064773 :CDA6F993:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 07:41:37.059 2025-09-15 07:41:37.064776 :CDA6F994:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24816 pso_num=22 pso_serial#=200 2025-09-15 07:41:37.064919 :CDA6F995:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24816 osp_idx=0 osp_ver=609467 osp_pg=0 (spawn #593857) 2025-09-15 07:41:37.064920 :CDA6F996:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:41:37.064920 :CDA6F997:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=144 time=2307748362 2025-09-15 07:41:37.064920 :CDA6F998:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:41:37.064921 :CDA6F999:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:41:37.064921 :CDA6F99A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:41:37.064921 :CDA6F99B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:41:37.064922 :CDA6F99C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:41:37.065425 :CDA6F99D: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-09-15 07:41:37.065480 :CDA6F9A5: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-09-15 07:51:38.003156 :CDA7C0CE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 07:51:38.413957 :CDA7C125:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 07:51:38.409 2025-09-15 07:51:38.413959 :CDA7C126:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24744 pso_num=22 pso_serial#=201 2025-09-15 07:51:38.414095 :CDA7C127:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24744 osp_idx=0 osp_ver=609468 osp_pg=0 (spawn #593880) 2025-09-15 07:51:38.414095 :CDA7C128:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 07:51:38.414096 :CDA7C129:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=145 time=2308349721 2025-09-15 07:51:38.414096 :CDA7C12A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 07:51:38.414097 :CDA7C12B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 07:51:38.414097 :CDA7C12C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 07:51:38.414097 :CDA7C12D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 07:51:38.414098 :CDA7C12E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 07:51:38.414580 :CDA7C12F: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-09-15 07:51:38.414624 :CDA7C137: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-09-15 08:01:39.353579 :CDA88BAE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 08:01:39.717965 :CDA88C01:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 08:01:39.713 2025-09-15 08:01:39.717968 :CDA88C02:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22904 pso_num=22 pso_serial#=202 2025-09-15 08:01:39.718107 :CDA88C03:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22904 osp_idx=0 osp_ver=609469 osp_pg=0 (spawn #593905) 2025-09-15 08:01:39.718107 :CDA88C04:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:01:39.718108 :CDA88C05:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=146 time=2308951018 2025-09-15 08:01:39.718109 :CDA88C06:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:01:39.718109 :CDA88C07:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:01:39.718109 :CDA88C08:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:01:39.718109 :CDA88C09:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:01:39.718110 :CDA88C0A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:01:39.718598 :CDA88C0B: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-09-15 08:01:39.718644 :CDA88C12: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-09-15 08:11:40.633861 :CDA95A0F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 08:11:41.044803 :CDA95A62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 08:11:41.040 2025-09-15 08:11:41.044806 :CDA95A63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16340 pso_num=22 pso_serial#=203 2025-09-15 08:11:41.044949 :CDA95A64:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16340 osp_idx=0 osp_ver=609470 osp_pg=0 (spawn #593928) 2025-09-15 08:11:41.044950 :CDA95A65:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-15 08:11:41.044950 :CDA95A66:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=147 time=2309552346 2025-09-15 08:11:41.044951 :CDA95A67:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 08:11:41.044951 :CDA95A68:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 08:11:41.044951 :CDA95A69:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 08:11:41.044952 :CDA95A6A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 08:11:41.044952 :CDA95A6B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 08:11:41.045447 :CDA95A6C: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-09-15 08:11:41.045490 :CDA95A74: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-09-15 08:18:11.116114 :CDA9DE3B: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-09-15 08:21:42.414615 :CDAA2428: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: 16340, W000)