Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250508082336\orcl_w000_7888_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250508082336\orcl_w000_7888_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:37514M/63366M, Ph+PgF:44924M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 7888, image: ORACLE.EXE (W000) *** 2025-05-08 08:23:37.012 *** SESSION ID:(201.47061) 2025-05-08 08:23:37.012 *** 2025-05-08 08:23:37.012 Process diagnostic dump for ORACLE.EXE (W000), OS id=7888, pid: 25, proc_ser: 105, sid: 201, sess_ser: 47061 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=1.566997 sec, exc=1.566997 sec, total=1.566997 sec wait times: max=5.000000 sec, heur=6 min 32 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.000031 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=5.014701 sec, exc=5.014701 sec, total=5.014701 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.010132 sec, exc=5.010132 sec, total=5.010132 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.013467 sec, exc=5.013467 sec, total=5.013467 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000038 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.006937 sec, exc=5.006937 sec, total=5.006937 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000042 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.004609 sec, exc=5.004609 sec, total=5.004609 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.004600 sec, exc=5.004600 sec, total=5.004600 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.009202 sec, exc=5.009202 sec, total=5.009202 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.005131 sec, exc=5.005131 sec, total=5.005131 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.003370 sec, exc=5.003370 sec, total=5.003370 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000044 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.009994 sec, exc=5.009994 sec, total=5.009994 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000043 sec of elapsed time Sampled Session History of session 201 serial 47061 --------------------------------------------------- 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, 08:21:37 - 08:23:37] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-08 08:23:37.012 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 7888, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-08 05:56:46.653102 :9437901F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 05:56:46.653102 :94379020:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=3954583812 2025-05-08 05:56:46.653103 :94379021:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 05:56:46.653103 :94379022:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 05:56:46.653104 :94379023:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 05:56:46.653104 :94379024:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 05:56:46.653104 :94379025:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 05:56:46.653594 :94379026: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-05-08 05:56:46.653642 :9437902D: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-05-08 06:06:47.634661 :94385FFE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 06:06:48.029958 :94386036:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 06:06:48.025 2025-05-08 06:06:48.029960 :94386037:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9176 pso_num=25 pso_serial#=92 2025-05-08 06:06:48.030102 :94386038:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9176 osp_idx=23 osp_ver=80161 osp_pg=0 (spawn #160043) 2025-05-08 06:06:48.030103 :94386039:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 06:06:48.030103 :9438603A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=3955185203 2025-05-08 06:06:48.030104 :9438603B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 06:06:48.030105 :9438603C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 06:06:48.030105 :9438603D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 06:06:48.030105 :9438603E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 06:06:48.030106 :9438603F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 06:06:48.030606 :94386040: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-05-08 06:06:48.030653 :94386048: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-05-08 06:16:48.932929 :94392A30:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 06:16:49.286401 :94392A70:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 06:16:49.281 2025-05-08 06:16:49.286403 :94392A71:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12816 pso_num=25 pso_serial#=93 2025-05-08 06:16:49.286539 :94392A72:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12816 osp_idx=23 osp_ver=80162 osp_pg=0 (spawn #160066) 2025-05-08 06:16:49.286540 :94392A73:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 06:16:49.286540 :94392A74:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=3955786453 2025-05-08 06:16:49.286540 :94392A75:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 06:16:49.286541 :94392A76:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 06:16:49.286541 :94392A77:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 06:16:49.286541 :94392A78:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 06:16:49.286541 :94392A79:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 06:16:49.287040 :94392A7A: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-05-08 06:16:49.287095 :94392A81: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-05-08 06:26:50.252396 :9439F080:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 06:26:50.647764 :9439F0BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 06:26:50.642 2025-05-08 06:26:50.647765 :9439F0BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11268 pso_num=25 pso_serial#=94 2025-05-08 06:26:50.647908 :9439F0BE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11268 osp_idx=23 osp_ver=80163 osp_pg=0 (spawn #160088) 2025-05-08 06:26:50.647909 :9439F0BF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 06:26:50.647909 :9439F0C0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=3956387812 2025-05-08 06:26:50.647910 :9439F0C1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 06:26:50.647910 :9439F0C2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 06:26:50.647911 :9439F0C3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 06:26:50.647911 :9439F0C4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 06:26:50.647911 :9439F0C5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 06:26:50.648420 :9439F0C6: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-05-08 06:26:50.648465 :9439F0CE: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-05-08 06:36:51.576687 :943AB6BA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 06:36:51.862619 :943AB6FF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 06:36:51.858 2025-05-08 06:36:51.862621 :943AB700:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13044 pso_num=25 pso_serial#=95 2025-05-08 06:36:51.862762 :943AB701:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13044 osp_idx=23 osp_ver=80164 osp_pg=0 (spawn #160111) 2025-05-08 06:36:51.862763 :943AB702:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 06:36:51.862763 :943AB703:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=3956989031 2025-05-08 06:36:51.862763 :943AB704:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 06:36:51.862764 :943AB705:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 06:36:51.862764 :943AB706:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 06:36:51.862764 :943AB707:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 06:36:51.862765 :943AB708:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 06:36:51.863274 :943AB709: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-05-08 06:36:51.863329 :943AB70F: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-05-08 06:46:52.863201 :943B7C72:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 06:46:53.133538 :943B7CA8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 06:46:53.129 2025-05-08 06:46:53.133540 :943B7CA9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12792 pso_num=25 pso_serial#=96 2025-05-08 06:46:53.133683 :943B7CAA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12792 osp_idx=23 osp_ver=80165 osp_pg=0 (spawn #160134) 2025-05-08 06:46:53.133684 :943B7CAB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 06:46:53.133684 :943B7CAC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=3957590296 2025-05-08 06:46:53.133685 :943B7CAD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 06:46:53.133685 :943B7CAE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 06:46:53.133686 :943B7CAF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 06:46:53.133686 :943B7CB0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 06:46:53.133686 :943B7CB1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 06:46:53.134177 :943B7CB2: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-05-08 06:46:53.134231 :943B7CB9: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-05-08 06:49:49.332693 :943BB60C: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-05-08 06:56:54.416759 :943C4276: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-05-08 07:06:55.380150 :943D10AA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:06:55.650236 :943D10E1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:06:55.646 2025-05-08 07:06:55.650238 :943D10E2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8780 pso_num=25 pso_serial#=97 2025-05-08 07:06:55.650372 :943D10E3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8780 osp_idx=23 osp_ver=80166 osp_pg=0 (spawn #160183) 2025-05-08 07:06:55.650373 :943D10E4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 07:06:55.650373 :943D10E5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=3958792812 2025-05-08 07:06:55.650374 :943D10E6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 07:06:55.650374 :943D10E7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 07:06:55.650374 :943D10E8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 07:06:55.650374 :943D10E9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 07:06:55.650375 :943D10EA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 07:06:55.650869 :943D10EB: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-05-08 07:06:55.650915 :943D10F3: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-05-08 07:16:56.587677 :943DD895:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:16:56.920474 :943DD8CD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:16:56.916 2025-05-08 07:16:56.920476 :943DD8CE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6572 pso_num=25 pso_serial#=98 2025-05-08 07:16:56.920609 :943DD8CF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6572 osp_idx=23 osp_ver=80167 osp_pg=0 (spawn #160206) 2025-05-08 07:16:56.920610 :943DD8D0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 07:16:56.920611 :943DD8D1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=3959394093 2025-05-08 07:16:56.920611 :943DD8D2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 07:16:56.920612 :943DD8D3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 07:16:56.920612 :943DD8D4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 07:16:56.920612 :943DD8D5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 07:16:56.920612 :943DD8D6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 07:16:56.921087 :943DD8D7: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-05-08 07:16:56.921136 :943DD8DF: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-05-08 07:26:57.848232 :943E9E00:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:26:58.137972 :943E9E0F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:26:58.129 2025-05-08 07:26:58.137974 :943E9E10:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4728 pso_num=25 pso_serial#=99 2025-05-08 07:26:58.138107 :943E9E11:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=4728 (legacy spawn) 2025-05-08 07:26:58.144506 :943E9E12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-08 07:26:58.153276 :943E9E1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-08 07:26:58.153435 :943E9E23:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-08 07:26:58.154245 :943E9E24:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:26:58.181068 :943E9E5C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:26:58.176 2025-05-08 07:26:58.181070 :943E9E5D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2956 pso_num=25 pso_serial#=100 2025-05-08 07:26:58.181205 :943E9E5E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2956 osp_idx=23 osp_ver=80169 osp_pg=0 (spawn #160228) 2025-05-08 07:26:58.181205 :943E9E5F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 07:26:58.181206 :943E9E60:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=3959995343 2025-05-08 07:26:58.181206 :943E9E61:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 07:26:58.181207 :943E9E62:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 07:26:58.181207 :943E9E63:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 07:26:58.181207 :943E9E64:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 07:26:58.181208 :943E9E65:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 07:26:58.181700 :943E9E66: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-05-08 07:26:58.181752 :943E9E6F: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-05-08 07:36:59.119945 :943F646F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:36:59.483988 :943F64A9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:36:59.479 2025-05-08 07:36:59.483990 :943F64AA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10296 pso_num=25 pso_serial#=101 2025-05-08 07:36:59.484131 :943F64AB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10296 osp_idx=23 osp_ver=80170 osp_pg=0 (spawn #160251) 2025-05-08 07:36:59.484132 :943F64AC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 07:36:59.484132 :943F64AD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=3960596656 2025-05-08 07:36:59.484133 :943F64AE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 07:36:59.484133 :943F64AF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 07:36:59.484134 :943F64B0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 07:36:59.484134 :943F64B1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 07:36:59.484134 :943F64B2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 07:36:59.484625 :943F64B3: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-05-08 07:36:59.484669 :943F64BB: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-05-08 07:47:00.344201 :944029E4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:47:00.708231 :94402A38:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:47:00.703 2025-05-08 07:47:00.708233 :94402A39:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11988 pso_num=25 pso_serial#=102 2025-05-08 07:47:00.708367 :94402A3A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11988 osp_idx=23 osp_ver=80171 osp_pg=0 (spawn #160274) 2025-05-08 07:47:00.708368 :94402A3B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 07:47:00.708368 :94402A3C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=100 time=3961197875 2025-05-08 07:47:00.708368 :94402A3D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 07:47:00.708369 :94402A3E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 07:47:00.708369 :94402A3F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 07:47:00.708370 :94402A40:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 07:47:00.708370 :94402A41:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 07:47:00.708941 :94402A42: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-05-08 07:47:00.708985 :94402A4A: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-05-08 07:57:01.566529 :9440EF8E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 07:57:02.065585 :9440EFF2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 07:57:02.061 2025-05-08 07:57:02.065587 :9440EFF3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12792 pso_num=25 pso_serial#=103 2025-05-08 07:57:02.065718 :9440EFF4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12792 osp_idx=23 osp_ver=80172 osp_pg=0 (spawn #160296) 2025-05-08 07:57:02.065718 :9440EFF5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 07:57:02.065719 :9440EFF6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=101 time=3961799234 2025-05-08 07:57:02.065719 :9440EFF7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 07:57:02.065720 :9440EFF8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 07:57:02.065720 :9440EFF9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 07:57:02.065720 :9440EFFA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 07:57:02.065721 :9440EFFB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 07:57:02.066253 :9440EFFC: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-05-08 07:57:02.066297 :9440F005: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-05-08 08:07:02.971174 :9441BE74:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 08:07:03.444628 :9441BEAB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 08:07:03.440 2025-05-08 08:07:03.444630 :9441BEAC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9580 pso_num=25 pso_serial#=104 2025-05-08 08:07:03.444770 :9441BEAD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9580 osp_idx=23 osp_ver=80173 osp_pg=0 (spawn #160324) 2025-05-08 08:07:03.444770 :9441BEAE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:07:03.444771 :9441BEAF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=102 time=3962400609 2025-05-08 08:07:03.444771 :9441BEB0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:07:03.444772 :9441BEB1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:07:03.444772 :9441BEB2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:07:03.444773 :9441BEB3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:07:03.444773 :9441BEB4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:07:03.445282 :9441BEB5: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-05-08 08:07:03.445328 :9441BEBC: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-05-08 08:17:04.398865 :944286A9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-08 08:17:04.856606 :9442870C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-08 08:17:04.851 2025-05-08 08:17:04.856608 :9442870D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7888 pso_num=25 pso_serial#=105 2025-05-08 08:17:04.856745 :9442870E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7888 osp_idx=23 osp_ver=80174 osp_pg=0 (spawn #160347) 2025-05-08 08:17:04.856746 :9442870F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-08 08:17:04.856746 :94428710:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=103 time=3963002015 2025-05-08 08:17:04.856747 :94428711:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-08 08:17:04.856747 :94428712:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-08 08:17:04.856747 :94428713:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-08 08:17:04.856748 :94428714:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-08 08:17:04.856748 :94428715:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-08 08:17:04.857238 :94428716: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-05-08 08:17:04.857282 :9442871D: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: 7888, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de