Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525100351\orcl_w000_14468_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250525100351\orcl_w000_14468_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:37327M/63366M, Ph+PgF:44373M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 14468, image: ORACLE.EXE (W000) *** 2025-05-25 10:03:51.722 *** SESSION ID:(193.18711) 2025-05-25 10:03:51.722 *** 2025-05-25 10:03:51.722 Process diagnostic dump for ORACLE.EXE (W000), OS id=14468, pid: 24, proc_ser: 181, sid: 193, sess_ser: 18711 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=1.076469 sec, exc=1.076469 sec, total=1.076469 sec wait times: max=5.000000 sec, heur=1 min 41 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=20 seq_num=21 snap_id=1 wait times: snap=5.009798 sec, exc=5.009798 sec, total=5.009798 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=19 seq_num=20 snap_id=1 wait times: snap=5.009213 sec, exc=5.009213 sec, total=5.009213 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=5.003575 sec, exc=5.003575 sec, total=5.003575 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=5.000668 sec, exc=5.000668 sec, total=5.000668 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=5.003218 sec, exc=5.003218 sec, total=5.003218 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=5.000106 sec, exc=5.000106 sec, total=5.000106 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.000100 sec, exc=5.000100 sec, total=5.000100 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=13 seq_num=14 snap_id=1 wait times: snap=5.014715 sec, exc=5.014715 sec, total=5.014715 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 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 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.014724 sec, exc=5.014724 sec, total=5.014724 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time Sampled Session History of session 193 serial 18711 --------------------------------------------------- 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 --------------------------------------------------- [102 samples, 10:02:10 - 10:03:51] idle wait at each sample [session created at: 10:02:10] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-25 10:03:51.722 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 14468, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-25 07:51:52.563881 :9BB7027E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 07:51:52.563882 :9BB7027F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=14 time=1135284813 2025-05-25 07:51:52.563882 :9BB70280:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 07:51:52.563883 :9BB70281:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 07:51:52.563883 :9BB70282:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 07:51:52.563883 :9BB70283:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 07:51:52.563884 :9BB70284:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 07:51:52.564383 :9BB70285:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 07:51:52.564438 :9BB7028D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 07:52:22.611230 :9BB70C64:db_trace:ksl2.c@2477:kslwtbctx(): [10005:24:193] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-05-25 07:52:22.611236 :9BB70C65:db_trace:ksl2.c@2611:kslwtectx(): [10005:24:193] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-05-25 07:52:22.611236 :9BB70C66:db_trace:ksl2.c@2619:kslwtectx(): [10005:24:193] KSL WAIT END wait times (usecs) - snap=7, exc=7, tot=7 2025-05-25 07:52:22.611246 :9BB70C6B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 07:52:52.659683 :9BB71654:db_trace:ksl2.c@2477:kslwtbctx(): [10005:24:193] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=15 seq_num=16 snap_id=1 2025-05-25 07:52:52.659705 :9BB7165A:db_trace:ksl2.c@2611:kslwtectx(): [10005:24:193] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=15 seq_num=16 snap_id=1 2025-05-25 07:52:52.659706 :9BB7165B:db_trace:ksl2.c@2619:kslwtectx(): [10005:24:193] KSL WAIT END wait times (usecs) - snap=22, exc=22, tot=22 2025-05-25 08:01:53.562865 :9BB7C7CC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:01:53.835277 :9BB7C811:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:01:53.830 2025-05-25 08:01:53.835279 :9BB7C812:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13548 pso_num=24 pso_serial#=168 2025-05-25 08:01:53.835412 :9BB7C813:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13548 osp_idx=22 osp_ver=319427 osp_pg=0 (spawn #217226) 2025-05-25 08:01:53.835413 :9BB7C814:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 08:01:53.835414 :9BB7C815:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=15 time=1135886094 2025-05-25 08:01:53.835414 :9BB7C816:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 08:01:53.835414 :9BB7C817:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 08:01:53.835415 :9BB7C818:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 08:01:53.835415 :9BB7C819:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 08:01:53.835415 :9BB7C81A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 08:01:53.835914 :9BB7C81B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 08:01:53.835958 :9BB7C822:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:11:54.921151 :9BB8962A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:11:55.029081 :9BB8962D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:11:55.015 2025-05-25 08:11:55.029083 :9BB8962E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14408 pso_num=24 pso_serial#=169 2025-05-25 08:11:55.029222 :9BB8962F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14408 (legacy spawn) 2025-05-25 08:11:55.035659 :9BB89630:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 08:11:55.035837 :9BB89635:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:11:55.036650 :9BB8963A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:11:55.054329 :9BB8963D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:11:55.046 2025-05-25 08:11:55.054331 :9BB8963E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4696 pso_num=24 pso_serial#=170 2025-05-25 08:11:55.054474 :9BB8963F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4696 (legacy spawn) 2025-05-25 08:11:55.060912 :9BB89640:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-25 08:11:55.061076 :9BB89649:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:11:55.061893 :9BB8964A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:11:55.128784 :9BB89684:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:11:55.124 2025-05-25 08:11:55.128786 :9BB89685:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9492 pso_num=24 pso_serial#=171 2025-05-25 08:11:55.128926 :9BB89686:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9492 osp_idx=22 osp_ver=319430 osp_pg=0 (spawn #217249) 2025-05-25 08:11:55.128927 :9BB89687:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 08:11:55.128927 :9BB89688:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=16 time=1136487375 2025-05-25 08:11:55.128928 :9BB89689:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 08:11:55.128928 :9BB8968A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 08:11:55.128928 :9BB8968B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 08:11:55.128929 :9BB8968C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 08:11:55.128929 :9BB8968D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 08:11:55.129418 :9BB8968E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 08:11:55.129467 :9BB89696:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:17:02.865892 :9BB94C58:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:21:56.471897 :9BB9CA33:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:31:57.440653 :9BBA93C0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:31:57.773287 :9BBA93F8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:31:57.768 2025-05-25 08:31:57.773289 :9BBA93F9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9764 pso_num=24 pso_serial#=172 2025-05-25 08:31:57.773431 :9BBA93FA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9764 osp_idx=22 osp_ver=319431 osp_pg=0 (spawn #217299) 2025-05-25 08:31:57.773432 :9BBA93FB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 08:31:57.773433 :9BBA93FC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=17 time=1137690032 2025-05-25 08:31:57.773433 :9BBA93FD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 08:31:57.773434 :9BBA93FE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 08:31:57.773434 :9BBA93FF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 08:31:57.773435 :9BBA9400:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 08:31:57.773435 :9BBA9401:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 08:31:57.773964 :9BBA9402:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 08:31:57.774011 :9BBA940B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:41:58.707488 :9BBB592F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:41:59.166201 :9BBB596A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:41:59.161 2025-05-25 08:41:59.166203 :9BBB596B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6652 pso_num=24 pso_serial#=173 2025-05-25 08:41:59.166337 :9BBB596C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6652 osp_idx=22 osp_ver=319432 osp_pg=0 (spawn #217319) 2025-05-25 08:41:59.166337 :9BBB596D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 08:41:59.166338 :9BBB596E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=18 time=1138291422 2025-05-25 08:41:59.166340 :9BBB596F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 08:41:59.166340 :9BBB5970:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 08:41:59.166341 :9BBB5971:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 08:41:59.166341 :9BBB5972:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 08:41:59.166342 :9BBB5973:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 08:41:59.166894 :9BBB5974:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 08:41:59.166939 :9BBB597C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 08:52:00.145776 :9BBC1E1F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 08:52:00.431932 :9BBC1E5A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 08:52:00.426 2025-05-25 08:52:00.431934 :9BBC1E5B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15128 pso_num=24 pso_serial#=174 2025-05-25 08:52:00.432074 :9BBC1E5C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15128 osp_idx=22 osp_ver=319433 osp_pg=0 (spawn #217342) 2025-05-25 08:52:00.432075 :9BBC1E5D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 08:52:00.432075 :9BBC1E5E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=19 time=1138892688 2025-05-25 08:52:00.432076 :9BBC1E5F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 08:52:00.432076 :9BBC1E60:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 08:52:00.432076 :9BBC1E61:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 08:52:00.432077 :9BBC1E62:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 08:52:00.432077 :9BBC1E63:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 08:52:00.432578 :9BBC1E64:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 08:52:00.432627 :9BBC1E6D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 09:02:01.340392 :9BBCEF16:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 09:02:01.753428 :9BBCEF4D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 09:02:01.748 2025-05-25 09:02:01.753430 :9BBCEF4E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14404 pso_num=24 pso_serial#=175 2025-05-25 09:02:01.753564 :9BBCEF4F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14404 osp_idx=22 osp_ver=319434 osp_pg=0 (spawn #217367) 2025-05-25 09:02:01.753564 :9BBCEF50:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:02:01.753565 :9BBCEF51:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=20 time=1139494000 2025-05-25 09:02:01.753565 :9BBCEF52:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:02:01.753565 :9BBCEF53:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:02:01.753566 :9BBCEF54:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:02:01.753566 :9BBCEF55:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:02:01.753566 :9BBCEF56:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:02:01.754061 :9BBCEF57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:02:01.754105 :9BBCEF60:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 09:12:02.715097 :9BBDBC77:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 09:12:03.204395 :9BBDBCB7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 09:12:03.199 2025-05-25 09:12:03.204397 :9BBDBCB8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14016 pso_num=24 pso_serial#=176 2025-05-25 09:12:03.204539 :9BBDBCB9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14016 osp_idx=22 osp_ver=319435 osp_pg=0 (spawn #217390) 2025-05-25 09:12:03.204540 :9BBDBCBA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:12:03.204541 :9BBDBCBB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=21 time=1140095454 2025-05-25 09:12:03.204541 :9BBDBCBC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:12:03.204541 :9BBDBCBD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:12:03.204542 :9BBDBCBE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:12:03.204542 :9BBDBCBF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:12:03.204542 :9BBDBCC0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:12:03.205064 :9BBDBCC1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:12:03.205109 :9BBDBCC9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 09:22:04.117702 :9BBE84DE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 09:22:04.622510 :9BBE8530:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 09:22:04.618 2025-05-25 09:22:04.622512 :9BBE8531:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14200 pso_num=24 pso_serial#=177 2025-05-25 09:22:04.622655 :9BBE8532:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14200 osp_idx=22 osp_ver=319436 osp_pg=0 (spawn #217413) 2025-05-25 09:22:04.622656 :9BBE8533:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:22:04.622656 :9BBE8534:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=22 time=1140696875 2025-05-25 09:22:04.622657 :9BBE8535:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:22:04.622657 :9BBE8536:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:22:04.622657 :9BBE8537:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:22:04.622658 :9BBE8538:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:22:04.622658 :9BBE8539:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:22:04.623144 :9BBE853A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:22:04.623196 :9BBE8542:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 09:32:05.464948 :9BBF4BA2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 09:32:06.032331 :9BBF4BDC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 09:32:06.027 2025-05-25 09:32:06.032334 :9BBF4BDD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13936 pso_num=24 pso_serial#=178 2025-05-25 09:32:06.032477 :9BBF4BDE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13936 osp_idx=22 osp_ver=319437 osp_pg=0 (spawn #217436) 2025-05-25 09:32:06.032478 :9BBF4BDF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:32:06.032479 :9BBF4BE0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=23 time=1141298282 2025-05-25 09:32:06.032479 :9BBF4BE1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:32:06.032479 :9BBF4BE2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:32:06.032479 :9BBF4BE3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:32:06.032479 :9BBF4BE4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:32:06.032480 :9BBF4BE5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:32:06.032971 :9BBF4BE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:32:06.033016 :9BBF4BED:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 09:42:06.836359 :9BC0120B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 09:42:07.559806 :9BC01256:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 09:42:07.555 2025-05-25 09:42:07.559808 :9BC01257:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12436 pso_num=24 pso_serial#=179 2025-05-25 09:42:07.559946 :9BC01258:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12436 osp_idx=22 osp_ver=319438 osp_pg=0 (spawn #217458) 2025-05-25 09:42:07.559947 :9BC01259:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:42:07.559947 :9BC0125A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=1141899813 2025-05-25 09:42:07.559948 :9BC0125B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:42:07.559948 :9BC0125C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:42:07.559949 :9BC0125D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:42:07.559949 :9BC0125E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:42:07.559949 :9BC0125F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:42:07.560434 :9BC01260:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:42:07.560481 :9BC0126A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 09:52:08.574682 :9BC0D797:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 09:52:09.081412 :9BC0D7DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 09:52:09.076 2025-05-25 09:52:09.081414 :9BC0D7E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14464 pso_num=24 pso_serial#=180 2025-05-25 09:52:09.081548 :9BC0D7E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14464 osp_idx=22 osp_ver=319439 osp_pg=0 (spawn #217481) 2025-05-25 09:52:09.081549 :9BC0D7E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 09:52:09.081549 :9BC0D7E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=25 time=1142501329 2025-05-25 09:52:09.081550 :9BC0D7E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 09:52:09.081550 :9BC0D7E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 09:52:09.081550 :9BC0D7E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 09:52:09.081550 :9BC0D7E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 09:52:09.081551 :9BC0D7E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 09:52:09.082027 :9BC0D7E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 09:52:09.082074 :9BC0D7F2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-25 10:02:10.058360 :9BC19FA2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-25 10:02:10.547405 :9BC19FDD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-25 10:02:10.543 2025-05-25 10:02:10.547407 :9BC19FDE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14468 pso_num=24 pso_serial#=181 2025-05-25 10:02:10.547545 :9BC19FDF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14468 osp_idx=22 osp_ver=319440 osp_pg=0 (spawn #217506) 2025-05-25 10:02:10.547546 :9BC19FE0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-25 10:02:10.547546 :9BC19FE1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=26 time=1143102797 2025-05-25 10:02:10.547547 :9BC19FE2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-25 10:02:10.547547 :9BC19FE3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-25 10:02:10.547548 :9BC19FE4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-25 10:02:10.547548 :9BC19FE5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-25 10:02:10.547548 :9BC19FE6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-25 10:02:10.548034 :9BC19FE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-25 10:02:10.548080 :9BC19FED:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 14468, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de