Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250615140829\orcl_w000_16012_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250615140829\orcl_w000_16012_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:36645M/63366M, Ph+PgF:43136M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16012, image: ORACLE.EXE (W000) *** 2025-06-15 14:08:29.936 *** SESSION ID:(201.25015) 2025-06-15 14:08:29.936 *** 2025-06-15 14:08:29.936 Process diagnostic dump for ORACLE.EXE (W000), OS id=16012, pid: 25, proc_ser: 38, sid: 201, sess_ser: 25015 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=2.638913 sec, exc=2.638913 sec, total=2.638913 sec wait times: max=5.000000 sec, heur=2 min 42 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.000019 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.008395 sec, exc=5.008395 sec, total=5.008395 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=5.002293 sec, exc=5.002293 sec, total=5.002293 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.014514 sec, exc=5.014514 sec, total=5.014514 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=29 seq_num=30 snap_id=1 wait times: snap=5.014723 sec, exc=5.014723 sec, total=5.014723 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.004629 sec, exc=5.004629 sec, total=5.004629 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: 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.014732 sec, exc=5.014732 sec, total=5.014732 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 7: 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.015246 sec, exc=5.015246 sec, total=5.015246 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: 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.003379 sec, exc=5.003379 sec, total=5.003379 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: 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.000865 sec, exc=5.000865 sec, total=5.000865 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000041 sec of elapsed time Sampled Session History of session 201 serial 25015 --------------------------------------------------- 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:06:29 - 14:08:29] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-15 14:08:29.936 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16012, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-15 11:45:29.705519 :A4FF2052:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 11:45:29.706021 :A4FF2053: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-15 11:45:29.706068 :A4FF205B: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-15 11:55:30.520807 :A4FFE908:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 11:55:31.011587 :A4FFE94C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 11:55:31.006 2025-06-15 11:55:31.011589 :A4FFE94D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11704 pso_num=25 pso_serial#=24 2025-06-15 11:55:31.011729 :A4FFE94E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11704 osp_idx=23 osp_ver=158491 osp_pg=0 (spawn #287764) 2025-06-15 11:55:31.011730 :A4FFE94F: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-15 11:55:31.011731 :A4FFE950:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=121 time=2964274204 2025-06-15 11:55:31.011731 :A4FFE951:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 11:55:31.011732 :A4FFE952:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 11:55:31.011732 :A4FFE953:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 11:55:31.011732 :A4FFE954:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 11:55:31.011733 :A4FFE955:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 11:55:31.012241 :A4FFE956: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-15 11:55:31.012292 :A4FFE960: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-15 12:05:31.871761 :A500BA6E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:05:32.345250 :A500BAB5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:05:32.340 2025-06-15 12:05:32.345252 :A500BAB6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16260 pso_num=25 pso_serial#=25 2025-06-15 12:05:32.345387 :A500BAB7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16260 osp_idx=23 osp_ver=158492 osp_pg=0 (spawn #287790) 2025-06-15 12:05:32.345388 :A500BAB8: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-15 12:05:32.345388 :A500BAB9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=2964875532 2025-06-15 12:05:32.345389 :A500BABA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 12:05:32.345389 :A500BABB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 12:05:32.345390 :A500BABC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 12:05:32.345390 :A500BABD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 12:05:32.345390 :A500BABE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 12:05:32.345870 :A500BABF: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-15 12:05:32.345920 :A500BAC7: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-15 12:15:33.183699 :A5018605:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:15:33.625901 :A501863E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:15:33.621 2025-06-15 12:15:33.625904 :A501863F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16800 pso_num=25 pso_serial#=26 2025-06-15 12:15:33.626046 :A5018640:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16800 osp_idx=23 osp_ver=158493 osp_pg=0 (spawn #287812) 2025-06-15 12:15:33.626046 :A5018641: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-15 12:15:33.626047 :A5018642:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=2965476813 2025-06-15 12:15:33.626047 :A5018643:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 12:15:33.626048 :A5018644:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 12:15:33.626048 :A5018645:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 12:15:33.626048 :A5018646:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 12:15:33.626050 :A5018647:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 12:15:33.626548 :A5018648: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-15 12:15:33.626593 :A501864F: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-15 12:25:34.415583 :A5024ED5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:25:34.631643 :A5024ED8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:25:34.618 2025-06-15 12:25:34.631645 :A5024ED9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17060 pso_num=25 pso_serial#=27 2025-06-15 12:25:34.631816 :A5024EDA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17060 (legacy spawn) 2025-06-15 12:25:34.638223 :A5024EDB: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-06-15 12:25:34.638394 :A5024EE0: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-06-15 12:25:34.639190 :A5024EE5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:25:34.647884 :A5024EE8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:25:34.633 2025-06-15 12:25:34.647886 :A5024EE9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13316 pso_num=25 pso_serial#=28 2025-06-15 12:25:34.648027 :A5024EEA:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13316 (legacy spawn) 2025-06-15 12:25:34.654508 :A5024EEB: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-06-15 12:25:34.654539 :A5024EEE: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-06-15 12:25:34.654695 :A5024EF5: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-06-15 12:25:34.655494 :A5024F02:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:25:34.930316 :A5024F61:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:25:34.925 2025-06-15 12:25:34.930318 :A5024F62:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15924 pso_num=25 pso_serial#=29 2025-06-15 12:25:34.930458 :A5024F63:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15924 osp_idx=23 osp_ver=158496 osp_pg=0 (spawn #287835) 2025-06-15 12:25:34.930459 :A5024F64: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-15 12:25:34.930459 :A5024F65:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=2966078125 2025-06-15 12:25:34.930460 :A5024F66:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 12:25:34.930460 :A5024F67:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 12:25:34.930461 :A5024F68:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 12:25:34.930461 :A5024F69:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 12:25:34.930461 :A5024F6A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 12:25:34.930975 :A5024F6B: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-15 12:25:34.931023 :A5024F73: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-15 12:35:35.826620 :A50318DD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:35:36.284432 :A503194D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:35:36.279 2025-06-15 12:35:36.284434 :A503194E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16912 pso_num=25 pso_serial#=30 2025-06-15 12:35:36.284589 :A503194F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16912 osp_idx=23 osp_ver=158497 osp_pg=0 (spawn #287856) 2025-06-15 12:35:36.284590 :A5031950: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-15 12:35:36.284590 :A5031951:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=2966679469 2025-06-15 12:35:36.284591 :A5031952:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 12:35:36.284591 :A5031953:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 12:35:36.284591 :A5031954:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 12:35:36.284592 :A5031955:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 12:35:36.284592 :A5031956:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 12:35:36.285132 :A5031957: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-15 12:35:36.285183 :A503195F: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-15 12:45:37.041205 :A503E262:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:45:37.514624 :A503E29A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:45:37.510 2025-06-15 12:45:37.514627 :A503E29B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9804 pso_num=25 pso_serial#=31 2025-06-15 12:45:37.514759 :A503E29C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9804 osp_idx=23 osp_ver=158498 osp_pg=0 (spawn #287878) 2025-06-15 12:45:37.514759 :A503E29D: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-15 12:45:37.514760 :A503E29E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=2967280704 2025-06-15 12:45:37.514760 :A503E29F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 12:45:37.514760 :A503E2A0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 12:45:37.514761 :A503E2A1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 12:45:37.514761 :A503E2A2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 12:45:37.514761 :A503E2A3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 12:45:37.515253 :A503E2A4: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-15 12:45:37.515309 :A503E2AC: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-15 12:55:38.415307 :A504AAAE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 12:55:38.826083 :A504AAF0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 12:55:38.822 2025-06-15 12:55:38.826085 :A504AAF1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14480 pso_num=25 pso_serial#=32 2025-06-15 12:55:38.826282 :A504AAF2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14480 osp_idx=23 osp_ver=158499 osp_pg=0 (spawn #287901) 2025-06-15 12:55:38.826282 :A504AAF3: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-15 12:55:38.826282 :A504AAF4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=2967882016 2025-06-15 12:55:38.826283 :A504AAF5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 12:55:38.826283 :A504AAF6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 12:55:38.826283 :A504AAF7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 12:55:38.826284 :A504AAF8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 12:55:38.826284 :A504AAF9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 12:55:38.826790 :A504AAFA: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-15 12:55:38.826846 :A504AB00: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-15 13:05:39.657216 :A5058041:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 13:05:40.005879 :A5058087:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 13:05:40.001 2025-06-15 13:05:40.005881 :A5058088:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13888 pso_num=25 pso_serial#=33 2025-06-15 13:05:40.006025 :A5058089:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13888 osp_idx=23 osp_ver=158500 osp_pg=0 (spawn #287926) 2025-06-15 13:05:40.006026 :A505808A: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-15 13:05:40.006026 :A505808B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=128 time=2968483204 2025-06-15 13:05:40.006027 :A505808C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 13:05:40.006027 :A505808D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 13:05:40.006027 :A505808E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 13:05:40.006028 :A505808F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 13:05:40.006028 :A5058090:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 13:05:40.006534 :A5058091: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-15 13:05:40.006580 :A5058098: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-15 13:15:40.869991 :A50648B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 13:15:41.234123 :A5064901:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 13:15:41.229 2025-06-15 13:15:41.234125 :A5064902:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7708 pso_num=25 pso_serial#=34 2025-06-15 13:15:41.234265 :A5064903:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7708 osp_idx=23 osp_ver=158501 osp_pg=0 (spawn #287948) 2025-06-15 13:15:41.234266 :A5064904: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-15 13:15:41.234267 :A5064905:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=129 time=2969084422 2025-06-15 13:15:41.234267 :A5064906:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 13:15:41.234268 :A5064907:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 13:15:41.234268 :A5064908:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 13:15:41.234268 :A5064909:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 13:15:41.234269 :A506490A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 13:15:41.234760 :A506490B: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-15 13:15:41.234806 :A5064912: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-15 13:25:42.097741 :A50711C2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 13:25:42.353246 :A50711FC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 13:25:42.347 2025-06-15 13:25:42.353248 :A50711FD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7588 pso_num=25 pso_serial#=35 2025-06-15 13:25:42.353406 :A50711FE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7588 osp_idx=23 osp_ver=158502 osp_pg=0 (spawn #287971) 2025-06-15 13:25:42.353406 :A50711FF: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-15 13:25:42.353407 :A5071200:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=2969685547 2025-06-15 13:25:42.353407 :A5071201:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 13:25:42.353408 :A5071202:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 13:25:42.353408 :A5071203:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 13:25:42.353408 :A5071204:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 13:25:42.353408 :A5071205:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 13:25:42.353996 :A5071206: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-15 13:25:42.354067 :A5071210: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-15 13:30:10.614900 :A5076BC0: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-15 13:35:43.512068 :A507DA79: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-15 13:45:44.375660 :A508A32D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 13:45:44.645853 :A508A367:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 13:45:44.641 2025-06-15 13:45:44.645855 :A508A368:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13416 pso_num=25 pso_serial#=36 2025-06-15 13:45:44.645991 :A508A369:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13416 osp_idx=23 osp_ver=158503 osp_pg=0 (spawn #288013) 2025-06-15 13:45:44.645992 :A508A36A: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-15 13:45:44.645992 :A508A36B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=2970887844 2025-06-15 13:45:44.645993 :A508A36C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 13:45:44.645993 :A508A36D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 13:45:44.645993 :A508A36E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 13:45:44.645994 :A508A36F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 13:45:44.645994 :A508A370:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 13:45:44.646481 :A508A371: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-15 13:45:44.646528 :A508A378: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-15 13:55:45.578487 :A5096BC1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 13:55:45.880235 :A5096C08:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 13:55:45.875 2025-06-15 13:55:45.880237 :A5096C09:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=3836 pso_num=25 pso_serial#=37 2025-06-15 13:55:45.880376 :A5096C0A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=3836 osp_idx=23 osp_ver=158504 osp_pg=0 (spawn #288036) 2025-06-15 13:55:45.880377 :A5096C0B: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-15 13:55:45.880377 :A5096C0C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=132 time=2971489063 2025-06-15 13:55:45.880377 :A5096C0D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 13:55:45.880378 :A5096C0E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 13:55:45.880378 :A5096C0F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 13:55:45.880378 :A5096C10:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 13:55:45.880379 :A5096C11:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 13:55:45.880868 :A5096C12: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-15 13:55:45.880914 :A5096C19: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-15 14:05:46.809794 :A50A3DDA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-15 14:05:47.033377 :A50A3E15:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-15 14:05:47.028 2025-06-15 14:05:47.033379 :A50A3E16:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16012 pso_num=25 pso_serial#=38 2025-06-15 14:05:47.033523 :A50A3E17:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16012 osp_idx=23 osp_ver=158505 osp_pg=0 (spawn #288062) 2025-06-15 14:05:47.033524 :A50A3E18: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-15 14:05:47.033524 :A50A3E19:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=133 time=2972090219 2025-06-15 14:05:47.033525 :A50A3E1A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-15 14:05:47.033525 :A50A3E1B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-15 14:05:47.033525 :A50A3E1C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-15 14:05:47.033525 :A50A3E1D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-15 14:05:47.033526 :A50A3E1E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-15 14:05:47.034035 :A50A3E1F: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-15 14:05:47.034079 :A50A3E26: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: 16012, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de