Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250425220009\orcl_j001_688_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250425220009\orcl_j001_688_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:37583M/63366M, Ph+PgF:45263M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 688, image: ORACLE.EXE (J001) *** 2025-04-25 22:00:09.331 *** SESSION ID:(201.14197) 2025-04-25 22:00:09.331 *** 2025-04-25 22:00:09.331 Process diagnostic dump for ORACLE.EXE (J001), OS id=688, pid: 25, proc_ser: 235, sid: 201, sess_ser: 14197 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 688 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.285976 sec, exc=0.285976 sec, total=0.285976 sec wait times: max=0.500000 sec, heur=5.305962 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510110 sec, exc=0.510110 sec, total=0.510110 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499952 sec, exc=0.499952 sec, total=0.499952 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500036 sec, exc=0.500036 sec, total=0.500036 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510087 sec, exc=0.510087 sec, total=0.510087 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.499710 sec, exc=0.499710 sec, total=0.499710 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 201 serial 14197 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:00:04 - 22:00:09] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-25 22:00:09.331 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 688, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-25 21:48:16.789507 :8EBAF64E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:48:46.768270 :8EBB0046:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:48:46.757 2025-04-25 21:48:46.768272 :8EBB0047:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6092 pso_num=25 pso_serial#=224 2025-04-25 21:48:46.768423 :8EBB0048:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6092 osp_idx=23 osp_ver=64738 osp_pg=0 (spawn #118166) 2025-04-25 21:48:46.768423 :8EBB0049:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:48:46.768424 :8EBB004A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=196 time=2888528562 2025-04-25 21:48:46.768424 :8EBB004B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:48:46.768425 :8EBB004C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:48:46.768425 :8EBB004D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:48:46.768426 :8EBB004E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:48:46.768426 :8EBB004F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:48:46.768956 :8EBB0050: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-04-25 21:49:16.836301 :8EBB0AE0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:49:46.851701 :8EBB146D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:49:46.840 2025-04-25 21:49:46.851703 :8EBB146E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10664 pso_num=25 pso_serial#=225 2025-04-25 21:49:46.851829 :8EBB146F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10664 osp_idx=23 osp_ver=64739 osp_pg=0 (spawn #118169) 2025-04-25 21:49:46.851829 :8EBB1470:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:49:46.851830 :8EBB1471:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=197 time=2888588640 2025-04-25 21:49:46.851830 :8EBB1472:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:49:46.851831 :8EBB1473:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:49:46.851831 :8EBB1474:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:49:46.851831 :8EBB1475:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:49:46.851832 :8EBB1476:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:49:46.852334 :8EBB1477: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-04-25 21:50:16.950015 :8EBB1E5E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:50:46.924941 :8EBB2873:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:50:46.913 2025-04-25 21:50:46.924944 :8EBB2874:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9956 pso_num=25 pso_serial#=226 2025-04-25 21:50:46.925080 :8EBB2875:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9956 osp_idx=23 osp_ver=64740 osp_pg=0 (spawn #118171) 2025-04-25 21:50:46.925081 :8EBB2876:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:50:46.925082 :8EBB2877:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=198 time=2888648718 2025-04-25 21:50:46.925082 :8EBB2878:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:50:46.925083 :8EBB2879:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:50:46.925083 :8EBB287A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:50:46.925083 :8EBB287B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:50:46.925083 :8EBB287C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:50:46.925566 :8EBB287D: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-04-25 21:51:17.001812 :8EBB334D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:51:47.004246 :8EBB3D18:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:51:46.993 2025-04-25 21:51:47.004248 :8EBB3D19:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10832 pso_num=25 pso_serial#=227 2025-04-25 21:51:47.004384 :8EBB3D1A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10832 osp_idx=23 osp_ver=64741 osp_pg=0 (spawn #118173) 2025-04-25 21:51:47.004384 :8EBB3D1B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:51:47.004385 :8EBB3D1C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=199 time=2888708796 2025-04-25 21:51:47.004385 :8EBB3D1D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:51:47.004385 :8EBB3D1E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:51:47.004386 :8EBB3D1F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:51:47.004386 :8EBB3D20:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:51:47.004386 :8EBB3D21:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:51:47.004868 :8EBB3D22: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-04-25 21:52:17.110395 :8EBB471D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:52:47.081684 :8EBB50C8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:52:47.070 2025-04-25 21:52:47.081686 :8EBB50C9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9688 pso_num=25 pso_serial#=228 2025-04-25 21:52:47.081817 :8EBB50CA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9688 osp_idx=23 osp_ver=64742 osp_pg=0 (spawn #118175) 2025-04-25 21:52:47.081818 :8EBB50CB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:52:47.081818 :8EBB50CC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=200 time=2888768875 2025-04-25 21:52:47.081818 :8EBB50CD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:52:47.081819 :8EBB50CE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:52:47.081819 :8EBB50CF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:52:47.081820 :8EBB50D0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:52:47.081820 :8EBB50D1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:52:47.082311 :8EBB50D2: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-04-25 21:53:17.153817 :8EBB5A90:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:53:47.165448 :8EBB6472:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:53:47.154 2025-04-25 21:53:47.165450 :8EBB6473:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6232 pso_num=25 pso_serial#=229 2025-04-25 21:53:47.165581 :8EBB6474:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6232 osp_idx=23 osp_ver=64743 osp_pg=0 (spawn #118177) 2025-04-25 21:53:47.165582 :8EBB6475:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:53:47.165582 :8EBB6476:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=201 time=2888828953 2025-04-25 21:53:47.165582 :8EBB6477:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:53:47.165583 :8EBB6478:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:53:47.165583 :8EBB6479:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:53:47.165584 :8EBB647A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:53:47.165584 :8EBB647B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:53:47.166060 :8EBB647C: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-04-25 21:54:17.239013 :8EBB6F21:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:54:47.262889 :8EBB791B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:54:47.252 2025-04-25 21:54:47.262890 :8EBB791C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9788 pso_num=25 pso_serial#=230 2025-04-25 21:54:47.263022 :8EBB791D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9788 osp_idx=23 osp_ver=64744 osp_pg=0 (spawn #118180) 2025-04-25 21:54:47.263023 :8EBB791E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:54:47.263023 :8EBB791F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=202 time=2888889046 2025-04-25 21:54:47.263024 :8EBB7920:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:54:47.263024 :8EBB7921:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:54:47.263025 :8EBB7922:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:54:47.263025 :8EBB7923:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:54:47.263025 :8EBB7924:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:54:47.263510 :8EBB7925: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-04-25 21:55:17.349143 :8EBB82D5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:55:47.330883 :8EBB8D0C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:55:47.320 2025-04-25 21:55:47.330885 :8EBB8D0D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8776 pso_num=25 pso_serial#=231 2025-04-25 21:55:47.331023 :8EBB8D0E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8776 osp_idx=23 osp_ver=64745 osp_pg=0 (spawn #118182) 2025-04-25 21:55:47.331023 :8EBB8D0F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:55:47.331024 :8EBB8D10:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=203 time=2888949125 2025-04-25 21:55:47.331024 :8EBB8D11:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:55:47.331025 :8EBB8D12:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:55:47.331025 :8EBB8D13:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:55:47.331026 :8EBB8D14:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:55:47.331026 :8EBB8D15:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:55:47.331562 :8EBB8D16: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-04-25 21:56:17.405653 :8EBB9741:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:56:47.397976 :8EBBA10F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:56:47.387 2025-04-25 21:56:47.397978 :8EBBA110:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9560 pso_num=25 pso_serial#=232 2025-04-25 21:56:47.398113 :8EBBA111:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9560 osp_idx=23 osp_ver=64746 osp_pg=0 (spawn #118184) 2025-04-25 21:56:47.398114 :8EBBA112:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:56:47.398114 :8EBBA113:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=204 time=2889009187 2025-04-25 21:56:47.398115 :8EBBA114:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:56:47.398115 :8EBBA115:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:56:47.398116 :8EBBA116:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:56:47.398116 :8EBBA117:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:56:47.398117 :8EBBA118:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:56:47.398600 :8EBBA119: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-04-25 21:57:17.463186 :8EBBAB01:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:57:42.479496 :8EBBB339:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:57:42.468 2025-04-25 21:57:42.479498 :8EBBB33A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8072 pso_num=25 pso_serial#=233 2025-04-25 21:57:42.479629 :8EBBB33B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8072 osp_idx=23 osp_ver=64747 osp_pg=0 (spawn #118186) 2025-04-25 21:57:42.479630 :8EBBB33C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:57:42.479630 :8EBBB33D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=205 time=2889064265 2025-04-25 21:57:42.479631 :8EBBB33E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:57:42.479631 :8EBBB33F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:57:42.479632 :8EBBB340:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:57:42.479632 :8EBBB341:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:57:42.479632 :8EBBB342:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:57:42.480121 :8EBBB343: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-04-25 21:57:47.470710 :8EBBB51E: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-04-25 21:58:17.576785 :8EBBBF0B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:58:47.566941 :8EBBC91A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:58:47.556 2025-04-25 21:58:47.566943 :8EBBC91B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11228 pso_num=25 pso_serial#=234 2025-04-25 21:58:47.567084 :8EBBC91C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11228 osp_idx=23 osp_ver=64748 osp_pg=0 (spawn #118189) 2025-04-25 21:58:47.567085 :8EBBC91D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:58:47.567086 :8EBBC91E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=206 time=2889129359 2025-04-25 21:58:47.567087 :8EBBC91F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:58:47.567087 :8EBBC920:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:58:47.567088 :8EBBC921:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:58:47.567088 :8EBBC922:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:58:47.567089 :8EBBC923:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:58:47.567657 :8EBBC924: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-04-25 21:59:17.661729 :8EBBD357:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-04-25 21:59:47.643912 :8EBBDD2A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 04-25 21:59:47.633 2025-04-25 21:59:47.643914 :8EBBDD2B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=688 pso_num=25 pso_serial#=235 2025-04-25 21:59:47.644111 :8EBBDD2C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=688 osp_idx=23 osp_ver=64749 osp_pg=0 (spawn #118191) 2025-04-25 21:59:47.644112 :8EBBDD2D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-25 21:59:47.644112 :8EBBDD2E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=207 time=2889189437 2025-04-25 21:59:47.644113 :8EBBDD2F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-04-25 21:59:47.644113 :8EBBDD30:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-04-25 21:59:47.644113 :8EBBDD31:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-04-25 21:59:47.644114 :8EBBDD32:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-25 21:59:47.644114 :8EBBDD33:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-25 21:59:47.644617 :8EBBDD34: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-04-25 22:00:02.035462 :8EBBE353: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-04-25 22:00:02.035462*:8EBBE37B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDC718 estart=1745589601 eid=16779256 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-25 22:00:02.035462*:8EBBE37C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 pubTabIdxCnt=0 2025-04-25 22:00:02.035462*:8EBBE37D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDC718 2025-04-25 22:00:02.035462*:8EBBE37E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDC718 2025-04-25 22:00:02.035462*:8EBBE38D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 xsc->flg4=65792 flags=2 2025-04-25 22:00:02.035462*:8EBBE38E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDC718 2025-04-25 22:00:02.042343 :8EBBE396:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.042410 :8EBBE399:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-04-25 22:00:02.054175 :8EBBE426:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.059418 :8EBBE552:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.060292 :8EBBE55A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.066071 :8EBBE577:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.066223 :8EBBE586:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.066824 :8EBBE598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.067065 :8EBBE5AC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-04-25 22:00:02.068571 :8EBBE5AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.068636 :8EBBE5B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.068725 :8EBBE5BD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-25 22:00:02.160194 :8EBBE698: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-04-25 22:00:02.162286 :8EBBE69F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.162331 :8EBBE6A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-04-25 22:00:02.164787 :8EBBE6A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-04-25 22:00:02.168192 :8EBBE6A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.168272 :8EBBE6AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-25 22:00:02.168347 :8EBBE6B9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-04-25 22:00:04.014352 :8EBBEACD: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-04-25 22:00:04.014352*:8EBBEACF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DDC718 estart=1745589603 eid=16779261 ctx=0x000000077FD6A000 cwh=0x0000000024DD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-25 22:00:04.014352*:8EBBEAD0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 pubTabIdxCnt=0 2025-04-25 22:00:04.014352*:8EBBEAD1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DDC718 2025-04-25 22:00:04.014352*:8EBBEAD2:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DDC718 2025-04-25 22:00:04.014352*:8EBBEAD3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DDC718 curCtx=0x0000000024DD8938 xsc->flg4=65792 flags=2 2025-04-25 22:00:04.014352*:8EBBEAD4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DDC718 2025-04-25 22:00:04.016446 :8EBBEAD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:04.016484 :8EBBEAD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-04-25 22:00:04.024289 :8EBBEADE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:04.024944 :8EBBEAE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:04.025707 :8EBBEAEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:04.025891 :8EBBEAF6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-04-25 22:00:04.028553 :8EBBEAF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-25 22:00:04.028616 :8EBBEAFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-25 22:00:04.028699 :8EBBEB07:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 688, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de