Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250531140403\orcl_w000_13356_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250531140403\orcl_w000_13356_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:37219M/63366M, Ph+PgF:44083M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 13356, image: ORACLE.EXE (W000) *** 2025-05-31 14:04:03.688 *** SESSION ID:(177.13471) 2025-05-31 14:04:03.688 *** 2025-05-31 14:04:03.688 Process diagnostic dump for ORACLE.EXE (W000), OS id=13356, pid: 22, proc_ser: 152, sid: 177, sess_ser: 13471 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=2.426954 sec, exc=2.426954 sec, total=2.426954 sec wait times: max=5.000000 sec, heur=2 min 12 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000025 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.003197 sec, exc=5.003197 sec, total=5.003197 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: 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.014048 sec, exc=5.014048 sec, total=5.014048 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 2: 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.004679 sec, exc=5.004679 sec, total=5.004679 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=23 seq_num=24 snap_id=1 wait times: snap=5.004649 sec, exc=5.004649 sec, total=5.004649 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 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.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=5.011805 sec, exc=5.011805 sec, total=5.011805 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=5.001134 sec, exc=5.001134 sec, total=5.001134 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=19 seq_num=20 snap_id=1 wait times: snap=5.000479 sec, exc=5.000479 sec, total=5.000479 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=5.014143 sec, exc=5.014143 sec, total=5.014143 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=5.010671 sec, exc=5.010671 sec, total=5.010671 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 177 serial 13471 --------------------------------------------------- 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, 14:02:03 - 14:04:03] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-31 14:04:03.688 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 13356, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-31 11:31:31.122357 :9E6B26EA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 11:31:31.122357 :9E6B26EB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 11:31:31.122899 :9E6B26EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 11:31:31.122945 :9E6B26F3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 11:41:32.185051 :9E6BE95C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 11:41:32.486541 :9E6BE9A0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 11:41:32.482 2025-05-31 11:41:32.486543 :9E6BE9A1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13372 pso_num=22 pso_serial#=139 2025-05-31 11:41:32.486675 :9E6BE9A2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13372 osp_idx=0 osp_ver=252661 osp_pg=0 (spawn #237720) 2025-05-31 11:41:32.486676 :9E6BE9A3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 11:41:32.486676 :9E6BE9A4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=53 time=1667451141 2025-05-31 11:41:32.486677 :9E6BE9A5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 11:41:32.486677 :9E6BE9A6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 11:41:32.486677 :9E6BE9A7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 11:41:32.486678 :9E6BE9A8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 11:41:32.486678 :9E6BE9A9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 11:41:32.487217 :9E6BE9AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 11:41:32.487261 :9E6BE9B2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 11:51:33.494369 :9E6CACB7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 11:51:33.795982 :9E6CACEE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 11:51:33.791 2025-05-31 11:51:33.795984 :9E6CACEF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8672 pso_num=22 pso_serial#=140 2025-05-31 11:51:33.796129 :9E6CACF0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8672 osp_idx=0 osp_ver=252662 osp_pg=0 (spawn #237743) 2025-05-31 11:51:33.796130 :9E6CACF1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 11:51:33.796130 :9E6CACF2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=54 time=1668052438 2025-05-31 11:51:33.796130 :9E6CACF3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 11:51:33.796130 :9E6CACF4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 11:51:33.796131 :9E6CACF5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 11:51:33.796131 :9E6CACF6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 11:51:33.796131 :9E6CACF7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 11:51:33.796639 :9E6CACF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 11:51:33.796695 :9E6CACFE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 12:01:34.712867 :9E6D71E4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 12:01:35.030164 :9E6D7219:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 12:01:35.025 2025-05-31 12:01:35.030166 :9E6D721A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14136 pso_num=22 pso_serial#=141 2025-05-31 12:01:35.030300 :9E6D721B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14136 osp_idx=0 osp_ver=252663 osp_pg=0 (spawn #237767) 2025-05-31 12:01:35.030301 :9E6D721C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 12:01:35.030302 :9E6D721D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=55 time=1668653672 2025-05-31 12:01:35.030302 :9E6D721E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 12:01:35.030302 :9E6D721F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 12:01:35.030303 :9E6D7220:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 12:01:35.030303 :9E6D7221:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 12:01:35.030303 :9E6D7222:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 12:01:35.030800 :9E6D7223:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 12:01:35.030843 :9E6D722B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 12:11:36.037352 :9E6E3D13:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 12:11:36.307631 :9E6E3D49:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 12:11:36.303 2025-05-31 12:11:36.307633 :9E6E3D4A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14400 pso_num=22 pso_serial#=142 2025-05-31 12:11:36.307771 :9E6E3D4B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14400 osp_idx=0 osp_ver=252664 osp_pg=0 (spawn #237791) 2025-05-31 12:11:36.307772 :9E6E3D4C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 12:11:36.307772 :9E6E3D4D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=56 time=1669254954 2025-05-31 12:11:36.307773 :9E6E3D4E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 12:11:36.307774 :9E6E3D4F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 12:11:36.307774 :9E6E3D50:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 12:11:36.307775 :9E6E3D51:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 12:11:36.307775 :9E6E3D52:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 12:11:36.308289 :9E6E3D53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 12:11:36.308335 :9E6E3D5A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 12:21:37.326426 :9E6EFFE2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 12:21:37.581167 :9E6F0017:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 12:21:37.577 2025-05-31 12:21:37.581169 :9E6F0018:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13028 pso_num=22 pso_serial#=143 2025-05-31 12:21:37.581308 :9E6F0019:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13028 osp_idx=0 osp_ver=252665 osp_pg=0 (spawn #237814) 2025-05-31 12:21:37.581309 :9E6F001A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 12:21:37.581309 :9E6F001B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=57 time=1669856235 2025-05-31 12:21:37.581310 :9E6F001C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 12:21:37.581310 :9E6F001D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 12:21:37.581310 :9E6F001E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 12:21:37.581311 :9E6F001F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 12:21:37.581311 :9E6F0020:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 12:21:37.581827 :9E6F0021:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 12:21:37.581875 :9E6F0028:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 12:31:38.596850 :9E6FC386:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 12:31:38.898432 :9E6FC3D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 12:31:38.893 2025-05-31 12:31:38.898435 :9E6FC3D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1880 pso_num=22 pso_serial#=144 2025-05-31 12:31:38.898566 :9E6FC3D2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1880 osp_idx=0 osp_ver=252666 osp_pg=0 (spawn #237836) 2025-05-31 12:31:38.898567 :9E6FC3D3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 12:31:38.898568 :9E6FC3D4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=58 time=1670457547 2025-05-31 12:31:38.898568 :9E6FC3D5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 12:31:38.898568 :9E6FC3D6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 12:31:38.898569 :9E6FC3D7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 12:31:38.898569 :9E6FC3D8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 12:31:38.898569 :9E6FC3D9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 12:31:38.899124 :9E6FC3DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 12:31:38.899169 :9E6FC3E2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 12:41:39.849942 :9E70866F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 12:41:40.198411 :9E7086A9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 12:41:40.194 2025-05-31 12:41:40.198414 :9E7086AA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12068 pso_num=22 pso_serial#=145 2025-05-31 12:41:40.198573 :9E7086AB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12068 osp_idx=0 osp_ver=252667 osp_pg=0 (spawn #237859) 2025-05-31 12:41:40.198574 :9E7086AC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 12:41:40.198574 :9E7086AD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=59 time=1671058844 2025-05-31 12:41:40.198575 :9E7086AE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 12:41:40.198575 :9E7086AF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 12:41:40.198575 :9E7086B0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 12:41:40.198576 :9E7086B1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 12:41:40.198576 :9E7086B2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 12:41:40.199113 :9E7086B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 12:41:40.199159 :9E7086BB:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 12:51:41.142575 :9E71491F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 12:51:41.475393 :9E714973:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 12:51:41.470 2025-05-31 12:51:41.475395 :9E714974:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14216 pso_num=22 pso_serial#=146 2025-05-31 12:51:41.475534 :9E714975:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14216 osp_idx=0 osp_ver=252668 osp_pg=0 (spawn #237882) 2025-05-31 12:51:41.475535 :9E714976:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 12:51:41.475535 :9E714977:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=60 time=1671660125 2025-05-31 12:51:41.475536 :9E714978:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 12:51:41.475536 :9E714979:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 12:51:41.475537 :9E71497A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 12:51:41.475537 :9E71497B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 12:51:41.475538 :9E71497C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 12:51:41.476029 :9E71497D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 12:51:41.476081 :9E714984:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:01:42.310735 :9E720E7B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 13:01:42.810738 :9E720EEF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 13:01:42.805 2025-05-31 13:01:42.810741 :9E720EF0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14568 pso_num=22 pso_serial#=147 2025-05-31 13:01:42.810877 :9E720EF1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14568 osp_idx=0 osp_ver=252669 osp_pg=0 (spawn #237906) 2025-05-31 13:01:42.810878 :9E720EF2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 13:01:42.810878 :9E720EF3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=61 time=1672261454 2025-05-31 13:01:42.810879 :9E720EF4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 13:01:42.810879 :9E720EF5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 13:01:42.810880 :9E720EF6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 13:01:42.810880 :9E720EF7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 13:01:42.810880 :9E720EF8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 13:01:42.811388 :9E720EF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 13:01:42.811431 :9E720F01:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:01:45.394081 :9E720F9B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:11:44.147976 :9E72DB41:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:21:45.127170 :9E739E9E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 13:21:45.554200 :9E739F97:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 13:21:45.549 2025-05-31 13:21:45.554201 :9E739F98:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4800 pso_num=22 pso_serial#=148 2025-05-31 13:21:45.554343 :9E739F99:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4800 osp_idx=0 osp_ver=252670 osp_pg=0 (spawn #237954) 2025-05-31 13:21:45.554344 :9E739F9A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 13:21:45.554344 :9E739F9B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=62 time=1673464204 2025-05-31 13:21:45.554344 :9E739F9C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 13:21:45.554345 :9E739F9D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 13:21:45.554345 :9E739F9E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 13:21:45.554346 :9E739F9F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 13:21:45.554346 :9E739FA0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 13:21:45.554861 :9E739FA1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 13:21:45.554906 :9E739FAA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:31:46.455567 :9E746375:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 13:31:46.898310 :9E7463E6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 13:31:46.893 2025-05-31 13:31:46.898312 :9E7463E7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11988 pso_num=22 pso_serial#=149 2025-05-31 13:31:46.898449 :9E7463E8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11988 osp_idx=0 osp_ver=252671 osp_pg=0 (spawn #237974) 2025-05-31 13:31:46.898449 :9E7463E9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 13:31:46.898449 :9E7463EA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=63 time=1674065547 2025-05-31 13:31:46.898450 :9E7463EB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 13:31:46.898450 :9E7463EC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 13:31:46.898451 :9E7463ED:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 13:31:46.898452 :9E7463EE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 13:31:46.898452 :9E7463EF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 13:31:46.898975 :9E7463F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 13:31:46.899024 :9E7463F6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:41:47.790944 :9E7528A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 13:41:48.265318 :9E75290C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 13:41:48.260 2025-05-31 13:41:48.265320 :9E75290D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13636 pso_num=22 pso_serial#=150 2025-05-31 13:41:48.265455 :9E75290E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13636 osp_idx=0 osp_ver=252672 osp_pg=0 (spawn #237997) 2025-05-31 13:41:48.265455 :9E75290F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 13:41:48.265456 :9E752910:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=64 time=1674666907 2025-05-31 13:41:48.265456 :9E752911:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 13:41:48.265457 :9E752912:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 13:41:48.265457 :9E752913:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 13:41:48.265457 :9E752914:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 13:41:48.265457 :9E752915:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 13:41:48.265951 :9E752916:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 13:41:48.266003 :9E75291D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 13:51:49.162096 :9E75ECA5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 13:51:49.661203 :9E75ED00:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 13:51:49.656 2025-05-31 13:51:49.661204 :9E75ED01:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14960 pso_num=22 pso_serial#=151 2025-05-31 13:51:49.661341 :9E75ED02:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14960 osp_idx=0 osp_ver=252673 osp_pg=0 (spawn #238020) 2025-05-31 13:51:49.661342 :9E75ED03:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 13:51:49.661342 :9E75ED04:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=1675268313 2025-05-31 13:51:49.661343 :9E75ED05:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 13:51:49.661343 :9E75ED06:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 13:51:49.661343 :9E75ED07:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 13:51:49.661344 :9E75ED08:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 13:51:49.661344 :9E75ED09:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 13:51:49.661845 :9E75ED0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 13:51:49.661892 :9E75ED12:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-31 14:01:50.569054 :9E76B305:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-31 14:01:51.073422 :9E76B36D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-31 14:01:51.069 2025-05-31 14:01:51.073424 :9E76B36E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13356 pso_num=22 pso_serial#=152 2025-05-31 14:01:51.073556 :9E76B36F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13356 osp_idx=0 osp_ver=252674 osp_pg=0 (spawn #238044) 2025-05-31 14:01:51.073557 :9E76B370:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-31 14:01:51.073557 :9E76B371:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=1675869719 2025-05-31 14:01:51.073557 :9E76B372:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-31 14:01:51.073557 :9E76B373:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-31 14:01:51.073558 :9E76B374:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-31 14:01:51.073558 :9E76B375:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-31 14:01:51.073558 :9E76B376:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-31 14:01:51.074042 :9E76B377:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-31 14:01:51.074088 :9E76B37E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 13356, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de