Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250902220005\orcl_w000_22844_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250902220005\orcl_w000_22844_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:35007M/63366M, Ph+PgF:39923M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 22844, image: ORACLE.EXE (W000) *** 2025-09-02 22:00:05.277 *** SESSION ID:(201.29013) 2025-09-02 22:00:05.277 *** 2025-09-02 22:00:05.277 Process diagnostic dump for ORACLE.EXE (W000), OS id=22844, pid: 25, proc_ser: 146, sid: 201, sess_ser: 29013 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=3.243573 sec, exc=3.243573 sec, total=3.243573 sec wait times: max=5.000000 sec, heur=4 min 13 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=50 seq_num=51 snap_id=1 wait times: snap=5.010171 sec, exc=5.010171 sec, total=5.010171 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.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 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=5.014716 sec, exc=5.014716 sec, total=5.014716 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=47 seq_num=48 snap_id=1 wait times: snap=5.008595 sec, exc=5.008595 sec, total=5.008595 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=46 seq_num=47 snap_id=1 wait times: snap=5.004017 sec, exc=5.004017 sec, total=5.004017 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=45 seq_num=46 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.000017 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=5.004630 sec, exc=5.004630 sec, total=5.004630 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=43 seq_num=44 snap_id=1 wait times: snap=5.014742 sec, exc=5.014742 sec, total=5.014742 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=42 seq_num=43 snap_id=1 wait times: snap=5.015254 sec, exc=5.015254 sec, total=5.015254 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=5.003210 sec, exc=5.003210 sec, total=5.003210 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time Sampled Session History of session 201 serial 29013 --------------------------------------------------- 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:05 - 22:00:05] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-02 22:00:05.277 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 22844, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-02 19:25:33.830265 :C81BA117:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 19:25:33.830764 :C81BA118: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-02 19:25:33.830808 :C81BA122: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-02 19:35:34.723235 :C81C671B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 19:35:35.212527 :C81C6750:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 19:35:35.207 2025-09-02 19:35:35.212529 :C81C6751:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22152 pso_num=25 pso_serial#=133 2025-09-02 19:35:35.212672 :C81C6752:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22152 osp_idx=23 osp_ver=279626 osp_pg=0 (spawn #552157) 2025-09-02 19:35:35.212673 :C81C6753: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-02 19:35:35.212673 :C81C6754:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=1227386518 2025-09-02 19:35:35.212674 :C81C6755:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 19:35:35.212674 :C81C6756:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 19:35:35.212674 :C81C6757:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 19:35:35.212675 :C81C6758:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 19:35:35.212675 :C81C6759:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 19:35:35.213187 :C81C675A: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-02 19:35:35.213236 :C81C6761: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-02 19:45:35.999979 :C81D2D6A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 19:45:36.535967 :C81D2DC1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 19:45:36.531 2025-09-02 19:45:36.535970 :C81D2DC2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22476 pso_num=25 pso_serial#=134 2025-09-02 19:45:36.536105 :C81D2DC3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22476 osp_idx=23 osp_ver=279627 osp_pg=0 (spawn #552180) 2025-09-02 19:45:36.536106 :C81D2DC4: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-02 19:45:36.536106 :C81D2DC5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=1227987846 2025-09-02 19:45:36.536107 :C81D2DC6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 19:45:36.536107 :C81D2DC7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 19:45:36.536108 :C81D2DC8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 19:45:36.536108 :C81D2DC9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 19:45:36.536108 :C81D2DCA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 19:45:36.536627 :C81D2DCB: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-02 19:45:36.536682 :C81D2DD5: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-02 19:55:37.310316 :C81DF36D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 19:55:37.815040 :C81DF3B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 19:55:37.810 2025-09-02 19:55:37.815042 :C81DF3B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19388 pso_num=25 pso_serial#=135 2025-09-02 19:55:37.815181 :C81DF3B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19388 osp_idx=23 osp_ver=279628 osp_pg=0 (spawn #552203) 2025-09-02 19:55:37.815181 :C81DF3B9: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-02 19:55:37.815182 :C81DF3BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=1228589112 2025-09-02 19:55:37.815182 :C81DF3BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 19:55:37.815182 :C81DF3BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 19:55:37.815183 :C81DF3BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 19:55:37.815183 :C81DF3BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 19:55:37.815183 :C81DF3BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 19:55:37.815694 :C81DF3C0: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-02 19:55:37.815740 :C81DF3C7: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-02 20:01:11.044459 :C81E6343: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-02 20:05:38.926379 :C81EBC82: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-02 20:15:39.670100 :C81F8ACD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 20:15:39.955725 :C81F8B15:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:15:39.951 2025-09-02 20:15:39.955728 :C81F8B16:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22920 pso_num=25 pso_serial#=136 2025-09-02 20:15:39.955905 :C81F8B17:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22920 osp_idx=23 osp_ver=279629 osp_pg=0 (spawn #552250) 2025-09-02 20:15:39.955906 :C81F8B18: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-02 20:15:39.955906 :C81F8B19:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=1229791268 2025-09-02 20:15:39.955907 :C81F8B1A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 20:15:39.955907 :C81F8B1B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 20:15:39.955907 :C81F8B1C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 20:15:39.955908 :C81F8B1D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 20:15:39.955908 :C81F8B1E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 20:15:39.956413 :C81F8B1F: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-02 20:15:39.956459 :C81F8B27: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-02 20:25:40.801453 :C82050B1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 20:25:41.181516 :C82050FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:25:41.176 2025-09-02 20:25:41.181519 :C82050FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20860 pso_num=25 pso_serial#=137 2025-09-02 20:25:41.181668 :C82050FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20860 osp_idx=23 osp_ver=279630 osp_pg=0 (spawn #552273) 2025-09-02 20:25:41.181668 :C8205100: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-02 20:25:41.181669 :C8205101:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=1230392487 2025-09-02 20:25:41.181670 :C8205102:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 20:25:41.181671 :C8205103:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 20:25:41.181671 :C8205104:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 20:25:41.181671 :C8205105:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 20:25:41.181672 :C8205106:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 20:25:41.182199 :C8205107: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-02 20:25:41.182246 :C820510F: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-02 20:35:42.018019 :C8211721:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 20:35:42.460319 :C8211792:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:35:42.455 2025-09-02 20:35:42.460321 :C8211793:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22520 pso_num=25 pso_serial#=138 2025-09-02 20:35:42.460463 :C8211794:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22520 osp_idx=23 osp_ver=279631 osp_pg=0 (spawn #552295) 2025-09-02 20:35:42.460464 :C8211795: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-02 20:35:42.460464 :C8211796:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=1230993768 2025-09-02 20:35:42.460465 :C8211797:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 20:35:42.460465 :C8211798:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 20:35:42.460466 :C8211799:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 20:35:42.460466 :C821179A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 20:35:42.460466 :C821179B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 20:35:42.460965 :C821179C: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-02 20:35:42.461011 :C82117A5: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-02 20:45:43.238150 :C821DD1C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 20:45:43.602319 :C821DD9F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:45:43.597 2025-09-02 20:45:43.602322 :C821DDA0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18716 pso_num=25 pso_serial#=139 2025-09-02 20:45:43.602461 :C821DDA1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18716 osp_idx=23 osp_ver=279632 osp_pg=0 (spawn #552318) 2025-09-02 20:45:43.602462 :C821DDA2: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-02 20:45:43.602462 :C821DDA3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=1231594908 2025-09-02 20:45:43.602462 :C821DDA4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 20:45:43.602463 :C821DDA5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 20:45:43.602463 :C821DDA6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 20:45:43.602463 :C821DDA7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 20:45:43.602464 :C821DDA8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 20:45:43.602968 :C821DDA9: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-02 20:45:43.603017 :C821DDB1: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-02 20:55:44.497596 :C822A369:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 20:55:44.840591 :C822A3DA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:55:44.835 2025-09-02 20:55:44.840593 :C822A3DB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22268 pso_num=25 pso_serial#=140 2025-09-02 20:55:44.840729 :C822A3DC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22268 osp_idx=23 osp_ver=279633 osp_pg=0 (spawn #552342) 2025-09-02 20:55:44.840730 :C822A3DD: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-02 20:55:44.840731 :C822A3DE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=1232196143 2025-09-02 20:55:44.840731 :C822A3DF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 20:55:44.840732 :C822A3E0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 20:55:44.840732 :C822A3E1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 20:55:44.840732 :C822A3E2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 20:55:44.840732 :C822A3E3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 20:55:44.841239 :C822A3E4: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-02 20:55:44.841285 :C822A3ED: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-02 21:05:45.700833 :C8237233:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 21:05:46.018029 :C82372B0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:05:46.013 2025-09-02 21:05:46.018031 :C82372B1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22740 pso_num=25 pso_serial#=141 2025-09-02 21:05:46.018168 :C82372B2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22740 osp_idx=23 osp_ver=279634 osp_pg=0 (spawn #552367) 2025-09-02 21:05:46.018168 :C82372B3: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-02 21:05:46.018169 :C82372B4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=1232797315 2025-09-02 21:05:46.018170 :C82372B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 21:05:46.018171 :C82372B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 21:05:46.018171 :C82372B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 21:05:46.018171 :C82372B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 21:05:46.018172 :C82372B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 21:05:46.018666 :C82372BA: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-02 21:05:46.018711 :C82372C2: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-02 21:15:46.819992 :C8243AF6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 21:15:47.246638 :C8243B3C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:15:47.242 2025-09-02 21:15:47.246641 :C8243B3D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21904 pso_num=25 pso_serial#=142 2025-09-02 21:15:47.246782 :C8243B3E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21904 osp_idx=23 osp_ver=279635 osp_pg=0 (spawn #552390) 2025-09-02 21:15:47.246783 :C8243B3F: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-02 21:15:47.246783 :C8243B40:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=1233398549 2025-09-02 21:15:47.246784 :C8243B41:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 21:15:47.246784 :C8243B42:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 21:15:47.246784 :C8243B43:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 21:15:47.246785 :C8243B44:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 21:15:47.246785 :C8243B45:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 21:15:47.247294 :C8243B46: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-02 21:15:47.247344 :C8243B50: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-02 21:25:48.116194 :C82500F3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 21:25:48.402203 :C8250156:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:25:48.397 2025-09-02 21:25:48.402205 :C8250157:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23408 pso_num=25 pso_serial#=143 2025-09-02 21:25:48.402345 :C8250158:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23408 osp_idx=23 osp_ver=279636 osp_pg=0 (spawn #552413) 2025-09-02 21:25:48.402345 :C8250159: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-02 21:25:48.402346 :C825015A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=1233999705 2025-09-02 21:25:48.402346 :C825015B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 21:25:48.402347 :C825015C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 21:25:48.402347 :C825015D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 21:25:48.402348 :C825015E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 21:25:48.402348 :C825015F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 21:25:48.402841 :C8250160: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-02 21:25:48.402884 :C8250168: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-02 21:35:49.247364 :C825C7FB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 21:35:49.533372 :C825C834:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:35:49.528 2025-09-02 21:35:49.533374 :C825C835:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22964 pso_num=25 pso_serial#=144 2025-09-02 21:35:49.533513 :C825C836:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22964 osp_idx=23 osp_ver=279637 osp_pg=0 (spawn #552435) 2025-09-02 21:35:49.533514 :C825C837: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-02 21:35:49.533514 :C825C838:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=1234600830 2025-09-02 21:35:49.533515 :C825C839:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 21:35:49.533515 :C825C83A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 21:35:49.533515 :C825C83B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 21:35:49.533516 :C825C83C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 21:35:49.533516 :C825C83D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 21:35:49.534022 :C825C83E: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-02 21:35:49.534068 :C825C846: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-02 21:45:50.293993 :C8268E57:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 21:45:50.579925 :C8268E98:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:45:50.575 2025-09-02 21:45:50.579928 :C8268E99:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20172 pso_num=25 pso_serial#=145 2025-09-02 21:45:50.580065 :C8268E9A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20172 osp_idx=23 osp_ver=279638 osp_pg=0 (spawn #552458) 2025-09-02 21:45:50.580065 :C8268E9B: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-02 21:45:50.580066 :C8268E9C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=100 time=1235201877 2025-09-02 21:45:50.580066 :C8268E9D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 21:45:50.580067 :C8268E9E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 21:45:50.580067 :C8268E9F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 21:45:50.580067 :C8268EA0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 21:45:50.580067 :C8268EA1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 21:45:50.580577 :C8268EA2: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-02 21:45:50.580620 :C8268EAA: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-02 21:55:51.384575 :C8275441:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-02 21:55:51.639439 :C8275476:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:55:51.634 2025-09-02 21:55:51.639441 :C8275477:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22844 pso_num=25 pso_serial#=146 2025-09-02 21:55:51.639585 :C8275478:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22844 osp_idx=23 osp_ver=279639 osp_pg=0 (spawn #552481) 2025-09-02 21:55:51.639585 :C8275479: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-02 21:55:51.639586 :C827547A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=101 time=1235802940 2025-09-02 21:55:51.639586 :C827547B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 21:55:51.639587 :C827547C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 21:55:51.639587 :C827547D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 21:55:51.639587 :C827547E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 21:55:51.639587 :C827547F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 21:55:51.640078 :C8275480: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-02 21:55:51.640121 :C827548A: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: 22844, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de