Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250623220009\orcl_w000_15536_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250623220009\orcl_w000_15536_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:36342M/63366M, Ph+PgF:42575M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 15536, image: ORACLE.EXE (W000) *** 2025-06-23 22:00:09.544 *** SESSION ID:(201.42977) 2025-06-23 22:00:09.544 *** 2025-06-23 22:00:09.544 Process diagnostic dump for ORACLE.EXE (W000), OS id=15536, pid: 25, proc_ser: 32, sid: 201, sess_ser: 42977 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=239 seq_num=240 snap_id=1 wait times: snap=4.526829 sec, exc=4.526829 sec, total=4.526829 sec wait times: max=5.000000 sec, heur=14 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.000027 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=238 seq_num=239 snap_id=1 wait times: snap=5.004615 sec, exc=5.004615 sec, total=5.004615 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=237 seq_num=238 snap_id=1 wait times: snap=5.001717 sec, exc=5.001717 sec, total=5.001717 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=236 seq_num=237 snap_id=1 wait times: snap=5.001361 sec, exc=5.001361 sec, total=5.001361 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=235 seq_num=236 snap_id=1 wait times: snap=5.015488 sec, exc=5.015488 sec, total=5.015488 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=234 seq_num=235 snap_id=1 wait times: snap=5.004120 sec, exc=5.004120 sec, total=5.004120 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=233 seq_num=234 snap_id=1 wait times: snap=5.004639 sec, exc=5.004639 sec, total=5.004639 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=232 seq_num=233 snap_id=1 wait times: snap=5.014709 sec, exc=5.014709 sec, total=5.014709 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=231 seq_num=232 snap_id=1 wait times: snap=5.014758 sec, exc=5.014758 sec, total=5.014758 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=230 seq_num=231 snap_id=1 wait times: snap=5.010153 sec, exc=5.010153 sec, total=5.010153 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=229 seq_num=230 snap_id=1 wait times: snap=5.008024 sec, exc=5.008024 sec, total=5.008024 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 201 serial 42977 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [121 samples, 21:58:09 - 22:00:09] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-23 22:00:09.544 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 15536, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-23 19:10:03.616839 :A8AB5058: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-06-23 19:20:04.411574 :A8AC1A34:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 19:20:04.822336 :A8AC1A93:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 19:20:04.818 2025-06-23 19:20:04.822338 :A8AC1A94:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12128 pso_num=25 pso_serial#=19 2025-06-23 19:20:04.822479 :A8AC1A95:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12128 osp_idx=23 osp_ver=166901 osp_pg=0 (spawn #315475) 2025-06-23 19:20:04.822480 :A8AC1A96:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 19:20:04.822480 :A8AC1A97:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=24 time=3682148016 2025-06-23 19:20:04.822481 :A8AC1A98:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 19:20:04.822481 :A8AC1A99:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 19:20:04.822482 :A8AC1A9A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 19:20:04.822482 :A8AC1A9B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 19:20:04.822482 :A8AC1A9C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 19:20:04.822984 :A8AC1A9D: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-06-23 19:20:04.823028 :A8AC1AA5: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-06-23 19:30:05.737560 :A8ACE624:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 19:30:06.210904 :A8ACE683:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 19:30:06.206 2025-06-23 19:30:06.210905 :A8ACE684:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13728 pso_num=25 pso_serial#=20 2025-06-23 19:30:06.211047 :A8ACE685:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13728 osp_idx=23 osp_ver=166902 osp_pg=0 (spawn #315498) 2025-06-23 19:30:06.211048 :A8ACE686:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 19:30:06.211048 :A8ACE687:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=25 time=3682749407 2025-06-23 19:30:06.211049 :A8ACE688:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 19:30:06.211049 :A8ACE689:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 19:30:06.211050 :A8ACE68A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 19:30:06.211050 :A8ACE68B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 19:30:06.211050 :A8ACE68C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 19:30:06.211544 :A8ACE68D: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-06-23 19:30:06.211595 :A8ACE694: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-06-23 19:40:07.095569 :A8ADB186:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 19:40:07.694003 :A8ADB22C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 19:40:07.689 2025-06-23 19:40:07.694004 :A8ADB22D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16388 pso_num=25 pso_serial#=21 2025-06-23 19:40:07.694142 :A8ADB22E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16388 osp_idx=23 osp_ver=166903 osp_pg=0 (spawn #315520) 2025-06-23 19:40:07.694143 :A8ADB22F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 19:40:07.694143 :A8ADB230:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=26 time=3683350891 2025-06-23 19:40:07.694144 :A8ADB231:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 19:40:07.694144 :A8ADB232:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 19:40:07.694145 :A8ADB233:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 19:40:07.694145 :A8ADB234:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 19:40:07.694145 :A8ADB235:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 19:40:07.694637 :A8ADB236: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-06-23 19:40:07.694683 :A8ADB23D: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-06-23 19:40:56.281502 :A8ADC1AE: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-06-23 19:50:09.042889 :A8AE7B93: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-06-23 20:00:09.866855 :A8AF448D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 20:00:10.340020 :A8AF4511:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 20:00:10.335 2025-06-23 20:00:10.340022 :A8AF4512:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14980 pso_num=25 pso_serial#=22 2025-06-23 20:00:10.340162 :A8AF4513:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14980 osp_idx=23 osp_ver=166904 osp_pg=0 (spawn #315565) 2025-06-23 20:00:10.340163 :A8AF4514:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 20:00:10.340163 :A8AF4515:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=27 time=3684553532 2025-06-23 20:00:10.340164 :A8AF4516:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 20:00:10.340164 :A8AF4517:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 20:00:10.340164 :A8AF4518:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 20:00:10.340165 :A8AF4519:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 20:00:10.340165 :A8AF451A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 20:00:10.340658 :A8AF451B: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-06-23 20:00:10.340704 :A8AF4523: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-06-23 20:10:11.233808 :A8B0154C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 20:10:11.676689 :A8B015B2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 20:10:11.671 2025-06-23 20:10:11.676691 :A8B015B3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17412 pso_num=25 pso_serial#=23 2025-06-23 20:10:11.676828 :A8B015B4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17412 osp_idx=23 osp_ver=166905 osp_pg=0 (spawn #315590) 2025-06-23 20:10:11.676829 :A8B015B5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 20:10:11.676829 :A8B015B6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=28 time=3685154860 2025-06-23 20:10:11.676829 :A8B015B7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 20:10:11.676830 :A8B015B8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 20:10:11.676830 :A8B015B9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 20:10:11.676830 :A8B015BA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 20:10:11.676830 :A8B015BB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 20:10:11.677329 :A8B015BC: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-06-23 20:10:11.677374 :A8B015C5: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-06-23 20:20:12.557115 :A8B0E127:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 20:20:12.967655 :A8B0E186:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 20:20:12.963 2025-06-23 20:20:12.967657 :A8B0E187:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16984 pso_num=25 pso_serial#=24 2025-06-23 20:20:12.967790 :A8B0E188:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16984 osp_idx=23 osp_ver=166906 osp_pg=0 (spawn #315613) 2025-06-23 20:20:12.967791 :A8B0E189:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 20:20:12.967791 :A8B0E18A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=29 time=3685756157 2025-06-23 20:20:12.967792 :A8B0E18B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 20:20:12.967792 :A8B0E18C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 20:20:12.967792 :A8B0E18D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 20:20:12.967792 :A8B0E18E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 20:20:12.967792 :A8B0E18F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 20:20:12.968280 :A8B0E190: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-06-23 20:20:12.968325 :A8B0E199: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-06-23 20:30:13.760709 :A8B1A99F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 20:30:14.234549 :A8B1AA0B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 20:30:14.229 2025-06-23 20:30:14.234551 :A8B1AA0C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15412 pso_num=25 pso_serial#=25 2025-06-23 20:30:14.234694 :A8B1AA0D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15412 osp_idx=23 osp_ver=166907 osp_pg=0 (spawn #315636) 2025-06-23 20:30:14.234694 :A8B1AA0E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 20:30:14.234695 :A8B1AA0F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=30 time=3686357422 2025-06-23 20:30:14.234695 :A8B1AA10:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 20:30:14.234696 :A8B1AA11:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 20:30:14.234696 :A8B1AA12:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 20:30:14.234696 :A8B1AA13:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 20:30:14.234696 :A8B1AA14:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 20:30:14.235202 :A8B1AA15: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-06-23 20:30:14.235245 :A8B1AA1E: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-06-23 20:40:15.130041 :A8B27276:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 20:40:15.478144 :A8B272D3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 20:40:15.473 2025-06-23 20:40:15.478146 :A8B272D4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16080 pso_num=25 pso_serial#=26 2025-06-23 20:40:15.478279 :A8B272D5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16080 osp_idx=23 osp_ver=166908 osp_pg=0 (spawn #315658) 2025-06-23 20:40:15.478280 :A8B272D6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 20:40:15.478280 :A8B272D7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=31 time=3686958672 2025-06-23 20:40:15.478281 :A8B272D8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 20:40:15.478281 :A8B272D9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 20:40:15.478282 :A8B272DA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 20:40:15.478282 :A8B272DB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 20:40:15.478282 :A8B272DC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 20:40:15.478828 :A8B272DD: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-06-23 20:40:15.478871 :A8B272E5: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-06-23 20:50:16.392277 :A8B33B60:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 20:50:16.663380 :A8B33BC7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 20:50:16.658 2025-06-23 20:50:16.663382 :A8B33BC8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12136 pso_num=25 pso_serial#=27 2025-06-23 20:50:16.663526 :A8B33BC9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12136 osp_idx=23 osp_ver=166909 osp_pg=0 (spawn #315681) 2025-06-23 20:50:16.663527 :A8B33BCA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 20:50:16.663527 :A8B33BCB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=32 time=3687559860 2025-06-23 20:50:16.663527 :A8B33BCC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 20:50:16.663528 :A8B33BCD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 20:50:16.663528 :A8B33BCE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 20:50:16.663528 :A8B33BCF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 20:50:16.663529 :A8B33BD0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 20:50:16.664039 :A8B33BD1: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-06-23 20:50:16.664095 :A8B33BD7: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-06-23 21:00:17.594324 :A8B4041A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 21:00:17.895982 :A8B4047B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 21:00:17.891 2025-06-23 21:00:17.895984 :A8B4047C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12880 pso_num=25 pso_serial#=28 2025-06-23 21:00:17.896125 :A8B4047D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12880 osp_idx=23 osp_ver=166910 osp_pg=0 (spawn #315704) 2025-06-23 21:00:17.896126 :A8B4047E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 21:00:17.896126 :A8B4047F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=33 time=3688161079 2025-06-23 21:00:17.896127 :A8B40480:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 21:00:17.896127 :A8B40481:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 21:00:17.896127 :A8B40482:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 21:00:17.896128 :A8B40483:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 21:00:17.896128 :A8B40484:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 21:00:17.896635 :A8B40485: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-06-23 21:00:17.896681 :A8B4048C: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-06-23 21:10:18.885786 :A8B4D749:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 21:10:19.202702 :A8B4D7C2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 21:10:19.198 2025-06-23 21:10:19.202704 :A8B4D7C3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16800 pso_num=25 pso_serial#=29 2025-06-23 21:10:19.202841 :A8B4D7C4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16800 osp_idx=23 osp_ver=166911 osp_pg=0 (spawn #315729) 2025-06-23 21:10:19.202842 :A8B4D7C5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 21:10:19.202842 :A8B4D7C6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=3688762391 2025-06-23 21:10:19.202843 :A8B4D7C7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 21:10:19.202843 :A8B4D7C8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 21:10:19.202843 :A8B4D7C9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 21:10:19.202844 :A8B4D7CA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 21:10:19.202844 :A8B4D7CB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 21:10:19.203358 :A8B4D7CC: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-06-23 21:10:19.203402 :A8B4D7D5: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-06-23 21:20:20.136105 :A8B5A00F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 21:20:20.515734 :A8B5A077:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 21:20:20.510 2025-06-23 21:20:20.515736 :A8B5A078:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17064 pso_num=25 pso_serial#=30 2025-06-23 21:20:20.515873 :A8B5A079:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17064 osp_idx=23 osp_ver=166912 osp_pg=0 (spawn #315752) 2025-06-23 21:20:20.515874 :A8B5A07A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 21:20:20.515874 :A8B5A07B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=3689363704 2025-06-23 21:20:20.515875 :A8B5A07C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 21:20:20.515875 :A8B5A07D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 21:20:20.515876 :A8B5A07E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 21:20:20.515876 :A8B5A07F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 21:20:20.515876 :A8B5A080:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 21:20:20.516378 :A8B5A081: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-06-23 21:20:20.516424 :A8B5A088: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-06-23 21:30:21.438937 :A8B668F5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 21:30:21.771700 :A8B66956:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 21:30:21.767 2025-06-23 21:30:21.771702 :A8B66957:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18396 pso_num=25 pso_serial#=31 2025-06-23 21:30:21.771842 :A8B66958:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18396 osp_idx=23 osp_ver=166913 osp_pg=0 (spawn #315775) 2025-06-23 21:30:21.771842 :A8B66959:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 21:30:21.771843 :A8B6695A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=3689964969 2025-06-23 21:30:21.771843 :A8B6695B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 21:30:21.771843 :A8B6695C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 21:30:21.771844 :A8B6695D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 21:30:21.771844 :A8B6695E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 21:30:21.771844 :A8B6695F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 21:30:21.772364 :A8B66960: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-06-23 21:30:21.772410 :A8B66968: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-06-23 21:40:22.650008 :A8B73118:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-23 21:40:22.900061 :A8B73177:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-23 21:40:22.895 2025-06-23 21:40:22.900063 :A8B73178:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15536 pso_num=25 pso_serial#=32 2025-06-23 21:40:22.900206 :A8B73179:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15536 osp_idx=23 osp_ver=166914 osp_pg=0 (spawn #315797) 2025-06-23 21:40:22.900207 :A8B7317A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-23 21:40:22.900208 :A8B7317B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=3690566094 2025-06-23 21:40:22.900208 :A8B7317C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 21:40:22.900209 :A8B7317D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 21:40:22.900209 :A8B7317E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 21:40:22.900209 :A8B7317F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 21:40:22.900210 :A8B73180:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 21:40:22.900712 :A8B73181: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-06-23 21:40:22.900754 :A8B73189: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-06-23 21:45:56.981678 :A8B7A15D: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-06-23 21:50:24.130985 :A8B7FAA3: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 15536, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de