Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250912082324\orcl_w000_22904_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:34824M/63366M, Ph+PgF:39501M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22904, image: ORACLE.EXE (W000) *** 2025-09-12 08:23:24.722 *** SESSION ID:(201.59271) 2025-09-12 08:23:24.722 *** 2025-09-12 08:23:24.722 Process diagnostic dump for ORACLE.EXE (W000), OS id=22904, pid: 25, proc_ser: 35, sid: 201, sess_ser: 59271 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=4.414906 sec, exc=4.414906 sec, total=4.414906 sec wait times: max=5.000000 sec, heur=39.442238 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.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.001893 sec, exc=5.001893 sec, total=5.001893 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.013400 sec, exc=5.013400 sec, total=5.013400 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=5.004623 sec, exc=5.004623 sec, total=5.004623 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=3 seq_num=4 snap_id=1 wait times: snap=5.007233 sec, exc=5.007233 sec, total=5.007233 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=2 seq_num=3 snap_id=1 wait times: snap=5.001443 sec, exc=5.001443 sec, total=5.001443 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=4.993956 sec, exc=4.993956 sec, total=4.993956 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.004076 sec of elapsed time 7: waited for 'class slave wait' slave id=0x11f4fe90, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 59271 --------------------------------------------------- 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 --------------------------------------------------- [40 samples, 08:22:45 - 08:23:24] idle wait at each sample [session created at: 08:22:45] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-12 08:23:24.722 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22904, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-12 05:52:27.238416 :CC49084B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 05:52:27.238416 :CC49084C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 05:52:27.238904 :CC49084D: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-12 05:52:27.238951 :CC490855: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-12 06:02:28.080692 :CC49CEC3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 06:02:28.429250 :CC49CF26:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 06:02:28.424 2025-09-12 06:02:28.429252 :CC49CF27:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22372 pso_num=25 pso_serial#=22 2025-09-12 06:02:28.429394 :CC49CF28:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22372 osp_idx=23 osp_ver=293540 osp_pg=0 (spawn #583621) 2025-09-12 06:02:28.429394 :CC49CF29: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-12 06:02:28.429395 :CC49CF2A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=141 time=2042599737 2025-09-12 06:02:28.429395 :CC49CF2B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 06:02:28.429396 :CC49CF2C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 06:02:28.429396 :CC49CF2D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 06:02:28.429396 :CC49CF2E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 06:02:28.429396 :CC49CF2F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 06:02:28.429902 :CC49CF30: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-12 06:02:28.429950 :CC49CF38: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-12 06:12:29.305731 :CC4A99E9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 06:12:29.701460 :CC4A9A46:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 06:12:29.696 2025-09-12 06:12:29.701462 :CC4A9A47:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20336 pso_num=25 pso_serial#=23 2025-09-12 06:12:29.701607 :CC4A9A48:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20336 osp_idx=23 osp_ver=293541 osp_pg=0 (spawn #583645) 2025-09-12 06:12:29.701608 :CC4A9A49: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-12 06:12:29.701608 :CC4A9A4A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=142 time=2043201002 2025-09-12 06:12:29.701608 :CC4A9A4B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 06:12:29.701609 :CC4A9A4C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 06:12:29.701609 :CC4A9A4D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 06:12:29.701610 :CC4A9A4E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 06:12:29.701610 :CC4A9A4F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 06:12:29.702117 :CC4A9A50: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-12 06:12:29.702164 :CC4A9A58: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-12 06:22:30.552929 :CC4B60BF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 06:22:30.901676 :CC4B611F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 06:22:30.896 2025-09-12 06:22:30.901678 :CC4B6120:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23264 pso_num=25 pso_serial#=24 2025-09-12 06:22:30.901819 :CC4B6121:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23264 osp_idx=23 osp_ver=293542 osp_pg=0 (spawn #583668) 2025-09-12 06:22:30.901820 :CC4B6122: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-12 06:22:30.901821 :CC4B6123:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=143 time=2043802205 2025-09-12 06:22:30.901821 :CC4B6124:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 06:22:30.901821 :CC4B6125:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 06:22:30.901821 :CC4B6126:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 06:22:30.901822 :CC4B6127:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 06:22:30.901822 :CC4B6128:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 06:22:30.902339 :CC4B6129: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-12 06:22:30.902390 :CC4B6130: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-12 06:32:31.592769 :CC4C2404:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 06:32:32.144396 :CC4C246C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 06:32:32.139 2025-09-12 06:32:32.144398 :CC4C246D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19064 pso_num=25 pso_serial#=25 2025-09-12 06:32:32.144534 :CC4C246E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19064 osp_idx=23 osp_ver=293543 osp_pg=0 (spawn #583690) 2025-09-12 06:32:32.144535 :CC4C246F: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-12 06:32:32.144535 :CC4C2470:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=144 time=2044403455 2025-09-12 06:32:32.144536 :CC4C2471:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 06:32:32.144537 :CC4C2472:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 06:32:32.144537 :CC4C2473:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 06:32:32.144537 :CC4C2474:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 06:32:32.144538 :CC4C2475:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 06:32:32.145030 :CC4C2476: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-12 06:32:32.145073 :CC4C247D: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-12 06:42:32.939532 :CC4CEA6F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 06:42:33.398018 :CC4CEAC9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 06:42:33.392 2025-09-12 06:42:33.398019 :CC4CEACA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23264 pso_num=25 pso_serial#=26 2025-09-12 06:42:33.398189 :CC4CEACB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23264 osp_idx=23 osp_ver=293544 osp_pg=0 (spawn #583713) 2025-09-12 06:42:33.398190 :CC4CEACC: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-12 06:42:33.398191 :CC4CEACD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=145 time=2045004705 2025-09-12 06:42:33.398191 :CC4CEACE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 06:42:33.398192 :CC4CEACF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 06:42:33.398192 :CC4CEAD0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 06:42:33.398193 :CC4CEAD1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 06:42:33.398193 :CC4CEAD2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 06:42:33.398779 :CC4CEAD3: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-12 06:42:33.398841 :CC4CEAD9: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-12 06:52:34.195354 :CC4DAE95:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 06:52:34.668861 :CC4DAEEE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 06:52:34.664 2025-09-12 06:52:34.668863 :CC4DAEEF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18376 pso_num=25 pso_serial#=27 2025-09-12 06:52:34.669003 :CC4DAEF0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18376 osp_idx=23 osp_ver=293545 osp_pg=0 (spawn #583736) 2025-09-12 06:52:34.669004 :CC4DAEF1: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-12 06:52:34.669004 :CC4DAEF2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=146 time=2045605971 2025-09-12 06:52:34.669004 :CC4DAEF3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 06:52:34.669005 :CC4DAEF4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 06:52:34.669005 :CC4DAEF5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 06:52:34.669006 :CC4DAEF6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 06:52:34.669006 :CC4DAEF7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 06:52:34.669487 :CC4DAEF8: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-12 06:52:34.669535 :CC4DAF00: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-12 07:02:35.543718 :CC4E74A9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 07:02:35.861901 :CC4E750C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 07:02:35.857 2025-09-12 07:02:35.861903 :CC4E750D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23948 pso_num=25 pso_serial#=28 2025-09-12 07:02:35.862042 :CC4E750E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23948 osp_idx=23 osp_ver=293546 osp_pg=0 (spawn #583761) 2025-09-12 07:02:35.862042 :CC4E750F: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-12 07:02:35.862043 :CC4E7510:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=147 time=2046207158 2025-09-12 07:02:35.862043 :CC4E7511:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 07:02:35.862043 :CC4E7512:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 07:02:35.862044 :CC4E7513:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 07:02:35.862044 :CC4E7514:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 07:02:35.862044 :CC4E7515:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 07:02:35.862534 :CC4E7516: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-12 07:02:35.862582 :CC4E751F: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-12 07:12:36.687082 :CC4F3E2B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 07:12:37.160831 :CC4F3E7A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 07:12:37.156 2025-09-12 07:12:37.160834 :CC4F3E7B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23264 pso_num=25 pso_serial#=29 2025-09-12 07:12:37.160975 :CC4F3E7C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23264 osp_idx=23 osp_ver=293547 osp_pg=0 (spawn #583784) 2025-09-12 07:12:37.160976 :CC4F3E7D: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-12 07:12:37.160976 :CC4F3E7E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=148 time=2046808471 2025-09-12 07:12:37.160976 :CC4F3E7F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 07:12:37.160977 :CC4F3E80:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 07:12:37.160977 :CC4F3E81:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 07:12:37.160977 :CC4F3E82:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 07:12:37.160978 :CC4F3E83:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 07:12:37.161470 :CC4F3E84: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-12 07:12:37.161524 :CC4F3E8E: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-12 07:22:38.059150 :CC500478:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 07:22:38.360883 :CC5004E9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 07:22:38.356 2025-09-12 07:22:38.360886 :CC5004EA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24444 pso_num=25 pso_serial#=30 2025-09-12 07:22:38.361043 :CC5004EB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24444 osp_idx=23 osp_ver=293548 osp_pg=0 (spawn #583807) 2025-09-12 07:22:38.361043 :CC5004EC: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-12 07:22:38.361044 :CC5004ED:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=149 time=2047409658 2025-09-12 07:22:38.361044 :CC5004EE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 07:22:38.361045 :CC5004EF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 07:22:38.361045 :CC5004F0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 07:22:38.361045 :CC5004F1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 07:22:38.361045 :CC5004F2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 07:22:38.361551 :CC5004F3: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-12 07:22:38.361597 :CC5004FD: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-12 07:23:18.741026 :CC50114E: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-12 07:32:39.496738 :CC50C88F: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-12 07:42:40.274216 :CC518C42:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 07:42:40.716375 :CC518CD1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 07:42:40.711 2025-09-12 07:42:40.716377 :CC518CD2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24852 pso_num=25 pso_serial#=31 2025-09-12 07:42:40.716511 :CC518CD3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24852 osp_idx=23 osp_ver=293549 osp_pg=0 (spawn #583851) 2025-09-12 07:42:40.716511 :CC518CD4: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-12 07:42:40.716512 :CC518CD5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=150 time=2048612018 2025-09-12 07:42:40.716512 :CC518CD6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 07:42:40.716513 :CC518CD7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 07:42:40.716513 :CC518CD8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 07:42:40.716513 :CC518CD9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 07:42:40.716514 :CC518CDA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 07:42:40.717049 :CC518CDB: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-12 07:42:40.717101 :CC518CE3: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-12 07:52:41.537038 :CC525009:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 07:52:41.792149 :CC52507C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 07:52:41.787 2025-09-12 07:52:41.792151 :CC52507D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23808 pso_num=25 pso_serial#=32 2025-09-12 07:52:41.792298 :CC52507E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23808 osp_idx=23 osp_ver=293550 osp_pg=0 (spawn #583874) 2025-09-12 07:52:41.792299 :CC52507F: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-12 07:52:41.792299 :CC525080:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=151 time=2049213096 2025-09-12 07:52:41.792299 :CC525081:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 07:52:41.792300 :CC525082:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 07:52:41.792300 :CC525083:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 07:52:41.792300 :CC525084:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 07:52:41.792301 :CC525085:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 07:52:41.792808 :CC525086: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-12 07:52:41.792856 :CC52508E: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-12 08:02:42.604814 :CC53167D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 08:02:42.906492 :CC53170B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 08:02:42.902 2025-09-12 08:02:42.906494 :CC53170C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25304 pso_num=25 pso_serial#=33 2025-09-12 08:02:42.906632 :CC53170D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25304 osp_idx=23 osp_ver=293551 osp_pg=0 (spawn #583899) 2025-09-12 08:02:42.906633 :CC53170E: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-12 08:02:42.906633 :CC53170F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=152 time=2049814205 2025-09-12 08:02:42.906634 :CC531710:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 08:02:42.906634 :CC531711:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 08:02:42.906635 :CC531712:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 08:02:42.906635 :CC531713:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 08:02:42.906635 :CC531714:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 08:02:42.907128 :CC531715: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-12 08:02:42.907177 :CC53171D: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-12 08:12:43.762952 :CC53E3B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 08:12:44.111545 :CC53E426:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 08:12:44.107 2025-09-12 08:12:44.111547 :CC53E427:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21728 pso_num=25 pso_serial#=34 2025-09-12 08:12:44.111691 :CC53E428:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21728 osp_idx=23 osp_ver=293552 osp_pg=0 (spawn #583922) 2025-09-12 08:12:44.111693 :CC53E429: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-12 08:12:44.111693 :CC53E42A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=2050415408 2025-09-12 08:12:44.111694 :CC53E42B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 08:12:44.111694 :CC53E42C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 08:12:44.111694 :CC53E42D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 08:12:44.111695 :CC53E42E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 08:12:44.111695 :CC53E42F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 08:12:44.112190 :CC53E430: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-12 08:12:44.112235 :CC53E437: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-12 08:22:45.028573 :CC54A7B7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-12 08:22:45.283405 :CC54A824:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-12 08:22:45.278 2025-09-12 08:22:45.283407 :CC54A825:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22904 pso_num=25 pso_serial#=35 2025-09-12 08:22:45.283542 :CC54A826:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22904 osp_idx=23 osp_ver=293553 osp_pg=0 (spawn #583945) 2025-09-12 08:22:45.283543 :CC54A827: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-12 08:22:45.283543 :CC54A828:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=2051016580 2025-09-12 08:22:45.283544 :CC54A829:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-12 08:22:45.283544 :CC54A82A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-12 08:22:45.283544 :CC54A82B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-12 08:22:45.283545 :CC54A82C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-12 08:22:45.283545 :CC54A82D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-12 08:22:45.284039 :CC54A82E: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-12 08:22:45.284089 :CC54A835: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: 22904, W000)