Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913100329\orcl_w000_22080_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:34819M/63366M, Ph+PgF:39506M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22080, image: ORACLE.EXE (W000) *** 2025-09-13 10:03:29.530 *** SESSION ID:(201.61173) 2025-09-13 10:03:29.530 *** 2025-09-13 10:03:29.530 Process diagnostic dump for ORACLE.EXE (W000), OS id=22080, pid: 25, proc_ser: 206, sid: 201, sess_ser: 61173 ------------------------------------------------------------------------------- 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.286552 sec, exc=3.286552 sec, total=3.286552 sec wait times: max=5.000000 sec, heur=13 min 12 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=213 seq_num=214 snap_id=1 wait times: snap=5.001130 sec, exc=5.001130 sec, total=5.001130 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 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.001868 sec, exc=5.001868 sec, total=5.001868 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 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.000624 sec, exc=5.000624 sec, total=5.000624 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 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.004633 sec, exc=5.004633 sec, total=5.004633 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=209 seq_num=210 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.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.014792 sec, exc=5.014792 sec, total=5.014792 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 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.010163 sec, exc=5.010163 sec, total=5.010163 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 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.008470 sec, exc=5.008470 sec, total=5.008470 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 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.010898 sec, exc=5.010898 sec, total=5.010898 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.013877 sec, exc=5.013877 sec, total=5.013877 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 61173 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 10:01:29 - 10:03:29] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 10:03:29.530 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22080, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 07:15:31.789164 :CCC1104C: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 07:25:32.622089 :CCC1D994:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:25:32.925993 :CCC1D9CA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:25:32.921 2025-09-13 07:25:32.925995 :CCC1D9CB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20612 pso_num=25 pso_serial#=193 2025-09-13 07:25:32.926131 :CCC1D9CC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20612 osp_idx=23 osp_ver=294476 osp_pg=0 (spawn #587143) 2025-09-13 07:25:32.926131 :CCC1D9CD: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 07:25:32.926132 :CCC1D9CE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=2133984237 2025-09-13 07:25:32.926132 :CCC1D9CF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:25:32.926132 :CCC1D9D0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:25:32.926133 :CCC1D9D1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:25:32.926133 :CCC1D9D2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:25:32.926134 :CCC1D9D3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:25:32.926623 :CCC1D9D4: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 07:25:32.926669 :CCC1D9DB: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 07:35:33.742809 :CCC2A3BC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:35:34.106953 :CCC2A3F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:35:34.102 2025-09-13 07:35:34.106955 :CCC2A3F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22436 pso_num=25 pso_serial#=194 2025-09-13 07:35:34.107104 :CCC2A3F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22436 osp_idx=23 osp_ver=294477 osp_pg=0 (spawn #587165) 2025-09-13 07:35:34.107105 :CCC2A3F9: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 07:35:34.107105 :CCC2A3FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=2134585408 2025-09-13 07:35:34.107106 :CCC2A3FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:35:34.107106 :CCC2A3FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:35:34.107106 :CCC2A3FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:35:34.107107 :CCC2A3FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:35:34.107107 :CCC2A3FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:35:34.107614 :CCC2A400: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 07:35:34.107662 :CCC2A406: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 07:45:16.668028 :CCC36668: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 07:45:35.333910 :CCC36D1D: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 07:55:36.189335 :CCC437C3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 07:55:36.506548 :CCC437FC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 07:55:36.501 2025-09-13 07:55:36.506550 :CCC437FD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25396 pso_num=25 pso_serial#=195 2025-09-13 07:55:36.506686 :CCC437FE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25396 osp_idx=23 osp_ver=294478 osp_pg=0 (spawn #587210) 2025-09-13 07:55:36.506687 :CCC437FF: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 07:55:36.506687 :CCC43800:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=2135787815 2025-09-13 07:55:36.506687 :CCC43801:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 07:55:36.506688 :CCC43802:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 07:55:36.506688 :CCC43803:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 07:55:36.506688 :CCC43804:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 07:55:36.506688 :CCC43805:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 07:55:36.507178 :CCC43806: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 07:55:36.507219 :CCC4380E: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 08:05:37.309854 :CCC50B8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:05:37.691542 :CCC50BF2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:05:37.686 2025-09-13 08:05:37.691545 :CCC50BF3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22572 pso_num=25 pso_serial#=196 2025-09-13 08:05:37.691694 :CCC50BF4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22572 osp_idx=23 osp_ver=294479 osp_pg=0 (spawn #587235) 2025-09-13 08:05:37.691694 :CCC50BF5: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 08:05:37.691695 :CCC50BF6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=2136389002 2025-09-13 08:05:37.691695 :CCC50BF7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:05:37.691696 :CCC50BF8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:05:37.691696 :CCC50BF9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:05:37.691696 :CCC50BFA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:05:37.691697 :CCC50BFB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:05:37.692209 :CCC50BFC: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 08:05:37.692257 :CCC50C03: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 08:15:38.478471 :CCC5D563:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:15:38.837199 :CCC5D5CA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:15:38.832 2025-09-13 08:15:38.837200 :CCC5D5CB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20628 pso_num=25 pso_serial#=197 2025-09-13 08:15:38.837346 :CCC5D5CC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20628 osp_idx=23 osp_ver=294480 osp_pg=0 (spawn #587258) 2025-09-13 08:15:38.837347 :CCC5D5CD: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 08:15:38.837347 :CCC5D5CE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=2136990143 2025-09-13 08:15:38.837348 :CCC5D5CF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:15:38.837348 :CCC5D5D0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:15:38.837349 :CCC5D5D1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:15:38.837349 :CCC5D5D2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:15:38.837349 :CCC5D5D3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:15:38.837864 :CCC5D5D4: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 08:15:38.837914 :CCC5D5DB: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 08:25:39.620941 :CCC6EC27:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:25:39.953809 :CCC6EC5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:25:39.949 2025-09-13 08:25:39.953811 :CCC6EC60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24024 pso_num=25 pso_serial#=198 2025-09-13 08:25:39.953948 :CCC6EC61:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24024 osp_idx=23 osp_ver=294481 osp_pg=0 (spawn #587286) 2025-09-13 08:25:39.953948 :CCC6EC62: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 08:25:39.953949 :CCC6EC63:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=2137591252 2025-09-13 08:25:39.953949 :CCC6EC64:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:25:39.953949 :CCC6EC65:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:25:39.953950 :CCC6EC66:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:25:39.953950 :CCC6EC67:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:25:39.953950 :CCC6EC68:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:25:39.954444 :CCC6EC69: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 08:25:39.954489 :CCC6EC70: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 08:35:40.831393 :CCC7D205:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:35:41.086153 :CCC7D248:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:35:41.081 2025-09-13 08:35:41.086155 :CCC7D249:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23676 pso_num=25 pso_serial#=199 2025-09-13 08:35:41.086293 :CCC7D24A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23676 osp_idx=23 osp_ver=294482 osp_pg=0 (spawn #587308) 2025-09-13 08:35:41.086294 :CCC7D24B: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 08:35:41.086294 :CCC7D24C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=2138192393 2025-09-13 08:35:41.086295 :CCC7D24D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:35:41.086295 :CCC7D24E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:35:41.086295 :CCC7D24F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:35:41.086295 :CCC7D250:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:35:41.086296 :CCC7D251:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:35:41.086819 :CCC7D252: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 08:35:41.086866 :CCC7D25B: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 08:45:41.958943 :CCC8A057:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:45:42.229371 :CCC8A07B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:45:42.225 2025-09-13 08:45:42.229372 :CCC8A07C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24848 pso_num=25 pso_serial#=200 2025-09-13 08:45:42.229511 :CCC8A07D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24848 osp_idx=23 osp_ver=294483 osp_pg=0 (spawn #587331) 2025-09-13 08:45:42.229512 :CCC8A07E: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 08:45:42.229512 :CCC8A07F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=2138793533 2025-09-13 08:45:42.229512 :CCC8A080:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:45:42.229513 :CCC8A081:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:45:42.229513 :CCC8A082:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:45:42.229513 :CCC8A083:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:45:42.229514 :CCC8A084:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:45:42.229998 :CCC8A085: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 08:45:42.230052 :CCC8A08C: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 08:55:43.046850 :CCC96D89:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 08:55:43.301916 :CCC96DAC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 08:55:43.296 2025-09-13 08:55:43.301919 :CCC96DAD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22108 pso_num=25 pso_serial#=201 2025-09-13 08:55:43.302065 :CCC96DAE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22108 osp_idx=23 osp_ver=294484 osp_pg=0 (spawn #587356) 2025-09-13 08:55:43.302066 :CCC96DAF: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 08:55:43.302066 :CCC96DB0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=245 time=2139394612 2025-09-13 08:55:43.302067 :CCC96DB1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 08:55:43.302067 :CCC96DB2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 08:55:43.302067 :CCC96DB3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 08:55:43.302068 :CCC96DB4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 08:55:43.302068 :CCC96DB5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 08:55:43.302578 :CCC96DB6: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 08:55:43.302624 :CCC96DBE: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 09:05:44.103586 :CCCA42F8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 09:05:44.389941 :CCCA4334:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 09:05:44.384 2025-09-13 09:05:44.389943 :CCCA4335:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25516 pso_num=25 pso_serial#=202 2025-09-13 09:05:44.390083 :CCCA4336:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25516 osp_idx=23 osp_ver=294485 osp_pg=0 (spawn #587381) 2025-09-13 09:05:44.390084 :CCCA4337: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 09:05:44.390085 :CCCA4338:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=2139995690 2025-09-13 09:05:44.390086 :CCCA4339:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 09:05:44.390086 :CCCA433A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 09:05:44.390087 :CCCA433B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 09:05:44.390088 :CCCA433C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 09:05:44.390088 :CCCA433D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 09:05:44.390604 :CCCA433E: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 09:05:44.390661 :CCCA4344: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 09:15:45.182297 :CCCB0F96:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 09:15:45.525266 :CCCB0FCE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 09:15:45.520 2025-09-13 09:15:45.525268 :CCCB0FCF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21904 pso_num=25 pso_serial#=203 2025-09-13 09:15:45.525399 :CCCB0FD0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21904 osp_idx=23 osp_ver=294486 osp_pg=0 (spawn #587404) 2025-09-13 09:15:45.525399 :CCCB0FD1: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 09:15:45.525399 :CCCB0FD2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=2140596830 2025-09-13 09:15:45.525400 :CCCB0FD3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 09:15:45.525400 :CCCB0FD4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 09:15:45.525400 :CCCB0FD5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 09:15:45.525401 :CCCB0FD6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 09:15:45.525401 :CCCB0FD7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 09:15:45.525881 :CCCB0FD8: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 09:15:45.525929 :CCCB0FE2: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 09:25:46.397635 :CCCBD88B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 09:25:46.855513 :CCCBD8C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 09:25:46.850 2025-09-13 09:25:46.855515 :CCCBD8C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25488 pso_num=25 pso_serial#=204 2025-09-13 09:25:46.855648 :CCCBD8C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25488 osp_idx=23 osp_ver=294487 osp_pg=0 (spawn #587427) 2025-09-13 09:25:46.855649 :CCCBD8C5: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 09:25:46.855649 :CCCBD8C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=2141198158 2025-09-13 09:25:46.855650 :CCCBD8C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 09:25:46.855650 :CCCBD8C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 09:25:46.855651 :CCCBD8C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 09:25:46.855651 :CCCBD8CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 09:25:46.855651 :CCCBD8CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 09:25:46.856146 :CCCBD8CC: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 09:25:46.856190 :CCCBD8D3: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 09:35:47.785206 :CCCCA2AA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 09:35:48.117969 :CCCCA2E2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 09:35:48.113 2025-09-13 09:35:48.117972 :CCCCA2E3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21828 pso_num=25 pso_serial#=205 2025-09-13 09:35:48.118114 :CCCCA2E4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21828 osp_idx=23 osp_ver=294488 osp_pg=0 (spawn #587449) 2025-09-13 09:35:48.118115 :CCCCA2E5: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 09:35:48.118115 :CCCCA2E6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=2141799424 2025-09-13 09:35:48.118116 :CCCCA2E7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 09:35:48.118116 :CCCCA2E8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 09:35:48.118116 :CCCCA2E9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 09:35:48.118117 :CCCCA2EA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 09:35:48.118118 :CCCCA2EB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 09:35:48.118616 :CCCCA2EC: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 09:35:48.118661 :CCCCA2F3: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 09:45:48.947981 :CCCD6B95:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-13 09:45:49.405910 :CCCD6BE3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-13 09:45:49.400 2025-09-13 09:45:49.405912 :CCCD6BE4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22080 pso_num=25 pso_serial#=206 2025-09-13 09:45:49.406050 :CCCD6BE5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22080 osp_idx=23 osp_ver=294489 osp_pg=0 (spawn #587472) 2025-09-13 09:45:49.406050 :CCCD6BE6: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 09:45:49.406051 :CCCD6BE7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=2142400705 2025-09-13 09:45:49.406051 :CCCD6BE8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 09:45:49.406051 :CCCD6BE9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 09:45:49.406052 :CCCD6BEA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 09:45:49.406052 :CCCD6BEB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 09:45:49.406053 :CCCD6BEC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 09:45:49.406541 :CCCD6BED: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 09:45:49.406585 :CCCD6BF5: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 09:50:16.620536 :CCCDC3E7: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 09:55:50.669235 :CCCE34E7: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: 22080, W000)