Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250815220006\orcl_w000_14636_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:35466M/63366M, Ph+PgF:40798M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 14636, image: ORACLE.EXE (W000) *** 2025-08-15 22:00:06.566 *** SESSION ID:(201.8261) 2025-08-15 22:00:06.566 *** 2025-08-15 22:00:06.566 Process diagnostic dump for ORACLE.EXE (W000), OS id=14636, pid: 25, proc_ser: 68, sid: 201, sess_ser: 8261 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=115 seq_num=116 snap_id=1 wait times: snap=2.687306 sec, exc=2.687306 sec, total=2.687306 sec wait times: max=5.000000 sec, heur=9 min 33 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=114 seq_num=115 snap_id=1 wait times: snap=5.014722 sec, exc=5.014722 sec, total=5.014722 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=113 seq_num=114 snap_id=1 wait times: snap=5.014737 sec, exc=5.014737 sec, total=5.014737 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=112 seq_num=113 snap_id=1 wait times: snap=5.004652 sec, exc=5.004652 sec, total=5.004652 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=111 seq_num=112 snap_id=1 wait times: snap=5.007228 sec, exc=5.007228 sec, total=5.007228 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=110 seq_num=111 snap_id=1 wait times: snap=5.009524 sec, exc=5.009524 sec, total=5.009524 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=109 seq_num=110 snap_id=1 wait times: snap=4.999982 sec, exc=4.999982 sec, total=4.999982 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=108 seq_num=109 snap_id=1 wait times: snap=5.014723 sec, exc=5.014723 sec, total=5.014723 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=107 seq_num=108 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=5.004624 sec, exc=5.004624 sec, total=5.004624 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=105 seq_num=106 snap_id=1 wait times: snap=5.010219 sec, exc=5.010219 sec, total=5.010219 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 8261 --------------------------------------------------- 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, 21:58:06 - 22:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-15 22:00:06.566 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 14636, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-15 19:30:14.386018 :C02CDEB2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 19:30:14.386018 :C02CDEB3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 19:30:14.386511 :C02CDEB4: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-08-15 19:30:14.386555 :C02CDEBC: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-08-15 19:40:15.271045 :C02DA4A0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 19:40:15.299800 :C02DA4A3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 19:40:15.286 2025-08-15 19:40:15.299802 :C02DA4A4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21424 pso_num=25 pso_serial#=54 2025-08-15 19:40:15.299943 :C02DA4A5:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21424 (legacy spawn) 2025-08-15 19:40:15.319301 :C02DA4A6: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-08-15 19:40:15.319469 :C02DA4AB: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-08-15 19:40:15.320377 :C02DA4B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 19:40:15.329198 :C02DA4B3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 19:40:15.318 2025-08-15 19:40:15.329199 :C02DA4B4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20424 pso_num=25 pso_serial#=55 2025-08-15 19:40:15.329337 :C02DA4B5:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20424 (legacy spawn) 2025-08-15 19:40:15.335778 :C02DA4B6: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-08-15 19:40:15.335944 :C02DA4BE: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-08-15 19:40:15.336766 :C02DA4C0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 19:40:15.650749 :C02DA4F8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 19:40:15.646 2025-08-15 19:40:15.650751 :C02DA4F9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20260 pso_num=25 pso_serial#=56 2025-08-15 19:40:15.650891 :C02DA4FA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20260 osp_idx=23 osp_ver=241653 osp_pg=0 (spawn #492147) 2025-08-15 19:40:15.650891 :C02DA4FB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 19:40:15.650891 :C02DA4FC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=3967484954 2025-08-15 19:40:15.650892 :C02DA4FD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 19:40:15.650892 :C02DA4FE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 19:40:15.650892 :C02DA4FF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 19:40:15.650893 :C02DA500:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 19:40:15.650893 :C02DA501:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 19:40:15.651407 :C02DA502: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-08-15 19:40:15.651450 :C02DA50A: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-08-15 19:50:16.594833 :C02E6A4B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 19:50:17.021530 :C02E6A8E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 19:50:17.016 2025-08-15 19:50:17.021532 :C02E6A8F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1216 pso_num=25 pso_serial#=57 2025-08-15 19:50:17.021669 :C02E6A90:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1216 osp_idx=23 osp_ver=241654 osp_pg=0 (spawn #492169) 2025-08-15 19:50:17.021670 :C02E6A91:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 19:50:17.021670 :C02E6A92:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=3968086329 2025-08-15 19:50:17.021671 :C02E6A93:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 19:50:17.021671 :C02E6A94:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 19:50:17.021671 :C02E6A95:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 19:50:17.021672 :C02E6A96:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 19:50:17.021672 :C02E6A97:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 19:50:17.022183 :C02E6A98: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-08-15 19:50:17.022230 :C02E6AA0: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-08-15 19:58:52.810186 :C02F1379: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-08-15 20:00:18.275076 :C02F306C: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-08-15 20:10:19.242286 :C030013B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 20:10:19.731240 :C0300181:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 20:10:19.726 2025-08-15 20:10:19.731243 :C0300182:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21168 pso_num=25 pso_serial#=58 2025-08-15 20:10:19.731378 :C0300183:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21168 osp_idx=23 osp_ver=241655 osp_pg=0 (spawn #492218) 2025-08-15 20:10:19.731379 :C0300184:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 20:10:19.731379 :C0300185:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=3969289033 2025-08-15 20:10:19.731379 :C0300186:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 20:10:19.731380 :C0300187:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 20:10:19.731380 :C0300188:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 20:10:19.731380 :C0300189:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 20:10:19.731381 :C030018A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 20:10:19.731891 :C030018B: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-08-15 20:10:19.731944 :C0300195: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-08-15 20:20:20.540969 :C030C5DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 20:20:20.998907 :C030C61F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 20:20:20.994 2025-08-15 20:20:20.998909 :C030C620:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20196 pso_num=25 pso_serial#=59 2025-08-15 20:20:20.999049 :C030C621:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20196 osp_idx=23 osp_ver=241656 osp_pg=0 (spawn #492240) 2025-08-15 20:20:20.999049 :C030C622:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 20:20:20.999050 :C030C623:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=3969890298 2025-08-15 20:20:20.999050 :C030C624:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 20:20:20.999051 :C030C625:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 20:20:20.999051 :C030C626:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 20:20:20.999051 :C030C627:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 20:20:20.999052 :C030C628:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 20:20:20.999536 :C030C629: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-08-15 20:20:20.999581 :C030C631: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-08-15 20:30:21.884345 :C0318B7E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 20:30:22.274436 :C0318BB5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 20:30:22.269 2025-08-15 20:30:22.274438 :C0318BB6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6744 pso_num=25 pso_serial#=60 2025-08-15 20:30:22.274576 :C0318BB7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6744 osp_idx=23 osp_ver=241657 osp_pg=0 (spawn #492263) 2025-08-15 20:30:22.274577 :C0318BB8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 20:30:22.274577 :C0318BB9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=3970491579 2025-08-15 20:30:22.274580 :C0318BBA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 20:30:22.274580 :C0318BBB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 20:30:22.274581 :C0318BBC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 20:30:22.274581 :C0318BBD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 20:30:22.274581 :C0318BBE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 20:30:22.275082 :C0318BBF: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-08-15 20:30:22.275126 :C0318BC7: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-08-15 20:40:23.179461 :C03250B7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 20:40:23.621913 :C03250F0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 20:40:23.616 2025-08-15 20:40:23.621915 :C03250F1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13660 pso_num=25 pso_serial#=61 2025-08-15 20:40:23.622057 :C03250F2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13660 osp_idx=23 osp_ver=241658 osp_pg=0 (spawn #492286) 2025-08-15 20:40:23.622058 :C03250F3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 20:40:23.622058 :C03250F4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=3971092923 2025-08-15 20:40:23.622059 :C03250F5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 20:40:23.622059 :C03250F6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 20:40:23.622060 :C03250F7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 20:40:23.622060 :C03250F8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 20:40:23.622060 :C03250F9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 20:40:23.622566 :C03250FA: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-08-15 20:40:23.622615 :C0325102: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-08-15 20:50:24.510612 :C0331578:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 20:50:24.984203 :C03315BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 20:50:24.980 2025-08-15 20:50:24.984206 :C03315BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16800 pso_num=25 pso_serial#=62 2025-08-15 20:50:24.984367 :C03315BE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16800 osp_idx=23 osp_ver=241659 osp_pg=0 (spawn #492308) 2025-08-15 20:50:24.984367 :C03315BF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 20:50:24.984368 :C03315C0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=3971694298 2025-08-15 20:50:24.984368 :C03315C1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 20:50:24.984368 :C03315C2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 20:50:24.984369 :C03315C3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 20:50:24.984369 :C03315C4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 20:50:24.984369 :C03315C5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 20:50:24.984869 :C03315C6: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-08-15 20:50:24.984914 :C03315D0: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-08-15 21:00:25.867424 :C033DC71:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 21:00:26.387746 :C033DCA5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 21:00:26.383 2025-08-15 21:00:26.387748 :C033DCA6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17496 pso_num=25 pso_serial#=63 2025-08-15 21:00:26.387882 :C033DCA7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17496 osp_idx=23 osp_ver=241660 osp_pg=0 (spawn #492333) 2025-08-15 21:00:26.387882 :C033DCA8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 21:00:26.387883 :C033DCA9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=239 time=3972295689 2025-08-15 21:00:26.387883 :C033DCAA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 21:00:26.387884 :C033DCAB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 21:00:26.387884 :C033DCAC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 21:00:26.387884 :C033DCAD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 21:00:26.387885 :C033DCAE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 21:00:26.388367 :C033DCAF: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-08-15 21:00:26.388411 :C033DCB6: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-08-15 21:10:27.302920 :C034AB98:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 21:10:27.760680 :C034ABD8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 21:10:27.755 2025-08-15 21:10:27.760682 :C034ABD9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21000 pso_num=25 pso_serial#=64 2025-08-15 21:10:27.760822 :C034ABDA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21000 osp_idx=23 osp_ver=241661 osp_pg=0 (spawn #492357) 2025-08-15 21:10:27.760822 :C034ABDB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 21:10:27.760823 :C034ABDC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=240 time=3972897064 2025-08-15 21:10:27.760823 :C034ABDD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 21:10:27.760823 :C034ABDE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 21:10:27.760824 :C034ABDF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 21:10:27.760824 :C034ABE0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 21:10:27.760825 :C034ABE1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 21:10:27.761326 :C034ABE2: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-08-15 21:10:27.761387 :C034ABEC: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-08-15 21:20:28.662397 :C0357123:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 21:20:29.089179 :C035715A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 21:20:29.084 2025-08-15 21:20:29.089182 :C035715B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19980 pso_num=25 pso_serial#=65 2025-08-15 21:20:29.089330 :C035715C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19980 osp_idx=23 osp_ver=241662 osp_pg=0 (spawn #492379) 2025-08-15 21:20:29.089331 :C035715D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 21:20:29.089331 :C035715E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=241 time=3973498392 2025-08-15 21:20:29.089331 :C035715F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 21:20:29.089332 :C0357160:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 21:20:29.089332 :C0357161:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 21:20:29.089333 :C0357162:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 21:20:29.089333 :C0357163:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 21:20:29.089865 :C0357164: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-08-15 21:20:29.089913 :C035716B: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-08-15 21:30:30.033073 :C03636AB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 21:30:30.469771 :C03636EE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 21:30:30.465 2025-08-15 21:30:30.469774 :C03636EF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21080 pso_num=25 pso_serial#=66 2025-08-15 21:30:30.469912 :C03636F0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21080 osp_idx=23 osp_ver=241663 osp_pg=0 (spawn #492402) 2025-08-15 21:30:30.469912 :C03636F1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 21:30:30.469913 :C03636F2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=242 time=3974099783 2025-08-15 21:30:30.469913 :C03636F3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 21:30:30.469913 :C03636F4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 21:30:30.469914 :C03636F5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 21:30:30.469914 :C03636F6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 21:30:30.469915 :C03636F7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 21:30:30.470410 :C03636F8: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-08-15 21:30:30.470460 :C0363702: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-08-15 21:40:31.356813 :C036FBBB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 21:40:31.736450 :C036FBF3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 21:40:31.731 2025-08-15 21:40:31.736452 :C036FBF4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20648 pso_num=25 pso_serial#=67 2025-08-15 21:40:31.736589 :C036FBF5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20648 osp_idx=23 osp_ver=241664 osp_pg=0 (spawn #492425) 2025-08-15 21:40:31.736590 :C036FBF6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 21:40:31.736591 :C036FBF7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=243 time=3974701048 2025-08-15 21:40:31.736591 :C036FBF8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 21:40:31.736591 :C036FBF9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 21:40:31.736592 :C036FBFA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 21:40:31.736592 :C036FBFB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 21:40:31.736593 :C036FBFC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 21:40:31.737087 :C036FBFD: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-08-15 21:40:31.737134 :C036FC06: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-08-15 21:50:32.692019 :C037C11C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-15 21:50:32.978196 :C037C153:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-15 21:50:32.973 2025-08-15 21:50:32.978198 :C037C154:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14636 pso_num=25 pso_serial#=68 2025-08-15 21:50:32.978334 :C037C155:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14636 osp_idx=23 osp_ver=241665 osp_pg=0 (spawn #492447) 2025-08-15 21:50:32.978334 :C037C156:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-15 21:50:32.978335 :C037C157:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=244 time=3975302283 2025-08-15 21:50:32.978335 :C037C158:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-15 21:50:32.978336 :C037C159:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-15 21:50:32.978336 :C037C15A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-15 21:50:32.978336 :C037C15B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-15 21:50:32.978337 :C037C15C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-15 21:50:32.978831 :C037C15D: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-08-15 21:50:32.978876 :C037C165: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: 14636, W000)