Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503180846\orcl_w000_10352_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503180846\orcl_w000_10352_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:37604M/63366M, Ph+PgF:45167M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 10352, image: ORACLE.EXE (W000) *** 2025-05-03 18:08:46.534 *** SESSION ID:(177.20741) 2025-05-03 18:08:46.534 *** 2025-05-03 18:08:46.534 Process diagnostic dump for ORACLE.EXE (W000), OS id=10352, pid: 22, proc_ser: 49, sid: 177, sess_ser: 20741 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=3.492247 sec, exc=3.492247 sec, total=3.492247 sec wait times: max=5.000000 sec, heur=6 min 14 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.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=74 seq_num=75 snap_id=1 wait times: snap=5.008910 sec, exc=5.008910 sec, total=5.008910 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=73 seq_num=74 snap_id=1 wait times: snap=5.006405 sec, exc=5.006405 sec, total=5.006405 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.004613 sec, exc=5.004613 sec, total=5.004613 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.014729 sec, exc=5.014729 sec, total=5.014729 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.015242 sec, exc=5.015242 sec, total=5.015242 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=5.003533 sec, exc=5.003533 sec, total=5.003533 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.000479 sec, exc=5.000479 sec, total=5.000479 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.005619 sec, exc=5.005619 sec, total=5.005619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=66 seq_num=67 snap_id=1 wait times: snap=5.010143 sec, exc=5.010143 sec, total=5.010143 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=65 seq_num=66 snap_id=1 wait times: snap=5.014760 sec, exc=5.014760 sec, total=5.014760 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 177 serial 20741 --------------------------------------------------- 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, 18:06:46 - 18:08:46] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-03 18:08:46.534 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 10352, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-03 15:42:13.663538 :922DBF58:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 15:42:13.663538 :922DBF59:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 15:42:13.663539 :922DBF5A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 15:42:13.664064 :922DBF5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 15:42:13.664110 :922DBF63:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 15:52:14.470813 :922E8C99:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 15:52:15.022426 :922E8CE0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 15:52:15.018 2025-05-03 15:52:15.022428 :922E8CE1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12072 pso_num=22 pso_serial#=35 2025-05-03 15:52:15.022568 :922E8CE2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12072 osp_idx=0 osp_ver=149975 osp_pg=0 (spawn #144466) 2025-05-03 15:52:15.022569 :922E8CE3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 15:52:15.022569 :922E8CE4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=158 time=3558324500 2025-05-03 15:52:15.022570 :922E8CE5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 15:52:15.022571 :922E8CE6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 15:52:15.022571 :922E8CE7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 15:52:15.022571 :922E8CE8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 15:52:15.022572 :922E8CE9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 15:52:15.023106 :922E8CEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 15:52:15.023155 :922E8CF2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 16:02:15.956856 :922F5BDD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 16:02:16.367660 :922F5C1B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 16:02:16.362 2025-05-03 16:02:16.367663 :922F5C1C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9952 pso_num=22 pso_serial#=36 2025-05-03 16:02:16.367787 :922F5C1D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9952 osp_idx=0 osp_ver=149976 osp_pg=0 (spawn #144492) 2025-05-03 16:02:16.367787 :922F5C1E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 16:02:16.367788 :922F5C1F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=159 time=3558925843 2025-05-03 16:02:16.367788 :922F5C20:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 16:02:16.367789 :922F5C21:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 16:02:16.367789 :922F5C22:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 16:02:16.367789 :922F5C23:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 16:02:16.367790 :922F5C24:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 16:02:16.368269 :922F5C25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 16:02:16.368317 :922F5C2F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 16:12:17.270389 :923032F7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 16:12:17.743548 :92303336:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 16:12:17.738 2025-05-03 16:12:17.743550 :92303337:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1164 pso_num=22 pso_serial#=37 2025-05-03 16:12:17.743683 :92303338:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1164 osp_idx=0 osp_ver=149977 osp_pg=0 (spawn #144517) 2025-05-03 16:12:17.743684 :92303339:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 16:12:17.743684 :9230333A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=160 time=3559527218 2025-05-03 16:12:17.743684 :9230333B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 16:12:17.743685 :9230333C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 16:12:17.743685 :9230333D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 16:12:17.743685 :9230333E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 16:12:17.743685 :9230333F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 16:12:17.744170 :92303340:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 16:12:17.744220 :92303347:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 16:22:18.614210 :9231000C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 16:22:19.150136 :92310056:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 16:22:19.145 2025-05-03 16:22:19.150138 :92310057:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9648 pso_num=22 pso_serial#=38 2025-05-03 16:22:19.150275 :92310058:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9648 osp_idx=0 osp_ver=149978 osp_pg=0 (spawn #144540) 2025-05-03 16:22:19.150275 :92310059:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 16:22:19.150276 :9231005A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=161 time=3560128625 2025-05-03 16:22:19.150276 :9231005B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 16:22:19.150278 :9231005C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 16:22:19.150279 :9231005D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 16:22:19.150279 :9231005E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 16:22:19.150279 :9231005F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 16:22:19.150762 :92310060:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 16:22:19.150807 :92310068:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 16:32:20.015831 :9231CD96:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 16:32:20.629914 :9231CDD5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 16:32:20.625 2025-05-03 16:32:20.629917 :9231CDD6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10720 pso_num=22 pso_serial#=39 2025-05-03 16:32:20.630055 :9231CDD7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10720 osp_idx=0 osp_ver=149979 osp_pg=0 (spawn #144563) 2025-05-03 16:32:20.630055 :9231CDD8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 16:32:20.630056 :9231CDD9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=162 time=3560730109 2025-05-03 16:32:20.630056 :9231CDDA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 16:32:20.630056 :9231CDDB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 16:32:20.630057 :9231CDDC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 16:32:20.630057 :9231CDDD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 16:32:20.630057 :9231CDDE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 16:32:20.630564 :9231CDDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 16:32:20.630623 :9231CDE9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 16:34:21.561803 :9231F647:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-03 16:42:21.940080 :92329A22:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-03 16:52:22.890733 :92336779:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 16:52:23.364004 :923367B6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 16:52:23.359 2025-05-03 16:52:23.364006 :923367B7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10084 pso_num=22 pso_serial#=40 2025-05-03 16:52:23.364141 :923367B8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10084 osp_idx=0 osp_ver=149980 osp_pg=0 (spawn #144607) 2025-05-03 16:52:23.364142 :923367B9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 16:52:23.364142 :923367BA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=163 time=3561932843 2025-05-03 16:52:23.364142 :923367BB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 16:52:23.364143 :923367BC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 16:52:23.364143 :923367BD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 16:52:23.364144 :923367BE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 16:52:23.364144 :923367BF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 16:52:23.364636 :923367C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 16:52:23.364680 :923367C8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 17:02:24.240058 :9234359F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:02:24.682269 :923435E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:02:24.677 2025-05-03 17:02:24.682272 :923435E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11296 pso_num=22 pso_serial#=41 2025-05-03 17:02:24.682404 :923435E9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11296 osp_idx=0 osp_ver=149981 osp_pg=0 (spawn #144632) 2025-05-03 17:02:24.682405 :923435EA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 17:02:24.682405 :923435EB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=164 time=3562534171 2025-05-03 17:02:24.682406 :923435EC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 17:02:24.682406 :923435ED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 17:02:24.682407 :923435EE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 17:02:24.682407 :923435EF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 17:02:24.682408 :923435F0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 17:02:24.682908 :923435F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 17:02:24.682952 :923435F7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 17:12:25.530546 :92350C6D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:12:26.035521 :92350CB5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:12:26.030 2025-05-03 17:12:26.035522 :92350CB6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11960 pso_num=22 pso_serial#=42 2025-05-03 17:12:26.035662 :92350CB7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11960 osp_idx=0 osp_ver=149982 osp_pg=0 (spawn #144657) 2025-05-03 17:12:26.035663 :92350CB8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 17:12:26.035663 :92350CB9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=165 time=3563135515 2025-05-03 17:12:26.035664 :92350CBA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 17:12:26.035664 :92350CBB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 17:12:26.035665 :92350CBC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 17:12:26.035665 :92350CBD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 17:12:26.035665 :92350CBE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 17:12:26.036181 :92350CBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 17:12:26.036232 :92350CC6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 17:22:26.978587 :9235D971:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:22:27.389547 :9235D9BA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:22:27.385 2025-05-03 17:22:27.389549 :9235D9BB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8568 pso_num=22 pso_serial#=43 2025-05-03 17:22:27.389682 :9235D9BC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8568 osp_idx=0 osp_ver=149983 osp_pg=0 (spawn #144680) 2025-05-03 17:22:27.389683 :9235D9BD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 17:22:27.389683 :9235D9BE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=166 time=3563736875 2025-05-03 17:22:27.389684 :9235D9BF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 17:22:27.389684 :9235D9C0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 17:22:27.389684 :9235D9C1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 17:22:27.389685 :9235D9C2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 17:22:27.389685 :9235D9C3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 17:22:27.390167 :9235D9C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 17:22:27.390211 :9235D9CC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 17:32:28.318631 :9236A731:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:32:28.667062 :9236A76C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:32:28.662 2025-05-03 17:32:28.667064 :9236A76D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10300 pso_num=22 pso_serial#=44 2025-05-03 17:32:28.667211 :9236A76E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10300 osp_idx=0 osp_ver=149984 osp_pg=0 (spawn #144703) 2025-05-03 17:32:28.667212 :9236A76F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 17:32:28.667212 :9236A770:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=167 time=3564338156 2025-05-03 17:32:28.667213 :9236A771:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 17:32:28.667213 :9236A772:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 17:32:28.667213 :9236A773:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 17:32:28.667214 :9236A774:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 17:32:28.667214 :9236A775:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 17:32:28.667721 :9236A776:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 17:32:28.667773 :9236A780:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 17:42:29.609948 :923774BF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:42:29.685628 :923774C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:42:29.672 2025-05-03 17:42:29.685629 :923774C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5296 pso_num=22 pso_serial#=45 2025-05-03 17:42:29.685760 :923774C4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=5296 (legacy spawn) 2025-05-03 17:42:29.706354 :923774D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 17:42:29.706562 :923774DA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-03 17:42:29.707336 :923774DB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:42:29.715520 :923774DE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:42:29.704 2025-05-03 17:42:29.715522 :923774DF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11412 pso_num=22 pso_serial#=46 2025-05-03 17:42:29.715648 :923774E0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11412 (legacy spawn) 2025-05-03 17:42:29.722206 :923774E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-03 17:42:29.722367 :923774EA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-03 17:42:29.723166 :923774EB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:42:29.958413 :92377525:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:42:29.954 2025-05-03 17:42:29.958415 :92377526:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12004 pso_num=22 pso_serial#=47 2025-05-03 17:42:29.958592 :92377527:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12004 osp_idx=0 osp_ver=149987 osp_pg=0 (spawn #144725) 2025-05-03 17:42:29.958593 :92377528:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 17:42:29.958594 :92377529:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=168 time=3564939437 2025-05-03 17:42:29.958594 :9237752A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 17:42:29.958595 :9237752B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 17:42:29.958596 :9237752C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 17:42:29.958596 :9237752D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 17:42:29.958596 :9237752E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 17:42:29.959115 :9237752F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 17:42:29.959162 :92377536:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 17:52:30.898331 :923841AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 17:52:31.246773 :923841E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 17:52:31.242 2025-05-03 17:52:31.246775 :923841EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7192 pso_num=22 pso_serial#=48 2025-05-03 17:52:31.246910 :923841EB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7192 osp_idx=0 osp_ver=149988 osp_pg=0 (spawn #144749) 2025-05-03 17:52:31.246911 :923841EC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 17:52:31.246911 :923841ED:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=169 time=3565540734 2025-05-03 17:52:31.246912 :923841EE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 17:52:31.246912 :923841EF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 17:52:31.246912 :923841F0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 17:52:31.246913 :923841F1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 17:52:31.246913 :923841F2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 17:52:31.247384 :923841F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 17:52:31.247437 :923841F9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-03 18:02:32.180199 :92391130:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-03 18:02:32.513075 :92391178:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-03 18:02:32.508 2025-05-03 18:02:32.513077 :92391179:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10352 pso_num=22 pso_serial#=49 2025-05-03 18:02:32.513221 :9239117A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10352 osp_idx=0 osp_ver=149989 osp_pg=0 (spawn #144774) 2025-05-03 18:02:32.513222 :9239117B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 18:02:32.513222 :9239117C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=170 time=3566142000 2025-05-03 18:02:32.513222 :9239117D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 18:02:32.513222 :9239117E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 18:02:32.513223 :9239117F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 18:02:32.513223 :92391180:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 18:02:32.513223 :92391181:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 18:02:32.513721 :92391182:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 18:02:32.513770 :92391189:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 10352, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de