Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250506082202\orcl_j001_4460_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250506082202\orcl_j001_4460_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:37457M/63366M, Ph+PgF:44933M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 4460, image: ORACLE.EXE (J001) *** 2025-05-06 08:22:02.309 *** SESSION ID:(201.38839) 2025-05-06 08:22:02.309 *** 2025-05-06 08:22:02.309 Process diagnostic dump for ORACLE.EXE (J001), OS id=4460, pid: 25, proc_ser: 176, sid: 201, sess_ser: 38839 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4460 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=0.034749 sec, exc=0.034749 sec, total=0.034749 sec wait times: max=0.500000 sec, heur=27.639827 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.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=54 seq_num=55 snap_id=1 wait times: snap=0.509583 sec, exc=0.509583 sec, total=0.509583 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.500103 sec, exc=0.500103 sec, total=0.500103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.500100 sec, exc=0.500100 sec, total=0.500100 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.510794 sec, exc=0.510794 sec, total=0.510794 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time Sampled Session History of session 201 serial 38839 --------------------------------------------------- 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 --------------------------------------------------- [28 samples, 08:21:34 - 08:22:02] idle wait at each sample [session created at: 08:21:34] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-06 08:22:02.309 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 4460, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-06 08:07:09.283963 :935FF06F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-06 08:07:09.283977 :935FF070:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-06 08:07:09.284182 :935FF071:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-06 08:07:09.284195 :935FF072:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-06 08:07:09.284405 :935FF073:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-06 08:07:09.284418 :935FF074:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-06 08:07:09.284618 :935FF075:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-06 08:07:09.284630 :935FF076:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-06 08:07:09.286964 :935FF077:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:07:33.484116 :935FF840:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:07:33.473 2025-05-06 08:07:33.484118 :935FF841:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11288 pso_num=25 pso_serial#=162 2025-05-06 08:07:33.484255 :935FF842:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11288 osp_idx=23 osp_ver=76406 osp_pg=0 (spawn #153556) 2025-05-06 08:07:33.484256 :935FF843:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:07:33.484256 :935FF844:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=224 time=3789630640 2025-05-06 08:07:33.484257 :935FF845:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:07:33.484257 :935FF846:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:07:33.484257 :935FF847:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:07:33.484257 :935FF848:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:07:33.484258 :935FF849:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:07:33.484767 :935FF84A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:08:03.537528 :936001C6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:08:33.554861 :93600B44:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:08:33.543 2025-05-06 08:08:33.554863 :93600B45:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9052 pso_num=25 pso_serial#=163 2025-05-06 08:08:33.555005 :93600B46:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9052 osp_idx=23 osp_ver=76407 osp_pg=0 (spawn #153558) 2025-05-06 08:08:33.555006 :93600B47:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:08:33.555006 :93600B48:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=225 time=3789690718 2025-05-06 08:08:33.555006 :93600B49:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:08:33.555007 :93600B4A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:08:33.555007 :93600B4B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:08:33.555007 :93600B4C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:08:33.555007 :93600B4D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:08:33.555491 :93600B4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:09:03.635367 :936014F4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:09:33.614738 :93601E63:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:09:33.603 2025-05-06 08:09:33.614741 :93601E64:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11408 pso_num=25 pso_serial#=164 2025-05-06 08:09:33.614886 :93601E65:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11408 osp_idx=23 osp_ver=76408 osp_pg=0 (spawn #153560) 2025-05-06 08:09:33.614887 :93601E66:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:09:33.614888 :93601E67:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=226 time=3789750781 2025-05-06 08:09:33.614888 :93601E68:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:09:33.614889 :93601E69:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:09:33.614889 :93601E6A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:09:33.614889 :93601E6B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:09:33.614890 :93601E6C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:09:33.615441 :93601E6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:10:03.728880 :936027F3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:10:28.695906 :93603040:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:10:28.684 2025-05-06 08:10:28.695908 :93603041:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9376 pso_num=25 pso_serial#=165 2025-05-06 08:10:28.696037 :93603042:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9376 osp_idx=23 osp_ver=76409 osp_pg=0 (spawn #153562) 2025-05-06 08:10:28.696037 :93603043:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:10:28.696038 :93603044:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=3789805859 2025-05-06 08:10:28.696038 :93603045:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:10:28.696039 :93603046:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:10:28.696039 :93603047:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:10:28.696039 :93603048:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:10:28.696040 :93603049:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:10:28.696523 :9360304A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:10:33.696452 :93603182:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 08:11:03.744888 :93603B40:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:11:33.788532 :93604518:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:11:33.777 2025-05-06 08:11:33.788534 :93604519:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7444 pso_num=25 pso_serial#=166 2025-05-06 08:11:33.788655 :9360451A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7444 osp_idx=23 osp_ver=76410 osp_pg=0 (spawn #153565) 2025-05-06 08:11:33.788656 :9360451B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:11:33.788656 :9360451C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=3789870953 2025-05-06 08:11:33.788657 :9360451D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:11:33.788657 :9360451E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:11:33.788658 :9360451F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:11:33.788658 :93604520:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:11:33.788658 :93604521:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:11:33.789165 :93604522:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:12:03.837041 :93604F2C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:12:33.850092 :936058AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:12:33.838 2025-05-06 08:12:33.850094 :936058AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11224 pso_num=25 pso_serial#=167 2025-05-06 08:12:33.850225 :936058AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11224 osp_idx=23 osp_ver=76411 osp_pg=0 (spawn #153567) 2025-05-06 08:12:33.850226 :936058AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:12:33.850227 :936058AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=3789931015 2025-05-06 08:12:33.850227 :936058B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:12:33.850228 :936058B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:12:33.850228 :936058B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:12:33.850228 :936058B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:12:33.850228 :936058B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:12:33.850710 :936058B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:13:03.925618 :936061EB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:13:33.922737 :93606BA7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:13:33.911 2025-05-06 08:13:33.922739 :93606BA8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8872 pso_num=25 pso_serial#=168 2025-05-06 08:13:33.922856 :93606BA9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8872 osp_idx=23 osp_ver=76412 osp_pg=0 (spawn #153569) 2025-05-06 08:13:33.922857 :93606BAA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:13:33.922857 :93606BAB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=3789991078 2025-05-06 08:13:33.922858 :93606BAC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:13:33.922858 :93606BAD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:13:33.922858 :93606BAE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:13:33.922859 :93606BAF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:13:33.922859 :93606BB0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:13:33.923335 :93606BB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:14:03.991354 :93607514:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:14:34.015553 :93607EA3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:14:34.004 2025-05-06 08:14:34.015555 :93607EA4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10056 pso_num=25 pso_serial#=169 2025-05-06 08:14:34.015684 :93607EA5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10056 osp_idx=23 osp_ver=76413 osp_pg=0 (spawn #153571) 2025-05-06 08:14:34.015684 :93607EA6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:14:34.015685 :93607EA7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=3790051171 2025-05-06 08:14:34.015685 :93607EA8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:14:34.015686 :93607EA9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:14:34.015686 :93607EAA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:14:34.015686 :93607EAB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:14:34.015686 :93607EAC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:14:34.016158 :93607EAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:15:04.079893 :93608888:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:15:34.118363 :93609218:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:15:34.107 2025-05-06 08:15:34.118365 :93609219:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11176 pso_num=25 pso_serial#=170 2025-05-06 08:15:34.118496 :9360921A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11176 osp_idx=23 osp_ver=76414 osp_pg=0 (spawn #153573) 2025-05-06 08:15:34.118496 :9360921B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:15:34.118497 :9360921C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=3790111281 2025-05-06 08:15:34.118497 :9360921D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:15:34.118497 :9360921E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:15:34.118498 :9360921F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:15:34.118498 :93609220:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:15:34.118499 :93609221:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:15:34.118960 :93609222:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:16:04.180083 :93609BAC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:16:34.163538 :9360A603:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:16:34.152 2025-05-06 08:16:34.163540 :9360A604:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10440 pso_num=25 pso_serial#=171 2025-05-06 08:16:34.163675 :9360A605:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10440 osp_idx=23 osp_ver=76415 osp_pg=0 (spawn #153575) 2025-05-06 08:16:34.163676 :9360A606:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:16:34.163677 :9360A607:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=3790171328 2025-05-06 08:16:34.163677 :9360A608:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:16:34.163677 :9360A609:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:16:34.163678 :9360A60A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:16:34.163678 :9360A60B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:16:34.163680 :9360A60C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:16:34.164152 :9360A60D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:17:04.258851 :9360B07A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:17:34.297809 :9360BA5D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:17:34.287 2025-05-06 08:17:34.297811 :9360BA5E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9484 pso_num=25 pso_serial#=172 2025-05-06 08:17:34.297950 :9360BA5F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9484 osp_idx=23 osp_ver=76416 osp_pg=0 (spawn #153578) 2025-05-06 08:17:34.297951 :9360BA60:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:17:34.297951 :9360BA61:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=3790231453 2025-05-06 08:17:34.297951 :9360BA62:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:17:34.297952 :9360BA63:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:17:34.297952 :9360BA64:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:17:34.297952 :9360BA65:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:17:34.297953 :9360BA66:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:17:34.298434 :9360BA67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:18:04.405850 :9360C3FE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:18:34.392389 :9360CD50:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:18:34.381 2025-05-06 08:18:34.392390 :9360CD51:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12144 pso_num=25 pso_serial#=173 2025-05-06 08:18:34.392525 :9360CD52:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12144 osp_idx=23 osp_ver=76417 osp_pg=0 (spawn #153580) 2025-05-06 08:18:34.392526 :9360CD53:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:18:34.392527 :9360CD54:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=3790291546 2025-05-06 08:18:34.392527 :9360CD55:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:18:34.392528 :9360CD56:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:18:34.392528 :9360CD57:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:18:34.392528 :9360CD58:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:18:34.392529 :9360CD59:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:18:34.393066 :9360CD5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:19:04.465140 :9360D707:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:19:34.464946 :9360E0C0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:19:34.464 2025-05-06 08:19:34.464948 :9360E0C1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10888 pso_num=25 pso_serial#=174 2025-05-06 08:19:34.465081 :9360E0C2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10888 osp_idx=23 osp_ver=76418 osp_pg=0 (spawn #153582) 2025-05-06 08:19:34.465081 :9360E0C3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:19:34.465082 :9360E0C4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=3790351625 2025-05-06 08:19:34.465082 :9360E0C5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:19:34.465083 :9360E0C6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:19:34.465083 :9360E0C7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:19:34.465083 :9360E0C8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:19:34.465084 :9360E0C9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:19:34.465558 :9360E0CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:20:04.543121 :9360EA8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:20:29.568640 :9360F2F9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:20:29.557 2025-05-06 08:20:29.568642 :9360F2FA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11384 pso_num=25 pso_serial#=175 2025-05-06 08:20:29.568780 :9360F2FB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11384 osp_idx=23 osp_ver=76419 osp_pg=0 (spawn #153584) 2025-05-06 08:20:29.568781 :9360F2FC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:20:29.568782 :9360F2FD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=237 time=3790406734 2025-05-06 08:20:29.568782 :9360F2FE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:20:29.568782 :9360F2FF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:20:29.568783 :9360F300:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:20:29.568783 :9360F301:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:20:29.568783 :9360F302:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:20:29.569259 :9360F303:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-06 08:20:34.573598 :9360F432:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-06 08:21:04.676149 :9360FDD4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-06 08:21:34.671476 :9361073B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-06 08:21:34.660 2025-05-06 08:21:34.671479 :9361073C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4460 pso_num=25 pso_serial#=176 2025-05-06 08:21:34.671611 :9361073D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4460 osp_idx=23 osp_ver=76420 osp_pg=0 (spawn #153587) 2025-05-06 08:21:34.671612 :9361073E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-06 08:21:34.671612 :9361073F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=238 time=3790471828 2025-05-06 08:21:34.671613 :93610740:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-06 08:21:34.671613 :93610741:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-06 08:21:34.671613 :93610742:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-06 08:21:34.671614 :93610743:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-06 08:21:34.671614 :93610744:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-06 08:21:34.672100 :93610745:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 4460, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de