Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250827081520\orcl_w000_22176_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:35168M/63366M, Ph+PgF:40226M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 22176, image: ORACLE.EXE (W000) *** 2025-08-27 08:15:20.094 *** SESSION ID:(177.64541) 2025-08-27 08:15:20.094 *** 2025-08-27 08:15:20.094 Process diagnostic dump for ORACLE.EXE (W000), OS id=22176, pid: 22, proc_ser: 187, sid: 177, sess_ser: 64541 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=228 seq_num=229 snap_id=1 wait times: snap=3.805015 sec, exc=3.805015 sec, total=3.805015 sec wait times: max=5.000000 sec, heur=14 min 27 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.000019 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=227 seq_num=228 snap_id=1 wait times: snap=5.002488 sec, exc=5.002488 sec, total=5.002488 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=226 seq_num=227 snap_id=1 wait times: snap=5.010676 sec, exc=5.010676 sec, total=5.010676 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=225 seq_num=226 snap_id=1 wait times: snap=5.014724 sec, exc=5.014724 sec, total=5.014724 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=224 seq_num=225 snap_id=1 wait times: snap=5.004617 sec, exc=5.004617 sec, total=5.004617 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=223 seq_num=224 snap_id=1 wait times: snap=5.004618 sec, exc=5.004618 sec, total=5.004618 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=222 seq_num=223 snap_id=1 wait times: snap=5.001718 sec, exc=5.001718 sec, total=5.001718 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=221 seq_num=222 snap_id=1 wait times: snap=5.001469 sec, exc=5.001469 sec, total=5.001469 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=220 seq_num=221 snap_id=1 wait times: snap=5.011038 sec, exc=5.011038 sec, total=5.011038 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=219 seq_num=220 snap_id=1 wait times: snap=5.014737 sec, exc=5.014737 sec, total=5.014737 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=218 seq_num=219 snap_id=1 wait times: snap=5.014736 sec, exc=5.014736 sec, total=5.014736 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 177 serial 64541 --------------------------------------------------- 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:13:20 - 08:15:20] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-27 08:15:20.094 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 22176, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-27 05:26:10.019010 :C5317795: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-08-27 05:36:10.933724 :C5323EFC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 05:36:11.313376 :C5323F97:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 05:36:11.308 2025-08-27 05:36:11.313378 :C5323F98:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23236 pso_num=22 pso_serial#=174 2025-08-27 05:36:11.313516 :C5323F99:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23236 osp_idx=0 osp_ver=551811 osp_pg=0 (spawn #530219) 2025-08-27 05:36:11.313517 :C5323F9A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 05:36:11.313517 :C5323F9B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=187 time=658622612 2025-08-27 05:36:11.313518 :C5323F9C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 05:36:11.313518 :C5323F9D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 05:36:11.313519 :C5323F9E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 05:36:11.313519 :C5323F9F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 05:36:11.313521 :C5323FA0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 05:36:11.314003 :C5323FA1: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-08-27 05:36:11.314045 :C5323FA9: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-08-27 05:46:12.178796 :C5330544:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 05:46:12.464550 :C53305BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 05:46:12.459 2025-08-27 05:46:12.464552 :C53305BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23516 pso_num=22 pso_serial#=175 2025-08-27 05:46:12.464737 :C53305C0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23516 osp_idx=0 osp_ver=551812 osp_pg=0 (spawn #530241) 2025-08-27 05:46:12.464738 :C53305C1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 05:46:12.464739 :C53305C2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=188 time=659223768 2025-08-27 05:46:12.464739 :C53305C3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 05:46:12.464739 :C53305C4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 05:46:12.464740 :C53305C5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 05:46:12.464740 :C53305C6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 05:46:12.464741 :C53305C7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 05:46:12.465233 :C53305C8: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-08-27 05:46:12.465281 :C53305D0: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-08-27 05:56:13.424525 :C533CBB9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 05:56:13.835355 :C533CC62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 05:56:13.830 2025-08-27 05:56:13.835357 :C533CC63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21376 pso_num=22 pso_serial#=176 2025-08-27 05:56:13.835491 :C533CC64:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21376 osp_idx=0 osp_ver=551813 osp_pg=0 (spawn #530264) 2025-08-27 05:56:13.835492 :C533CC65:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 05:56:13.835492 :C533CC66:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=189 time=659825143 2025-08-27 05:56:13.835493 :C533CC67:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 05:56:13.835493 :C533CC68:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 05:56:13.835493 :C533CC69:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 05:56:13.835494 :C533CC6A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 05:56:13.835494 :C533CC6B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 05:56:13.835977 :C533CC6C: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-08-27 05:56:13.836020 :C533CC73: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-08-27 06:00:22.042047 :C5341D0C: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-08-27 06:06:15.170495 :C5349C40: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-08-27 06:16:15.969199 :C53564C0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 06:16:16.426986 :C5356588:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 06:16:16.422 2025-08-27 06:16:16.426989 :C5356589:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22608 pso_num=22 pso_serial#=177 2025-08-27 06:16:16.427155 :C535658A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22608 osp_idx=0 osp_ver=551814 osp_pg=0 (spawn #530311) 2025-08-27 06:16:16.427156 :C535658B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 06:16:16.427157 :C535658C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=190 time=661027737 2025-08-27 06:16:16.427157 :C535658D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 06:16:16.427158 :C535658E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 06:16:16.427158 :C535658F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 06:16:16.427159 :C5356590:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 06:16:16.427159 :C5356591:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 06:16:16.427704 :C5356592: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-08-27 06:16:16.427747 :C535659A: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-08-27 06:26:17.340854 :C5362BC8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 06:26:17.777435 :C5362C88:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 06:26:17.772 2025-08-27 06:26:17.777437 :C5362C89:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17404 pso_num=22 pso_serial#=178 2025-08-27 06:26:17.777571 :C5362C8A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17404 osp_idx=0 osp_ver=551815 osp_pg=0 (spawn #530334) 2025-08-27 06:26:17.777572 :C5362C8B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 06:26:17.777573 :C5362C8C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=191 time=661629080 2025-08-27 06:26:17.777573 :C5362C8D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 06:26:17.777573 :C5362C8E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 06:26:17.777574 :C5362C8F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 06:26:17.777574 :C5362C90:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 06:26:17.777574 :C5362C91:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 06:26:17.778129 :C5362C92: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-08-27 06:26:17.778177 :C5362C9B: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-08-27 06:36:18.617054 :C536F34B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 06:36:19.106055 :C536F401:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 06:36:19.101 2025-08-27 06:36:19.106057 :C536F402:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22644 pso_num=22 pso_serial#=179 2025-08-27 06:36:19.106198 :C536F403:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22644 osp_idx=0 osp_ver=551816 osp_pg=0 (spawn #530357) 2025-08-27 06:36:19.106198 :C536F404:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 06:36:19.106199 :C536F405:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=192 time=662230408 2025-08-27 06:36:19.106199 :C536F406:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 06:36:19.106200 :C536F407:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 06:36:19.106200 :C536F408:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 06:36:19.106200 :C536F409:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 06:36:19.106201 :C536F40A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 06:36:19.106700 :C536F40B: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-08-27 06:36:19.106744 :C536F413: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-08-27 06:46:19.894756 :C537BA40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 06:46:20.306122 :C537BB05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 06:46:20.301 2025-08-27 06:46:20.306124 :C537BB06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22468 pso_num=22 pso_serial#=180 2025-08-27 06:46:20.306264 :C537BB07:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22468 osp_idx=0 osp_ver=551817 osp_pg=0 (spawn #530379) 2025-08-27 06:46:20.306265 :C537BB08:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 06:46:20.306266 :C537BB09:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=193 time=662831612 2025-08-27 06:46:20.306266 :C537BB0A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 06:46:20.306267 :C537BB0B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 06:46:20.306267 :C537BB0C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 06:46:20.306267 :C537BB0D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 06:46:20.306268 :C537BB0E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 06:46:20.306758 :C537BB0F: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-08-27 06:46:20.306808 :C537BB18: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-08-27 06:56:21.248670 :C5388211:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 06:56:21.706243 :C538829E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 06:56:21.701 2025-08-27 06:56:21.706246 :C538829F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23040 pso_num=22 pso_serial#=181 2025-08-27 06:56:21.706383 :C53882A0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23040 osp_idx=0 osp_ver=551818 osp_pg=0 (spawn #530402) 2025-08-27 06:56:21.706383 :C53882A1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 06:56:21.706383 :C53882A2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=194 time=663433018 2025-08-27 06:56:21.706384 :C53882A3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 06:56:21.706384 :C53882A4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 06:56:21.706385 :C53882A5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 06:56:21.706385 :C53882A6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 06:56:21.706385 :C53882A7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 06:56:21.706877 :C53882A8: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-08-27 06:56:21.706921 :C53882B0: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-08-27 07:06:22.608263 :C539511D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 07:06:23.034522 :C53951B5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 07:06:23.030 2025-08-27 07:06:23.034524 :C53951B6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21796 pso_num=22 pso_serial#=182 2025-08-27 07:06:23.034664 :C53951B7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21796 osp_idx=0 osp_ver=551819 osp_pg=0 (spawn #530428) 2025-08-27 07:06:23.034664 :C53951B8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 07:06:23.034665 :C53951B9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=664034346 2025-08-27 07:06:23.034665 :C53951BA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 07:06:23.034666 :C53951BB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 07:06:23.034666 :C53951BC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 07:06:23.034666 :C53951BD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 07:06:23.034667 :C53951BE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 07:06:23.035169 :C53951BF: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-08-27 07:06:23.035214 :C53951C7: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-08-27 07:16:23.999454 :C53A1B6D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 07:16:24.379002 :C53A1BE7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 07:16:24.374 2025-08-27 07:16:24.379004 :C53A1BE8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22368 pso_num=22 pso_serial#=183 2025-08-27 07:16:24.379176 :C53A1BE9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22368 osp_idx=0 osp_ver=551820 osp_pg=0 (spawn #530450) 2025-08-27 07:16:24.379177 :C53A1BEA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 07:16:24.379178 :C53A1BEB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=196 time=664635690 2025-08-27 07:16:24.379178 :C53A1BEC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 07:16:24.379179 :C53A1BED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 07:16:24.379179 :C53A1BEE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 07:16:24.379179 :C53A1BEF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 07:16:24.379180 :C53A1BF0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 07:16:24.379682 :C53A1BF1: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-08-27 07:16:24.379728 :C53A1BF9: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-08-27 07:26:25.258972 :C53AE22D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 07:26:25.718114 :C53AE2D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 07:26:25.712 2025-08-27 07:26:25.718116 :C53AE2D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16692 pso_num=22 pso_serial#=184 2025-08-27 07:26:25.718292 :C53AE2D2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16692 osp_idx=0 osp_ver=551821 osp_pg=0 (spawn #530473) 2025-08-27 07:26:25.718293 :C53AE2D3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 07:26:25.718293 :C53AE2D4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=665237018 2025-08-27 07:26:25.718294 :C53AE2D5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 07:26:25.718294 :C53AE2D6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 07:26:25.718295 :C53AE2D7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 07:26:25.718295 :C53AE2D8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 07:26:25.718296 :C53AE2D9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 07:26:25.718846 :C53AE2DA: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-08-27 07:26:25.718898 :C53AE2E0: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-08-27 07:36:26.577077 :C53BAAAC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 07:36:26.873540 :C53BAB44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 07:36:26.868 2025-08-27 07:36:26.873543 :C53BAB45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23256 pso_num=22 pso_serial#=185 2025-08-27 07:36:26.873695 :C53BAB46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23256 osp_idx=0 osp_ver=551822 osp_pg=0 (spawn #530496) 2025-08-27 07:36:26.873696 :C53BAB47:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 07:36:26.873696 :C53BAB48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=198 time=665838174 2025-08-27 07:36:26.873697 :C53BAB49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 07:36:26.873697 :C53BAB4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 07:36:26.873697 :C53BAB4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 07:36:26.873697 :C53BAB4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 07:36:26.873698 :C53BAB4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 07:36:26.874275 :C53BAB4E: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-08-27 07:36:26.874325 :C53BAB55: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-08-27 07:46:27.749861 :C53C72BB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 07:46:28.130539 :C53C735A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 07:46:28.125 2025-08-27 07:46:28.130541 :C53C735B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20892 pso_num=22 pso_serial#=186 2025-08-27 07:46:28.130675 :C53C735C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20892 osp_idx=0 osp_ver=551823 osp_pg=0 (spawn #530518) 2025-08-27 07:46:28.130676 :C53C735D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 07:46:28.130676 :C53C735E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=199 time=666439440 2025-08-27 07:46:28.130677 :C53C735F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 07:46:28.130677 :C53C7360:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 07:46:28.130678 :C53C7361:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 07:46:28.130679 :C53C7362:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 07:46:28.130679 :C53C7363:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 07:46:28.131202 :C53C7364: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-08-27 07:46:28.131259 :C53C736E: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-08-27 07:56:29.013866 :C53D3A5C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-27 07:56:29.378689 :C53D3AEF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-27 07:56:29.373 2025-08-27 07:56:29.378691 :C53D3AF0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22176 pso_num=22 pso_serial#=187 2025-08-27 07:56:29.378832 :C53D3AF1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22176 osp_idx=0 osp_ver=551824 osp_pg=0 (spawn #530541) 2025-08-27 07:56:29.378833 :C53D3AF2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-27 07:56:29.378833 :C53D3AF3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=200 time=667040690 2025-08-27 07:56:29.378834 :C53D3AF4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-27 07:56:29.378834 :C53D3AF5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-27 07:56:29.378835 :C53D3AF6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-27 07:56:29.378835 :C53D3AF7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-27 07:56:29.378836 :C53D3AF8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-27 07:56:29.379350 :C53D3AF9: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-08-27 07:56:29.379393 :C53D3B02: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-08-27 08:00:52.355013 :C53D9239: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-08-27 08:06:30.479565 :C53E0B51: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: 22176, W000)