Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250626080214\orcl_w000_12636_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:36333M/63366M, Ph+PgF:42475M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12636, image: ORACLE.EXE (W000) *** 2025-06-26 08:02:14.114 *** SESSION ID:(177.52123) 2025-06-26 08:02:14.114 *** 2025-06-26 08:02:14.114 Process diagnostic dump for ORACLE.EXE (W000), OS id=12636, pid: 22, proc_ser: 166, sid: 177, sess_ser: 52123 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=3.414441 sec, exc=3.414441 sec, total=3.414441 sec wait times: max=5.000000 sec, heur=4 min 33 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.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=5.000233 sec, exc=5.000233 sec, total=5.000233 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.006727 sec, exc=5.006727 sec, total=5.006727 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=5.003957 sec, exc=5.003957 sec, total=5.003957 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 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.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=5.014726 sec, exc=5.014726 sec, total=5.014726 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.001705 sec, exc=5.001705 sec, total=5.001705 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=48 seq_num=49 snap_id=1 wait times: snap=5.001117 sec, exc=5.001117 sec, total=5.001117 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=5.000430 sec, exc=5.000430 sec, total=5.000430 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=5.000336 sec, exc=5.000336 sec, total=5.000336 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=45 seq_num=46 snap_id=1 wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time Sampled Session History of session 177 serial 52123 --------------------------------------------------- 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:00:14 - 08:02:14] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-26 08:02:14.114 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12636, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-26 05:27:21.995496 :A9BF03C8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 05:27:21.995497 :A9BF03C9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 05:27:21.995981 :A9BF03CA: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-26 05:27:21.996034 :A9BF03D1: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-26 05:37:22.811445 :A9BFCBB5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 05:37:23.144639 :A9BFCBFA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 05:37:23.139 2025-06-26 05:37:23.144641 :A9BFCBFB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16844 pso_num=22 pso_serial#=153 2025-06-26 05:37:23.144781 :A9BFCBFC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16844 osp_idx=0 osp_ver=352125 osp_pg=0 (spawn #323550) 2025-06-26 05:37:23.144782 :A9BFCBFD: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-26 05:37:23.144783 :A9BFCBFE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=115 time=3891986329 2025-06-26 05:37:23.144783 :A9BFCBFF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 05:37:23.144784 :A9BFCC00:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 05:37:23.144784 :A9BFCC01:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 05:37:23.144784 :A9BFCC02:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 05:37:23.144785 :A9BFCC03:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 05:37:23.145405 :A9BFCC04: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-26 05:37:23.145450 :A9BFCC0E: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-26 05:47:24.026061 :A9C093F6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 05:47:24.421254 :A9C09430:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 05:47:24.416 2025-06-26 05:47:24.421256 :A9C09431:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16836 pso_num=22 pso_serial#=154 2025-06-26 05:47:24.421391 :A9C09432:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16836 osp_idx=0 osp_ver=352126 osp_pg=0 (spawn #323572) 2025-06-26 05:47:24.421391 :A9C09433: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-26 05:47:24.421392 :A9C09434:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=116 time=3892587610 2025-06-26 05:47:24.421392 :A9C09435:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 05:47:24.421392 :A9C09436:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 05:47:24.421393 :A9C09437:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 05:47:24.421393 :A9C09438:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 05:47:24.421393 :A9C09439:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 05:47:24.421958 :A9C0943A: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-26 05:47:24.422005 :A9C09442: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-26 05:57:25.277906 :A9C15C2F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 05:57:25.688701 :A9C15C75:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 05:57:25.684 2025-06-26 05:57:25.688703 :A9C15C76:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16116 pso_num=22 pso_serial#=155 2025-06-26 05:57:25.688839 :A9C15C77:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16116 osp_idx=0 osp_ver=352127 osp_pg=0 (spawn #323595) 2025-06-26 05:57:25.688840 :A9C15C78: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-26 05:57:25.688840 :A9C15C79:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=117 time=3893188875 2025-06-26 05:57:25.688840 :A9C15C7A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 05:57:25.688841 :A9C15C7B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 05:57:25.688841 :A9C15C7C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 05:57:25.688841 :A9C15C7D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 05:57:25.688842 :A9C15C7E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 05:57:25.689331 :A9C15C7F: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-26 05:57:25.689375 :A9C15C87: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-26 06:07:26.550583 :A9C22CBD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 06:07:26.961855 :A9C22CFB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 06:07:26.956 2025-06-26 06:07:26.961857 :A9C22CFC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13352 pso_num=22 pso_serial#=156 2025-06-26 06:07:26.961996 :A9C22CFD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13352 osp_idx=0 osp_ver=352128 osp_pg=0 (spawn #323621) 2025-06-26 06:07:26.961997 :A9C22CFE: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-26 06:07:26.961997 :A9C22CFF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=118 time=3893790157 2025-06-26 06:07:26.961998 :A9C22D00:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 06:07:26.961998 :A9C22D01:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 06:07:26.961999 :A9C22D02:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 06:07:26.961999 :A9C22D03:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 06:07:26.961999 :A9C22D04:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 06:07:26.962521 :A9C22D05: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-26 06:07:26.962569 :A9C22D0C: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-26 06:17:27.819107 :A9C2F7D4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 06:17:28.276909 :A9C2F822:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 06:17:28.272 2025-06-26 06:17:28.276911 :A9C2F823:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15468 pso_num=22 pso_serial#=157 2025-06-26 06:17:28.277050 :A9C2F824:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15468 osp_idx=0 osp_ver=352129 osp_pg=0 (spawn #323643) 2025-06-26 06:17:28.277051 :A9C2F825: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-26 06:17:28.277052 :A9C2F826:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=119 time=3894391469 2025-06-26 06:17:28.277052 :A9C2F827:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 06:17:28.277053 :A9C2F828:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 06:17:28.277053 :A9C2F829:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 06:17:28.277053 :A9C2F82A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 06:17:28.277054 :A9C2F82B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 06:17:28.277534 :A9C2F82C: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-26 06:17:28.277580 :A9C2F834: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-26 06:27:29.030938 :A9C3C0C1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 06:27:29.410638 :A9C3C0F8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 06:27:29.406 2025-06-26 06:27:29.410640 :A9C3C0F9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15672 pso_num=22 pso_serial#=158 2025-06-26 06:27:29.410777 :A9C3C0FA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15672 osp_idx=0 osp_ver=352130 osp_pg=0 (spawn #323666) 2025-06-26 06:27:29.410777 :A9C3C0FB: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-26 06:27:29.410778 :A9C3C0FC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=120 time=3894992594 2025-06-26 06:27:29.410778 :A9C3C0FD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 06:27:29.410778 :A9C3C0FE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 06:27:29.410779 :A9C3C0FF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 06:27:29.410779 :A9C3C100:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 06:27:29.410779 :A9C3C101:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 06:27:29.411268 :A9C3C102: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-26 06:27:29.411313 :A9C3C10A: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-26 06:37:30.248740 :A9C488A4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 06:37:30.597219 :A9C488E5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 06:37:30.593 2025-06-26 06:37:30.597221 :A9C488E6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16768 pso_num=22 pso_serial#=159 2025-06-26 06:37:30.597360 :A9C488E7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16768 osp_idx=0 osp_ver=352131 osp_pg=0 (spawn #323689) 2025-06-26 06:37:30.597360 :A9C488E8: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-26 06:37:30.597361 :A9C488E9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=121 time=3895593782 2025-06-26 06:37:30.597361 :A9C488EA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 06:37:30.597362 :A9C488EB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 06:37:30.597362 :A9C488EC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 06:37:30.597362 :A9C488ED:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 06:37:30.597363 :A9C488EE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 06:37:30.597846 :A9C488EF: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-26 06:37:30.597894 :A9C488F7: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-26 06:42:27.940620 :A9C4EC09: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-26 06:47:31.878444 :A9C5519E: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-26 06:57:32.666382 :A9C6190E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 06:57:33.139794 :A9C61945:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 06:57:33.135 2025-06-26 06:57:33.139795 :A9C61946:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14500 pso_num=22 pso_serial#=160 2025-06-26 06:57:33.139935 :A9C61947:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14500 osp_idx=0 osp_ver=352132 osp_pg=0 (spawn #323733) 2025-06-26 06:57:33.139936 :A9C61948: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-26 06:57:33.139936 :A9C61949:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=122 time=3896796329 2025-06-26 06:57:33.139937 :A9C6194A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 06:57:33.139937 :A9C6194B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 06:57:33.139938 :A9C6194C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 06:57:33.139938 :A9C6194D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 06:57:33.139938 :A9C6194E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 06:57:33.140435 :A9C6194F: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-26 06:57:33.140482 :A9C61956: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-26 07:07:33.895941 :A9C6EC40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 07:07:34.385004 :A9C6EC81:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 07:07:34.380 2025-06-26 07:07:34.385006 :A9C6EC82:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13284 pso_num=22 pso_serial#=161 2025-06-26 07:07:34.385140 :A9C6EC83:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13284 osp_idx=0 osp_ver=352133 osp_pg=0 (spawn #323760) 2025-06-26 07:07:34.385141 :A9C6EC84: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-26 07:07:34.385141 :A9C6EC85:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=123 time=3897397579 2025-06-26 07:07:34.385142 :A9C6EC86:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 07:07:34.385142 :A9C6EC87:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 07:07:34.385142 :A9C6EC88:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 07:07:34.385143 :A9C6EC89:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 07:07:34.385143 :A9C6EC8A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 07:07:34.385635 :A9C6EC8B: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-26 07:07:34.385684 :A9C6EC93: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-26 07:17:35.123971 :A9C7B342:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 07:17:35.550452 :A9C7B37B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 07:17:35.546 2025-06-26 07:17:35.550454 :A9C7B37C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18252 pso_num=22 pso_serial#=162 2025-06-26 07:17:35.550640 :A9C7B37D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18252 osp_idx=0 osp_ver=352134 osp_pg=0 (spawn #323782) 2025-06-26 07:17:35.550640 :A9C7B37E: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-26 07:17:35.550641 :A9C7B37F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=124 time=3897998735 2025-06-26 07:17:35.550641 :A9C7B380:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 07:17:35.550642 :A9C7B381:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 07:17:35.550642 :A9C7B382:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 07:17:35.550642 :A9C7B383:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 07:17:35.550643 :A9C7B384:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 07:17:35.551157 :A9C7B385: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-26 07:17:35.551201 :A9C7B38E: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-26 07:27:36.260525 :A9C87B6C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 07:27:36.702772 :A9C87BB6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 07:27:36.697 2025-06-26 07:27:36.702774 :A9C87BB7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14948 pso_num=22 pso_serial#=163 2025-06-26 07:27:36.702906 :A9C87BB8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14948 osp_idx=0 osp_ver=352135 osp_pg=0 (spawn #323805) 2025-06-26 07:27:36.702907 :A9C87BB9: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-26 07:27:36.702908 :A9C87BBA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=125 time=3898599891 2025-06-26 07:27:36.702908 :A9C87BBB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 07:27:36.702909 :A9C87BBC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 07:27:36.702909 :A9C87BBD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 07:27:36.702909 :A9C87BBE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 07:27:36.702910 :A9C87BBF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 07:27:36.703413 :A9C87BC0: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-26 07:27:36.703462 :A9C87BC8: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-26 07:37:37.534318 :A9C94586:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 07:37:37.976505 :A9C945D3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 07:37:37.972 2025-06-26 07:37:37.976506 :A9C945D4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14908 pso_num=22 pso_serial#=164 2025-06-26 07:37:37.976636 :A9C945D5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14908 osp_idx=0 osp_ver=352136 osp_pg=0 (spawn #323828) 2025-06-26 07:37:37.976637 :A9C945D6: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-26 07:37:37.976637 :A9C945D7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=126 time=3899201172 2025-06-26 07:37:37.976638 :A9C945D8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 07:37:37.976638 :A9C945D9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 07:37:37.976638 :A9C945DA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 07:37:37.976639 :A9C945DB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 07:37:37.976639 :A9C945DC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 07:37:37.977196 :A9C945DD: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-26 07:37:37.977246 :A9C945E5: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-26 07:47:38.831048 :A9CA0E6D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 07:47:39.226472 :A9CA0EA7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 07:47:39.222 2025-06-26 07:47:39.226474 :A9CA0EA8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17620 pso_num=22 pso_serial#=165 2025-06-26 07:47:39.226609 :A9CA0EA9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17620 osp_idx=0 osp_ver=352137 osp_pg=0 (spawn #323850) 2025-06-26 07:47:39.226609 :A9CA0EAA: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-26 07:47:39.226610 :A9CA0EAB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=127 time=3899802422 2025-06-26 07:47:39.226610 :A9CA0EAC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 07:47:39.226610 :A9CA0EAD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 07:47:39.226611 :A9CA0EAE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 07:47:39.226611 :A9CA0EAF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 07:47:39.226611 :A9CA0EB0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 07:47:39.227098 :A9CA0EB1: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-26 07:47:39.227143 :A9CA0EBB: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-26 07:57:40.019577 :A9CAD676:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-26 07:57:40.383496 :A9CAD6BA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 07:57:40.378 2025-06-26 07:57:40.383498 :A9CAD6BB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12636 pso_num=22 pso_serial#=166 2025-06-26 07:57:40.383632 :A9CAD6BC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12636 osp_idx=0 osp_ver=352138 osp_pg=0 (spawn #323873) 2025-06-26 07:57:40.383633 :A9CAD6BD: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-26 07:57:40.383633 :A9CAD6BE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=128 time=3900403579 2025-06-26 07:57:40.383633 :A9CAD6BF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-26 07:57:40.383634 :A9CAD6C0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-26 07:57:40.383634 :A9CAD6C1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-26 07:57:40.383634 :A9CAD6C2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-26 07:57:40.383635 :A9CAD6C3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-26 07:57:40.384208 :A9CAD6C4: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-26 07:57:40.384257 :A9CAD6CE: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: 12636, W000)