Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906060004\orcl_w000_17932_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:34999M/63366M, Ph+PgF:39839M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17932, image: ORACLE.EXE (W000) *** 2025-09-06 06:00:04.512 *** SESSION ID:(201.34835) 2025-09-06 06:00:04.512 *** 2025-09-06 06:00:04.512 Process diagnostic dump for ORACLE.EXE (W000), OS id=17932, pid: 25, proc_ser: 183, sid: 201, sess_ser: 34835 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=0.806294 sec, exc=0.806294 sec, total=0.806294 sec wait times: max=5.000000 sec, heur=4 min 46 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=57 seq_num=58 snap_id=1 wait times: snap=5.011015 sec, exc=5.011015 sec, total=5.011015 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=56 seq_num=57 snap_id=1 wait times: snap=5.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.014746 sec, exc=5.014746 sec, total=5.014746 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=54 seq_num=55 snap_id=1 wait times: snap=5.007243 sec, exc=5.007243 sec, total=5.007243 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: 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.001405 sec, exc=5.001405 sec, total=5.001405 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: 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.000294 sec, exc=5.000294 sec, total=5.000294 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: 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.009258 sec, exc=5.009258 sec, total=5.009258 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=50 seq_num=51 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.000026 sec of elapsed time 8: 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.004636 sec, exc=5.004636 sec, total=5.004636 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 9: 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.015232 sec, exc=5.015232 sec, total=5.015232 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 201 serial 34835 --------------------------------------------------- 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, 05:58:04 - 06:00:04] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-06 06:00:04.512 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17932, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 03:45:02.866845 :C999D7ED:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 03:45:02.866845 :C999D7EE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 03:45:02.867329 :C999D7EF: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-06 03:45:02.867379 :C999D7F9: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-06 03:55:03.668714 :C99A9CDA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 03:55:03.947103 :C99A9D1C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 03:55:03.938 2025-09-06 03:55:03.947105 :C99A9D1D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23544 pso_num=25 pso_serial#=168 2025-09-06 03:55:03.947246 :C99A9D1E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23544 (legacy spawn) 2025-09-06 03:55:03.953827 :C99A9D1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 03:55:03.954006 :C99A9D24:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 03:55:03.954794 :C99A9D29:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 03:55:03.961450 :C99A9D2C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 03:55:03.954 2025-09-06 03:55:03.961452 :C99A9D2D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19912 pso_num=25 pso_serial#=169 2025-09-06 03:55:03.961592 :C99A9D2E:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19912 (legacy spawn) 2025-09-06 03:55:03.968971 :C99A9D2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 03:55:03.969131 :C99A9D37:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 03:55:03.969997 :C99A9D39:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 03:55:04.067653 :C99A9D74:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 03:55:04.063 2025-09-06 03:55:04.067655 :C99A9D75:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23320 pso_num=25 pso_serial#=170 2025-09-06 03:55:04.067794 :C99A9D76:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23320 osp_idx=23 osp_ver=282468 osp_pg=0 (spawn #563311) 2025-09-06 03:55:04.067795 :C99A9D77: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-06 03:55:04.067796 :C99A9D78:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=10 time=1516555377 2025-09-06 03:55:04.067796 :C99A9D79:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 03:55:04.067797 :C99A9D7A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 03:55:04.067797 :C99A9D7B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 03:55:04.067797 :C99A9D7C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 03:55:04.067798 :C99A9D7D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 03:55:04.068330 :C99A9D7E: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-06 03:55:04.068378 :C99A9D87: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-06 04:05:04.710591 :C99B658C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 04:05:05.131827 :C99B65F7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 04:05:05.127 2025-09-06 04:05:05.131829 :C99B65F8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21392 pso_num=25 pso_serial#=171 2025-09-06 04:05:05.131967 :C99B65F9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21392 osp_idx=23 osp_ver=282469 osp_pg=0 (spawn #563337) 2025-09-06 04:05:05.131968 :C99B65FA: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-06 04:05:05.131968 :C99B65FB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=11 time=1517156440 2025-09-06 04:05:05.131968 :C99B65FC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 04:05:05.131969 :C99B65FD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 04:05:05.131969 :C99B65FE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 04:05:05.131969 :C99B65FF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 04:05:05.131970 :C99B6600:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 04:05:05.132457 :C99B6601: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-06 04:05:05.132500 :C99B6609: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-06 04:15:05.961273 :C99C2EE9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 04:15:06.325559 :C99C2F32:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 04:15:06.321 2025-09-06 04:15:06.325561 :C99C2F33:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23684 pso_num=25 pso_serial#=172 2025-09-06 04:15:06.325701 :C99C2F34:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23684 osp_idx=23 osp_ver=282470 osp_pg=0 (spawn #563359) 2025-09-06 04:15:06.325702 :C99C2F35: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-06 04:15:06.325703 :C99C2F36:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=12 time=1517757627 2025-09-06 04:15:06.325703 :C99C2F37:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 04:15:06.325704 :C99C2F38:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 04:15:06.325704 :C99C2F39:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 04:15:06.325704 :C99C2F3A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 04:15:06.325705 :C99C2F3B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 04:15:06.326208 :C99C2F3C: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-06 04:15:06.326257 :C99C2F44: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-06 04:25:07.152021 :C99CF76C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 04:25:07.453907 :C99CF7B0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 04:25:07.449 2025-09-06 04:25:07.453909 :C99CF7B1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22308 pso_num=25 pso_serial#=173 2025-09-06 04:25:07.454052 :C99CF7B2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22308 osp_idx=23 osp_ver=282471 osp_pg=0 (spawn #563382) 2025-09-06 04:25:07.454053 :C99CF7B3: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-06 04:25:07.454053 :C99CF7B4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=1518358752 2025-09-06 04:25:07.454054 :C99CF7B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 04:25:07.454054 :C99CF7B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 04:25:07.454055 :C99CF7B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 04:25:07.454055 :C99CF7B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 04:25:07.454055 :C99CF7B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 04:25:07.454565 :C99CF7BA: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-06 04:25:07.454614 :C99CF7C1: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-06 04:35:08.255962 :C99DBD51:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 04:35:08.666997 :C99DBDA0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 04:35:08.662 2025-09-06 04:35:08.666999 :C99DBDA1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21416 pso_num=25 pso_serial#=174 2025-09-06 04:35:08.667134 :C99DBDA2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21416 osp_idx=23 osp_ver=282472 osp_pg=0 (spawn #563405) 2025-09-06 04:35:08.667134 :C99DBDA3: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-06 04:35:08.667135 :C99DBDA4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=1518959971 2025-09-06 04:35:08.667135 :C99DBDA5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 04:35:08.667135 :C99DBDA6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 04:35:08.667136 :C99DBDA7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 04:35:08.667136 :C99DBDA8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 04:35:08.667136 :C99DBDA9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 04:35:08.667686 :C99DBDAA: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-06 04:35:08.667731 :C99DBDB4: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-06 04:45:09.485288 :C99E82DD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 04:45:09.849336 :C99E832C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 04:45:09.844 2025-09-06 04:45:09.849337 :C99E832D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18948 pso_num=25 pso_serial#=175 2025-09-06 04:45:09.849473 :C99E832E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18948 osp_idx=23 osp_ver=282473 osp_pg=0 (spawn #563427) 2025-09-06 04:45:09.849474 :C99E832F: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-06 04:45:09.849474 :C99E8330:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=1519561158 2025-09-06 04:45:09.849475 :C99E8331:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 04:45:09.849475 :C99E8332:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 04:45:09.849476 :C99E8333:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 04:45:09.849476 :C99E8334:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 04:45:09.849477 :C99E8335:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 04:45:09.850046 :C99E8336: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-06 04:45:09.850090 :C99E833E: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-06 04:55:10.673818 :C99F4968:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 04:55:10.991099 :C99F49A8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 04:55:10.986 2025-09-06 04:55:10.991101 :C99F49A9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23600 pso_num=25 pso_serial#=176 2025-09-06 04:55:10.991238 :C99F49AA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23600 osp_idx=23 osp_ver=282474 osp_pg=0 (spawn #563450) 2025-09-06 04:55:10.991239 :C99F49AB: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-06 04:55:10.991239 :C99F49AC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=1520162299 2025-09-06 04:55:10.991240 :C99F49AD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 04:55:10.991240 :C99F49AE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 04:55:10.991240 :C99F49AF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 04:55:10.991241 :C99F49B0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 04:55:10.991241 :C99F49B1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 04:55:10.991738 :C99F49B2: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-06 04:55:10.991781 :C99F49B9: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-06 05:05:11.737652 :C9A0121C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:05:12.260210 :C9A0126A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:05:12.255 2025-09-06 05:05:12.260212 :C9A0126B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21176 pso_num=25 pso_serial#=177 2025-09-06 05:05:12.260356 :C9A0126C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21176 osp_idx=23 osp_ver=282475 osp_pg=0 (spawn #563476) 2025-09-06 05:05:12.260357 :C9A0126D: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-06 05:05:12.260357 :C9A0126E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=17 time=1520763565 2025-09-06 05:05:12.260358 :C9A0126F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:05:12.260358 :C9A01270:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:05:12.260358 :C9A01271:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:05:12.260359 :C9A01272:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:05:12.260359 :C9A01273:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:05:12.260857 :C9A01274: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-06 05:05:12.260900 :C9A0127C: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-06 05:15:13.031229 :C9A0DE31:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:15:13.311268 :C9A0DE3F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:15:13.297 2025-09-06 05:15:13.311272 :C9A0DE40:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20932 pso_num=25 pso_serial#=178 2025-09-06 05:15:13.311466 :C9A0DE41:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20932 (legacy spawn) 2025-09-06 05:15:13.318678 :C9A0DE42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 05:15:13.318883 :C9A0DE47:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 05:15:13.319814 :C9A0DE4C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:15:13.329548 :C9A0DE4F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:15:13.328 2025-09-06 05:15:13.329550 :C9A0DE50:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12820 pso_num=25 pso_serial#=179 2025-09-06 05:15:13.329706 :C9A0DE51:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12820 (legacy spawn) 2025-09-06 05:15:13.336874 :C9A0DE52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-06 05:15:13.337079 :C9A0DE5B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-06 05:15:13.338069 :C9A0DE5C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:15:13.411317 :C9A0DE91:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:15:13.406 2025-09-06 05:15:13.411320 :C9A0DE92:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23500 pso_num=25 pso_serial#=180 2025-09-06 05:15:13.411466 :C9A0DE93:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23500 osp_idx=23 osp_ver=282478 osp_pg=0 (spawn #563498) 2025-09-06 05:15:13.411467 :C9A0DE94: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-06 05:15:13.411467 :C9A0DE95:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=18 time=1521364721 2025-09-06 05:15:13.411468 :C9A0DE96:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:15:13.411468 :C9A0DE97:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:15:13.411468 :C9A0DE98:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:15:13.411469 :C9A0DE99:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:15:13.411469 :C9A0DE9A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:15:13.411990 :C9A0DE9B: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-06 05:15:13.412039 :C9A0DEA2: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-06 05:23:47.837550 :C9A1871A: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-06 05:25:14.600388 :C9A1A3EC: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-06 05:35:15.433560 :C9A26A21:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:35:15.844380 :C9A26A80:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:35:15.839 2025-09-06 05:35:15.844382 :C9A26A81:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24188 pso_num=25 pso_serial#=181 2025-09-06 05:35:15.844527 :C9A26A82:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24188 osp_idx=23 osp_ver=282479 osp_pg=0 (spawn #563543) 2025-09-06 05:35:15.844528 :C9A26A83: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-06 05:35:15.844529 :C9A26A84:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=19 time=1522567143 2025-09-06 05:35:15.844529 :C9A26A85:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:35:15.844529 :C9A26A86:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:35:15.844530 :C9A26A87:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:35:15.844530 :C9A26A88:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:35:15.844530 :C9A26A89:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:35:15.845056 :C9A26A8A: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-06 05:35:15.845105 :C9A26A91: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-06 05:45:16.571594 :C9A32EDF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:45:17.013754 :C9A32F2D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:45:17.009 2025-09-06 05:45:17.013756 :C9A32F2E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23448 pso_num=25 pso_serial#=182 2025-09-06 05:45:17.013892 :C9A32F2F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23448 osp_idx=23 osp_ver=282480 osp_pg=0 (spawn #563565) 2025-09-06 05:45:17.013893 :C9A32F30: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-06 05:45:17.013893 :C9A32F31:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=20 time=1523168315 2025-09-06 05:45:17.013894 :C9A32F32:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:45:17.013894 :C9A32F33:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:45:17.013894 :C9A32F34:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:45:17.013895 :C9A32F35:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:45:17.013895 :C9A32F36:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:45:17.014387 :C9A32F37: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-06 05:45:17.014437 :C9A32F41: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-06 05:55:17.851563 :C9A3F4C8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-06 05:55:18.293758 :C9A3F513:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-06 05:55:18.289 2025-09-06 05:55:18.293760 :C9A3F514:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17932 pso_num=25 pso_serial#=183 2025-09-06 05:55:18.293908 :C9A3F515:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17932 osp_idx=23 osp_ver=282481 osp_pg=0 (spawn #563588) 2025-09-06 05:55:18.293909 :C9A3F516: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-06 05:55:18.293909 :C9A3F517:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=21 time=1523769596 2025-09-06 05:55:18.293909 :C9A3F518:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:55:18.293910 :C9A3F519:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:55:18.293910 :C9A3F51A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:55:18.293910 :C9A3F51B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:55:18.293911 :C9A3F51C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:55:18.294423 :C9A3F51D: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-06 05:55:18.294469 :C9A3F525: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 17932, W000)