Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250702220009\orcl_w000_11564_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:36856M/63366M, Ph+PgF:42872M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 11564, image: ORACLE.EXE (W000) *** 2025-07-02 22:00:09.746 *** SESSION ID:(201.5725) 2025-07-02 22:00:09.746 *** 2025-07-02 22:00:09.746 Process diagnostic dump for ORACLE.EXE (W000), OS id=11564, pid: 25, proc_ser: 244, sid: 201, sess_ser: 5725 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.408248 sec, exc=0.408248 sec, total=0.408248 sec wait times: max=5.000000 sec, heur=2 min 45 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.000035 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.009667 sec, exc=5.009667 sec, total=5.009667 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.014749 sec, exc=5.014749 sec, total=5.014749 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.010146 sec, exc=5.010146 sec, total=5.010146 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.009733 sec, exc=5.009733 sec, total=5.009733 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.003343 sec, exc=5.003343 sec, total=5.003343 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.000319 sec, exc=5.000319 sec, total=5.000319 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.008847 sec, exc=5.008847 sec, total=5.008847 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.010233 sec, exc=5.010233 sec, total=5.010233 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 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 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.014730 sec, exc=5.014730 sec, total=5.014730 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 201 serial 5725 --------------------------------------------------- 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:09 - 22:00:09] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-02 22:00:09.746 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 11564, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-02 21:35:21.625599 :ACB0A88F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:35:21.625599 :ACB0A890:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:35:21.626077 :ACB0A891:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:35:51.705269 :ACB0B2CE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:36:21.718015 :ACB0BCAB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:36:21.706 2025-07-02 21:36:21.718017 :ACB0BCAC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10908 pso_num=25 pso_serial#=231 2025-07-02 21:36:21.718152 :ACB0BCAD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10908 osp_idx=23 osp_ver=180118 osp_pg=0 (spawn #345772) 2025-07-02 21:36:21.718152 :ACB0BCAE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:36:21.718153 :ACB0BCAF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=172957611 2025-07-02 21:36:21.718153 :ACB0BCB0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:36:21.718154 :ACB0BCB1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:36:21.718154 :ACB0BCB2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:36:21.718155 :ACB0BCB3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:36:21.718155 :ACB0BCB4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:36:21.718640 :ACB0BCB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:36:46.749443 :ACB0C4E0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 21:37:16.816838 :ACB0CE52:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:37:21.802216 :ACB0D06C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:37:21.790 2025-07-02 21:37:21.802218 :ACB0D06D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11700 pso_num=25 pso_serial#=232 2025-07-02 21:37:21.802377 :ACB0D06E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11700 osp_idx=23 osp_ver=180119 osp_pg=0 (spawn #345775) 2025-07-02 21:37:21.802378 :ACB0D06F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:37:21.802378 :ACB0D070:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=173017689 2025-07-02 21:37:21.802379 :ACB0D071:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:37:21.802379 :ACB0D072:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:37:21.802380 :ACB0D073:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:37:21.802380 :ACB0D074:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:37:21.802380 :ACB0D075:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:37:21.802902 :ACB0D076:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:37:51.903811 :ACB0D9CC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:38:21.904764 :ACB0E3A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:38:21.894 2025-07-02 21:38:21.904766 :ACB0E3A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14700 pso_num=25 pso_serial#=233 2025-07-02 21:38:21.904894 :ACB0E3A6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14700 osp_idx=23 osp_ver=180120 osp_pg=0 (spawn #345777) 2025-07-02 21:38:21.904894 :ACB0E3A7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:38:21.904895 :ACB0E3A8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=173077798 2025-07-02 21:38:21.904895 :ACB0E3A9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:38:21.904896 :ACB0E3AA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:38:21.904896 :ACB0E3AB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:38:21.904897 :ACB0E3AC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:38:21.904897 :ACB0E3AD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:38:21.905363 :ACB0E3AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:38:51.955578 :ACB0ED5F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:39:21.956948 :ACB0F6FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:39:21.946 2025-07-02 21:39:21.956950 :ACB0F6FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15660 pso_num=25 pso_serial#=234 2025-07-02 21:39:21.957068 :ACB0F6FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15660 osp_idx=23 osp_ver=180121 osp_pg=0 (spawn #345779) 2025-07-02 21:39:21.957069 :ACB0F6FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:39:21.957069 :ACB0F6FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=173137845 2025-07-02 21:39:21.957069 :ACB0F6FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:39:21.957069 :ACB0F700:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:39:21.957070 :ACB0F701:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:39:21.957070 :ACB0F702:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:39:21.957070 :ACB0F703:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:39:21.957585 :ACB0F704:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:39:52.016122 :ACB1008F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:39:53.943530 :ACB10157:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:39:53.943 2025-07-02 21:39:53.943531 :ACB10158:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17440 pso_num=25 pso_serial#=235 2025-07-02 21:39:53.943618 :ACB10159:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17440 osp_idx=23 osp_ver=180122 osp_pg=0 (spawn #345781) 2025-07-02 21:39:53.943618 :ACB1015A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:39:53.943618 :ACB1015B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=173169829 2025-07-02 21:39:53.943619 :ACB1015C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:39:53.943619 :ACB1015D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:39:53.943619 :ACB1015E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:39:53.943619 :ACB1015F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:39:53.943620 :ACB10160:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:39:53.943957 :ACB10161:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:39:53.943993 :ACB10167:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 21:39:53.948015 :ACB10180:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-07-02 21:39:53.948058 :ACB10181:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-07-02 21:39:53.948326 :ACB10182:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-07-02 21:39:53.948342 :ACB10183:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-07-02 21:39:53.948565 :ACB10184:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-07-02 21:39:53.948579 :ACB10185:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-07-02 21:39:53.948795 :ACB10186:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-07-02 21:39:53.948807 :ACB10187:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-07-02 21:39:53.949015 :ACB10188:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-07-02 21:39:53.949027 :ACB10189:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-07-02 21:39:53.949237 :ACB1018A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-07-02 21:39:53.949250 :ACB1018B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-07-02 21:39:53.951585 :ACB1018C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:40:22.039701 :ACB10A83:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:40:22.027 2025-07-02 21:40:22.039703 :ACB10A84:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16340 pso_num=25 pso_serial#=236 2025-07-02 21:40:22.039818 :ACB10A85:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16340 osp_idx=23 osp_ver=180123 osp_pg=0 (spawn #345783) 2025-07-02 21:40:22.039818 :ACB10A86:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:40:22.039819 :ACB10A87:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=173197923 2025-07-02 21:40:22.039820 :ACB10A88:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:40:22.039820 :ACB10A89:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:40:22.039821 :ACB10A8A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:40:22.039821 :ACB10A8B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:40:22.039821 :ACB10A8C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:40:22.040440 :ACB10A8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:40:52.126172 :ACB11515:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:41:22.126481 :ACB11EEA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:41:22.115 2025-07-02 21:41:22.126484 :ACB11EEB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14420 pso_num=25 pso_serial#=237 2025-07-02 21:41:22.126595 :ACB11EEC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14420 osp_idx=23 osp_ver=180124 osp_pg=0 (spawn #345785) 2025-07-02 21:41:22.126596 :ACB11EED:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:41:22.126596 :ACB11EEE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=173258017 2025-07-02 21:41:22.126597 :ACB11EEF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:41:22.126597 :ACB11EF0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:41:22.126597 :ACB11EF1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:41:22.126597 :ACB11EF2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:41:22.126597 :ACB11EF3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:41:22.127104 :ACB11EF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:41:52.224301 :ACB12897:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:42:22.241608 :ACB1325A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:42:22.230 2025-07-02 21:42:22.241611 :ACB1325B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16772 pso_num=25 pso_serial#=238 2025-07-02 21:42:22.241770 :ACB1325C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16772 osp_idx=23 osp_ver=180125 osp_pg=0 (spawn #345787) 2025-07-02 21:42:22.241770 :ACB1325D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:42:22.241771 :ACB1325E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=100 time=173318126 2025-07-02 21:42:22.241771 :ACB1325F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:42:22.241772 :ACB13260:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:42:22.241772 :ACB13261:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:42:22.241772 :ACB13262:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:42:22.241773 :ACB13263:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:42:22.242270 :ACB13264:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:42:52.308420 :ACB13BF3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:43:22.281008 :ACB1460F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:43:22.270 2025-07-02 21:43:22.281010 :ACB14610:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2680 pso_num=25 pso_serial#=239 2025-07-02 21:43:22.281205 :ACB14611:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2680 osp_idx=23 osp_ver=180126 osp_pg=0 (spawn #345789) 2025-07-02 21:43:22.281205 :ACB14612:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:43:22.281206 :ACB14613:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=101 time=173378173 2025-07-02 21:43:22.281206 :ACB14614:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:43:22.281207 :ACB14615:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:43:22.281207 :ACB14616:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:43:22.281207 :ACB14617:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:43:22.281208 :ACB14618:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:43:22.281699 :ACB14619:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:43:52.378472 :ACB15009:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:44:22.354915 :ACB159C8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:44:22.344 2025-07-02 21:44:22.354917 :ACB159C9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15012 pso_num=25 pso_serial#=240 2025-07-02 21:44:22.355038 :ACB159CA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15012 osp_idx=23 osp_ver=180127 osp_pg=0 (spawn #345791) 2025-07-02 21:44:22.355039 :ACB159CB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:44:22.355040 :ACB159CC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=102 time=173438236 2025-07-02 21:44:22.355040 :ACB159CD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:44:22.355040 :ACB159CE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:44:22.355041 :ACB159CF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:44:22.355041 :ACB159D0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:44:22.355041 :ACB159D1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:44:22.355536 :ACB159D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:44:52.460300 :ACB16361:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:45:22.472015 :ACB16D4C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:45:22.460 2025-07-02 21:45:22.472017 :ACB16D4D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9912 pso_num=25 pso_serial#=241 2025-07-02 21:45:22.472157 :ACB16D4E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9912 osp_idx=23 osp_ver=180128 osp_pg=0 (spawn #345793) 2025-07-02 21:45:22.472157 :ACB16D4F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:45:22.472158 :ACB16D50:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=103 time=173498361 2025-07-02 21:45:22.472158 :ACB16D51:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:45:22.472159 :ACB16D52:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:45:22.472159 :ACB16D53:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:45:22.472159 :ACB16D54:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:45:22.472159 :ACB16D55:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:45:22.472655 :ACB16D56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:45:52.576833 :ACB17769:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:46:22.587288 :ACB180FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:46:22.575 2025-07-02 21:46:22.587290 :ACB180FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14392 pso_num=25 pso_serial#=242 2025-07-02 21:46:22.587422 :ACB180FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14392 osp_idx=23 osp_ver=180129 osp_pg=0 (spawn #345795) 2025-07-02 21:46:22.587422 :ACB18100:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:46:22.587423 :ACB18101:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=104 time=173558470 2025-07-02 21:46:22.587423 :ACB18102:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:46:22.587424 :ACB18103:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:46:22.587424 :ACB18104:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:46:22.587424 :ACB18105:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:46:22.587425 :ACB18106:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:46:22.587891 :ACB18107:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 21:46:47.616750 :ACB18968:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 21:47:17.732140 :ACB19320:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:47:22.835515 :ACB19532:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:47:22.831 2025-07-02 21:47:22.835517 :ACB19533:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18216 pso_num=25 pso_serial#=243 2025-07-02 21:47:22.835654 :ACB19534:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18216 osp_idx=23 osp_ver=180130 osp_pg=0 (spawn #345798) 2025-07-02 21:47:22.835655 :ACB19535:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:47:22.835655 :ACB19536:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=105 time=173618736 2025-07-02 21:47:22.835655 :ACB19537:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:47:22.835656 :ACB19538:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:47:22.835656 :ACB19539:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:47:22.835656 :ACB1953A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:47:22.835657 :ACB1953B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:47:22.836156 :ACB1953C: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-07-02 21:47:22.836205 :ACB19543: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-07-02 21:57:23.655958 :ACB25B91:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-02 21:57:24.097946 :ACB25BDE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-02 21:57:24.093 2025-07-02 21:57:24.097948 :ACB25BDF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11564 pso_num=25 pso_serial#=244 2025-07-02 21:57:24.098084 :ACB25BE0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11564 osp_idx=23 osp_ver=180131 osp_pg=0 (spawn #345821) 2025-07-02 21:57:24.098085 :ACB25BE1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:57:24.098085 :ACB25BE2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=106 time=174219986 2025-07-02 21:57:24.098086 :ACB25BE3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:57:24.098086 :ACB25BE4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:57:24.098086 :ACB25BE5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:57:24.098087 :ACB25BE6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:57:24.098087 :ACB25BE7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:57:24.098582 :ACB25BE8: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-07-02 21:57:24.098630 :ACB25BF2: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: 11564, W000)