Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512080006\orcl_j001_13052_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512080006\orcl_j001_13052_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:37421M/63366M, Ph+PgF:44736M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 13052, image: ORACLE.EXE (J001) *** 2025-05-12 08:00:06.580 *** SESSION ID:(201.9695) 2025-05-12 08:00:06.580 *** 2025-05-12 08:00:06.580 Process diagnostic dump for ORACLE.EXE (J001), OS id=13052, pid: 25, proc_ser: 209, sid: 201, sess_ser: 9695 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13052 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.222341 sec, exc=0.222341 sec, total=0.222341 sec wait times: max=0.500000 sec, heur=11.755545 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 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.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 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=20 seq_num=21 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.000010 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=0.504585 sec, exc=0.504585 sec, total=0.504585 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 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.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 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=15 seq_num=16 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 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=14 seq_num=15 snap_id=1 wait times: snap=0.510113 sec, exc=0.510113 sec, total=0.510113 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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 Sampled Session History of session 201 serial 9695 --------------------------------------------------- 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 --------------------------------------------------- [12 samples, 07:59:54 - 08:00:06] idle wait at each sample [session created at: 07:59:54] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-12 08:00:06.580 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 13052, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-12 07:45:53.626926 :960063B8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11788 pso_num=25 pso_serial#=195 2025-05-12 07:45:53.627045 :960063B9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11788 osp_idx=23 osp_ver=92504 osp_pg=0 (spawn #173846) 2025-05-12 07:45:53.627045 :960063BA: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-12 07:45:53.627046 :960063BB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=113 time=11763485 2025-05-12 07:45:53.627046 :960063BC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:45:53.627047 :960063BD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:45:53.627047 :960063BE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:45:53.627047 :960063BF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:45:53.627047 :960063C0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:45:53.627557 :960063C1: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-12 07:46:23.687984 :96006DB7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:46:53.687868 :9600774A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:46:53.676 2025-05-12 07:46:53.687870 :9600774B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12112 pso_num=25 pso_serial#=196 2025-05-12 07:46:53.688005 :9600774C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12112 osp_idx=23 osp_ver=92505 osp_pg=0 (spawn #173848) 2025-05-12 07:46:53.688006 :9600774D: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-12 07:46:53.688006 :9600774E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=114 time=11823547 2025-05-12 07:46:53.688007 :9600774F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:46:53.688007 :96007750:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:46:53.688008 :96007751:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:46:53.688008 :96007752:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:46:53.688008 :96007753:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:46:53.688485 :96007754: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-12 07:47:23.766159 :960080B9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:47:53.759345 :96008A2B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:47:53.748 2025-05-12 07:47:53.759347 :96008A2C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7192 pso_num=25 pso_serial#=197 2025-05-12 07:47:53.759485 :96008A2D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7192 osp_idx=23 osp_ver=92506 osp_pg=0 (spawn #173850) 2025-05-12 07:47:53.759485 :96008A2E: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-12 07:47:53.759486 :96008A2F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=115 time=11883625 2025-05-12 07:47:53.759486 :96008A30:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:47:53.759487 :96008A31:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:47:53.759487 :96008A32:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:47:53.759487 :96008A33:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:47:53.759488 :96008A34:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:47:53.759979 :96008A35: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-12 07:48:23.842628 :96009474:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:48:53.846428 :96009DD4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:48:53.835 2025-05-12 07:48:53.846431 :96009DD5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9904 pso_num=25 pso_serial#=198 2025-05-12 07:48:53.846562 :96009DD6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9904 osp_idx=23 osp_ver=92507 osp_pg=0 (spawn #173852) 2025-05-12 07:48:53.846563 :96009DD7: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-12 07:48:53.846563 :96009DD8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=116 time=11943704 2025-05-12 07:48:53.846563 :96009DD9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:48:53.846564 :96009DDA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:48:53.846564 :96009DDB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:48:53.846564 :96009DDC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:48:53.846564 :96009DDD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:48:53.847072 :96009DDE: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-12 07:49:23.944891 :9600A779:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:49:53.939158 :9600B10D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:49:53.928 2025-05-12 07:49:53.939161 :9600B10E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10684 pso_num=25 pso_serial#=199 2025-05-12 07:49:53.939320 :9600B10F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10684 osp_idx=23 osp_ver=92508 osp_pg=0 (spawn #173855) 2025-05-12 07:49:53.939321 :9600B110: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-12 07:49:53.939321 :9600B111:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=117 time=12003797 2025-05-12 07:49:53.939321 :9600B112:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:49:53.939322 :9600B113:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:49:53.939322 :9600B114:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:49:53.939323 :9600B115:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:49:53.939323 :9600B116:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:49:53.939803 :9600B117: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-12 07:50:24.003396 :9600BAB8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:50:53.980190 :9600C47C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:50:53.969 2025-05-12 07:50:53.980192 :9600C47D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6028 pso_num=25 pso_serial#=200 2025-05-12 07:50:53.980324 :9600C47E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6028 osp_idx=23 osp_ver=92509 osp_pg=0 (spawn #173858) 2025-05-12 07:50:53.980324 :9600C47F: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-12 07:50:53.980325 :9600C480:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=118 time=12063844 2025-05-12 07:50:53.980327 :9600C481:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:50:53.980327 :9600C482:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:50:53.980328 :9600C483:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:50:53.980328 :9600C484:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:50:53.980328 :9600C485:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:50:53.980804 :9600C486: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-12 07:51:24.080518 :9600CE42:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:51:54.071639 :9600D7BD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:51:54.060 2025-05-12 07:51:54.071641 :9600D7BE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12152 pso_num=25 pso_serial#=201 2025-05-12 07:51:54.071784 :9600D7BF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12152 osp_idx=23 osp_ver=92510 osp_pg=0 (spawn #173860) 2025-05-12 07:51:54.071785 :9600D7C0: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-12 07:51:54.071786 :9600D7C1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=119 time=12123938 2025-05-12 07:51:54.071786 :9600D7C2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:51:54.071787 :9600D7C3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:51:54.071787 :9600D7C4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:51:54.071787 :9600D7C5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:51:54.071787 :9600D7C6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:51:54.072288 :9600D7C7: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-12 07:52:24.182606 :9600E14B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:52:54.166923 :9600EAA2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:52:54.155 2025-05-12 07:52:54.166925 :9600EAA3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10236 pso_num=25 pso_serial#=202 2025-05-12 07:52:54.167059 :9600EAA4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10236 osp_idx=23 osp_ver=92511 osp_pg=0 (spawn #173862) 2025-05-12 07:52:54.167060 :9600EAA5: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-12 07:52:54.167060 :9600EAA6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=120 time=12184032 2025-05-12 07:52:54.167061 :9600EAA7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:52:54.167061 :9600EAA8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:52:54.167062 :9600EAA9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:52:54.167062 :9600EAAA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:52:54.167062 :9600EAAB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:52:54.167581 :9600EAAC: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-12 07:53:24.249879 :9600F50E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:53:49.252294 :9600FCF5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:53:49.241 2025-05-12 07:53:49.252296 :9600FCF6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12976 pso_num=25 pso_serial#=203 2025-05-12 07:53:49.252424 :9600FCF7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12976 osp_idx=23 osp_ver=92512 osp_pg=0 (spawn #173864) 2025-05-12 07:53:49.252424 :9600FCF8: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-12 07:53:49.252425 :9600FCF9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=121 time=12239110 2025-05-12 07:53:49.252425 :9600FCFA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:53:49.252425 :9600FCFB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:53:49.252426 :9600FCFC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:53:49.252426 :9600FCFD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:53:49.252426 :9600FCFE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:53:49.252895 :9600FCFF: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-12 07:53:54.247965 :9600FEC7: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-12 07:53:54.247997*:9600FEC8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024DCBB58 estart=1747007633 eid=16856315 ctx=0x000000077BD98A08 cwh=0x0000000024D801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-12 07:53:54.247997*:9600FEC9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 pubTabIdxCnt=0 2025-05-12 07:53:54.247997*:9600FECA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024DCBB58 2025-05-12 07:53:54.247997*:9600FECB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024DCBB58 2025-05-12 07:53:54.247997*:9600FECC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024DCBB58 curCtx=0x0000000024D8F708 xsc->flg4=65792 flags=2 2025-05-12 07:53:54.247997*:9600FECD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024DCBB58 2025-05-12 07:53:54.248528 :9600FECE: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-05-12 07:53:54.256092 :9600FED6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-05-12 07:53:54.256472 :9600FED7: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-05-12 07:53:54.256509 :9600FEDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-05-12 07:53:54.257422 :9600FEE0: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-05-12 07:53:54.257547 :9600FEE9: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-05-12 07:54:24.334069 :9601087B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:54:54.342539 :9601121B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:54:54.331 2025-05-12 07:54:54.342541 :9601121C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12500 pso_num=25 pso_serial#=204 2025-05-12 07:54:54.342684 :9601121D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12500 osp_idx=23 osp_ver=92513 osp_pg=0 (spawn #173866) 2025-05-12 07:54:54.342685 :9601121E: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-12 07:54:54.342686 :9601121F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=12304204 2025-05-12 07:54:54.342686 :96011220:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:54:54.342687 :96011221:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:54:54.342687 :96011222:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:54:54.342688 :96011223:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:54:54.342689 :96011224:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:54:54.343178 :96011225: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-12 07:55:24.429750 :96011BEA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:55:54.447207 :96012551:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:55:54.436 2025-05-12 07:55:54.447209 :96012552:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10372 pso_num=25 pso_serial#=205 2025-05-12 07:55:54.447341 :96012553:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10372 osp_idx=23 osp_ver=92514 osp_pg=0 (spawn #173868) 2025-05-12 07:55:54.447342 :96012554: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-12 07:55:54.447342 :96012555:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=12364313 2025-05-12 07:55:54.447342 :96012556:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:55:54.447343 :96012557:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:55:54.447343 :96012558:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:55:54.447344 :96012559:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:55:54.447344 :9601255A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:55:54.447815 :9601255B: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-12 07:56:24.565666 :96012F2E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:56:54.573556 :960138A7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:56:54.562 2025-05-12 07:56:54.573558 :960138A8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10624 pso_num=25 pso_serial#=206 2025-05-12 07:56:54.573691 :960138A9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10624 osp_idx=23 osp_ver=92515 osp_pg=0 (spawn #173870) 2025-05-12 07:56:54.573692 :960138AA: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-12 07:56:54.573692 :960138AB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=12424438 2025-05-12 07:56:54.573693 :960138AC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:56:54.573693 :960138AD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:56:54.573693 :960138AE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:56:54.573694 :960138AF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:56:54.573694 :960138B0:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:56:54.574161 :960138B1: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-12 07:57:24.654371 :96014219:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:57:54.641792 :96014BA5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:57:54.630 2025-05-12 07:57:54.641795 :96014BA6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12832 pso_num=25 pso_serial#=207 2025-05-12 07:57:54.641983 :96014BA7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12832 osp_idx=23 osp_ver=92516 osp_pg=0 (spawn #173872) 2025-05-12 07:57:54.641983 :96014BA8: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-12 07:57:54.641983 :96014BA9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=12484500 2025-05-12 07:57:54.641984 :96014BAA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:57:54.641984 :96014BAB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:57:54.641984 :96014BAC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:57:54.641985 :96014BAD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:57:54.641986 :96014BAE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:57:54.642464 :96014BAF: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-12 07:58:24.733930 :9601559F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:58:54.725584 :96015EFC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:58:54.714 2025-05-12 07:58:54.725586 :96015EFD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12720 pso_num=25 pso_serial#=208 2025-05-12 07:58:54.725717 :96015EFE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12720 osp_idx=23 osp_ver=92517 osp_pg=0 (spawn #173874) 2025-05-12 07:58:54.725718 :96015EFF: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-12 07:58:54.725718 :96015F00:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=12544594 2025-05-12 07:58:54.725719 :96015F01:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:58:54.725719 :96015F02:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:58:54.725719 :96015F03:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:58:54.725720 :96015F04:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:58:54.725720 :96015F05:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:58:54.726201 :96015F06: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-12 07:59:24.820405 :96016894:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-12 07:59:54.827647 :96017261:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-12 07:59:54.816 2025-05-12 07:59:54.827649 :96017262:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13052 pso_num=25 pso_serial#=209 2025-05-12 07:59:54.827781 :96017263:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13052 osp_idx=23 osp_ver=92518 osp_pg=0 (spawn #173877) 2025-05-12 07:59:54.827782 :96017264: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-12 07:59:54.827782 :96017265:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=12604688 2025-05-12 07:59:54.827783 :96017266:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 07:59:54.827783 :96017267:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 07:59:54.827783 :96017268:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 07:59:54.827784 :96017269:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 07:59:54.827784 :9601726A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 07:59:54.828268 :9601726B: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: 13052, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de