Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913180427\orcl_w000_21868_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:34775M/63366M, Ph+PgF:39418M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 21868, image: ORACLE.EXE (W000) *** 2025-09-13 18:04:27.610 *** SESSION ID:(201.61267) 2025-09-13 18:04:27.610 *** 2025-09-13 18:04:27.610 Process diagnostic dump for ORACLE.EXE (W000), OS id=21868, pid: 25, proc_ser: 253, sid: 201, sess_ser: 61267 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=214 seq_num=215 snap_id=1 wait times: snap=3.340361 sec, exc=3.340361 sec, total=3.340361 sec wait times: max=5.000000 sec, heur=8 min 59 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.000041 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=213 seq_num=214 snap_id=1 wait times: snap=5.001246 sec, exc=5.001246 sec, total=5.001246 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000042 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=212 seq_num=213 snap_id=1 wait times: snap=5.000439 sec, exc=5.000439 sec, total=5.000439 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000072 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=211 seq_num=212 snap_id=1 wait times: snap=5.007085 sec, exc=5.007085 sec, total=5.007085 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000048 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=210 seq_num=211 snap_id=1 wait times: snap=5.012392 sec, exc=5.012392 sec, total=5.012392 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=209 seq_num=210 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=208 seq_num=209 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000033 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=207 seq_num=208 snap_id=1 wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=206 seq_num=207 snap_id=1 wait times: snap=5.008438 sec, exc=5.008438 sec, total=5.008438 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=205 seq_num=206 snap_id=1 wait times: snap=5.006739 sec, exc=5.006739 sec, total=5.006739 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=204 seq_num=205 snap_id=1 wait times: snap=5.007908 sec, exc=5.007908 sec, total=5.007908 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 201 serial 61267 --------------------------------------------------- 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:02:27 - 18:04:27] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 18:04:27.610 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 21868, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 15:16:29.658323 :CCE7BB15:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 15:26:30.453027 :CCE8846F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 15:26:30.936912 :CCE884B5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 15:26:30.931 2025-09-13 15:26:30.936914 :CCE884B6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23216 pso_num=25 pso_serial#=240 2025-09-13 15:26:30.937068 :CCE884B7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23216 osp_idx=23 osp_ver=294523 osp_pg=0 (spawn #588262) 2025-09-13 15:26:30.937068 :CCE884B8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 15:26:30.937069 :CCE884B9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=25 time=2162842237 2025-09-13 15:26:30.937069 :CCE884BA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 15:26:30.937070 :CCE884BB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 15:26:30.937070 :CCE884BC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 15:26:30.937071 :CCE884BD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 15:26:30.937071 :CCE884BE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 15:26:30.937588 :CCE884BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 15:26:30.937638 :CCE884C7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 15:36:31.728716 :CCE94E6F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 15:36:32.186815 :CCE94EC3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 15:36:32.182 2025-09-13 15:36:32.186817 :CCE94EC4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23720 pso_num=25 pso_serial#=241 2025-09-13 15:36:32.186964 :CCE94EC5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23720 osp_idx=23 osp_ver=294524 osp_pg=0 (spawn #588284) 2025-09-13 15:36:32.186965 :CCE94EC6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 15:36:32.186965 :CCE94EC7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=26 time=2163443487 2025-09-13 15:36:32.186965 :CCE94EC8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 15:36:32.186966 :CCE94EC9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 15:36:32.186966 :CCE94ECA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 15:36:32.186967 :CCE94ECB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 15:36:32.186967 :CCE94ECC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 15:36:32.187478 :CCE94ECD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 15:36:32.187526 :CCE94ED4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 15:46:32.943722 :CCEA15EA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 15:46:33.261040 :CCEA162C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 15:46:33.256 2025-09-13 15:46:33.261042 :CCEA162D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24652 pso_num=25 pso_serial#=242 2025-09-13 15:46:33.261190 :CCEA162E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24652 osp_idx=23 osp_ver=294525 osp_pg=0 (spawn #588307) 2025-09-13 15:46:33.261191 :CCEA162F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 15:46:33.261191 :CCEA1630:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=27 time=2164044565 2025-09-13 15:46:33.261191 :CCEA1631:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 15:46:33.261192 :CCEA1632:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 15:46:33.261192 :CCEA1633:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 15:46:33.261193 :CCEA1634:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 15:46:33.261193 :CCEA1635:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 15:46:33.261714 :CCEA1636:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 15:46:33.261765 :CCEA163C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 15:55:26.975960 :CCEAC633:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-13 15:56:34.421369 :CCEADC4A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-13 16:06:35.303433 :CCEBAB78:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 16:06:35.495553 :CCEBABBA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 16:06:35.491 2025-09-13 16:06:35.495555 :CCEBABBB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22532 pso_num=25 pso_serial#=243 2025-09-13 16:06:35.495693 :CCEBABBC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22532 osp_idx=23 osp_ver=294526 osp_pg=0 (spawn #588355) 2025-09-13 16:06:35.495693 :CCEBABBD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 16:06:35.495694 :CCEBABBE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=28 time=2165246799 2025-09-13 16:06:35.495694 :CCEBABBF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 16:06:35.495695 :CCEBABC0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 16:06:35.495695 :CCEBABC1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 16:06:35.495695 :CCEBABC2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 16:06:35.495695 :CCEBABC3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 16:06:35.496178 :CCEBABC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 16:06:35.496224 :CCEBABCB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 16:16:36.292296 :CCEC74A8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 16:16:36.656428 :CCEC74F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 16:16:36.651 2025-09-13 16:16:36.656430 :CCEC74F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23428 pso_num=25 pso_serial#=244 2025-09-13 16:16:36.656571 :CCEC74F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23428 osp_idx=23 osp_ver=294527 osp_pg=0 (spawn #588378) 2025-09-13 16:16:36.656572 :CCEC74F9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 16:16:36.656573 :CCEC74FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=29 time=2165847955 2025-09-13 16:16:36.656575 :CCEC74FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 16:16:36.656575 :CCEC74FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 16:16:36.656575 :CCEC74FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 16:16:36.656575 :CCEC74FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 16:16:36.656576 :CCEC74FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 16:16:36.657067 :CCEC7500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 16:16:36.657120 :CCEC750A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 16:26:37.410833 :CCED3AEC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 16:26:37.759490 :CCED3B43:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 16:26:37.754 2025-09-13 16:26:37.759492 :CCED3B44:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24784 pso_num=25 pso_serial#=245 2025-09-13 16:26:37.759630 :CCED3B45:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24784 osp_idx=23 osp_ver=294528 osp_pg=0 (spawn #588401) 2025-09-13 16:26:37.759630 :CCED3B46:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 16:26:37.759631 :CCED3B47:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=30 time=2166449065 2025-09-13 16:26:37.759631 :CCED3B48:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 16:26:37.759632 :CCED3B49:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 16:26:37.759632 :CCED3B4A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 16:26:37.759632 :CCED3B4B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 16:26:37.759633 :CCED3B4C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 16:26:37.760126 :CCED3B4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 16:26:37.760171 :CCED3B54:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 16:36:38.601978 :CCEE01DB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 16:36:38.966237 :CCEE022C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 16:36:38.961 2025-09-13 16:36:38.966239 :CCEE022D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22444 pso_num=25 pso_serial#=246 2025-09-13 16:36:38.966383 :CCEE022E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22444 osp_idx=23 osp_ver=294529 osp_pg=0 (spawn #588423) 2025-09-13 16:36:38.966384 :CCEE022F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 16:36:38.966384 :CCEE0230:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=31 time=2167050268 2025-09-13 16:36:38.966384 :CCEE0231:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 16:36:38.966385 :CCEE0232:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 16:36:38.966385 :CCEE0233:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 16:36:38.966385 :CCEE0234:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 16:36:38.966386 :CCEE0235:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 16:36:38.966894 :CCEE0236:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 16:36:38.966940 :CCEE023D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 16:46:39.900102 :CCEEC7D7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 16:46:40.201757 :CCEEC82C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 16:46:40.197 2025-09-13 16:46:40.201759 :CCEEC82D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23144 pso_num=25 pso_serial#=247 2025-09-13 16:46:40.201898 :CCEEC82E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23144 osp_idx=23 osp_ver=294530 osp_pg=0 (spawn #588446) 2025-09-13 16:46:40.201898 :CCEEC82F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 16:46:40.201899 :CCEEC830:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=32 time=2167651502 2025-09-13 16:46:40.201899 :CCEEC831:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 16:46:40.201900 :CCEEC832:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 16:46:40.201900 :CCEEC833:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 16:46:40.201900 :CCEEC834:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 16:46:40.201901 :CCEEC835:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 16:46:40.202384 :CCEEC836:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 16:46:40.202428 :CCEEC83E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 16:56:41.131077 :CCEF8E69:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 16:56:41.354506 :CCEF8EB0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 16:56:41.349 2025-09-13 16:56:41.354508 :CCEF8EB1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22628 pso_num=25 pso_serial#=248 2025-09-13 16:56:41.354646 :CCEF8EB2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22628 osp_idx=23 osp_ver=294531 osp_pg=0 (spawn #588469) 2025-09-13 16:56:41.354647 :CCEF8EB3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 16:56:41.354647 :CCEF8EB4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=33 time=2168252658 2025-09-13 16:56:41.354648 :CCEF8EB5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 16:56:41.354648 :CCEF8EB6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 16:56:41.354648 :CCEF8EB7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 16:56:41.354649 :CCEF8EB8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 16:56:41.354649 :CCEF8EB9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 16:56:41.355144 :CCEF8EBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 16:56:41.355187 :CCEF8EC2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 17:06:42.180734 :CCF05C12:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 17:06:42.654176 :CCF05C9A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 17:06:42.649 2025-09-13 17:06:42.654178 :CCF05C9B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24448 pso_num=25 pso_serial#=249 2025-09-13 17:06:42.654316 :CCF05C9C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24448 osp_idx=23 osp_ver=294532 osp_pg=0 (spawn #588494) 2025-09-13 17:06:42.654316 :CCF05C9D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 17:06:42.654317 :CCF05C9E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=2168853955 2025-09-13 17:06:42.654317 :CCF05C9F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 17:06:42.654317 :CCF05CA0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 17:06:42.654318 :CCF05CA1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 17:06:42.654318 :CCF05CA2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 17:06:42.654318 :CCF05CA3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 17:06:42.654817 :CCF05CA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 17:06:42.654862 :CCF05CAC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 17:16:43.529468 :CCF1244C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 17:16:43.815508 :CCF124AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 17:16:43.810 2025-09-13 17:16:43.815510 :CCF124AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20612 pso_num=25 pso_serial#=250 2025-09-13 17:16:43.815662 :CCF124AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20612 osp_idx=23 osp_ver=294533 osp_pg=0 (spawn #588517) 2025-09-13 17:16:43.815663 :CCF124AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 17:16:43.815663 :CCF124AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=2169455127 2025-09-13 17:16:43.815664 :CCF124B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 17:16:43.815664 :CCF124B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 17:16:43.815665 :CCF124B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 17:16:43.815665 :CCF124B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 17:16:43.815666 :CCF124B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 17:16:43.816222 :CCF124B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 17:16:43.816268 :CCF124BD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 17:26:44.628390 :CCF1EAC5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 17:26:44.992928 :CCF1EB08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 17:26:44.987 2025-09-13 17:26:44.992931 :CCF1EB09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17340 pso_num=25 pso_serial#=251 2025-09-13 17:26:44.993086 :CCF1EB0A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17340 osp_idx=23 osp_ver=294534 osp_pg=0 (spawn #588540) 2025-09-13 17:26:44.993087 :CCF1EB0B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 17:26:44.993087 :CCF1EB0C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=2170056299 2025-09-13 17:26:44.993088 :CCF1EB0D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 17:26:44.993088 :CCF1EB0E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 17:26:44.993089 :CCF1EB0F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 17:26:44.993089 :CCF1EB10:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 17:26:44.993089 :CCF1EB11:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 17:26:44.993613 :CCF1EB12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 17:26:44.993665 :CCF1EB19:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 17:36:45.904687 :CCF2B11D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 17:36:46.294676 :CCF2B160:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 17:36:46.290 2025-09-13 17:36:46.294678 :CCF2B161:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25404 pso_num=25 pso_serial#=252 2025-09-13 17:36:46.294817 :CCF2B162:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25404 osp_idx=23 osp_ver=294535 osp_pg=0 (spawn #588562) 2025-09-13 17:36:46.294817 :CCF2B163:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 17:36:46.294818 :CCF2B164:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=2170657596 2025-09-13 17:36:46.294818 :CCF2B165:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 17:36:46.294819 :CCF2B166:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 17:36:46.294819 :CCF2B167:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 17:36:46.294819 :CCF2B168:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 17:36:46.294820 :CCF2B169:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 17:36:46.295410 :CCF2B16A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 17:36:46.295458 :CCF2B171:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 17:46:47.185059 :CCF3770D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 17:46:47.549341 :CCF3775E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 17:46:47.544 2025-09-13 17:46:47.549344 :CCF3775F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21868 pso_num=25 pso_serial#=253 2025-09-13 17:46:47.549486 :CCF37760:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21868 osp_idx=23 osp_ver=294536 osp_pg=0 (spawn #588585) 2025-09-13 17:46:47.549487 :CCF37761:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 17:46:47.549488 :CCF37762:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=2171258846 2025-09-13 17:46:47.549488 :CCF37763:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 17:46:47.549489 :CCF37764:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 17:46:47.549489 :CCF37765:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 17:46:47.549489 :CCF37766:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 17:46:47.549489 :CCF37767:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 17:46:47.549998 :CCF37768:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 17:46:47.550045 :CCF37770:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-13 17:55:27.802423 :CCF423FF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-13 17:56:48.686269 :CCF43E25:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 21868, W000)