Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614060009\orcl_j001_15092_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614060009\orcl_j001_15092_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:36689M/63366M, Ph+PgF:43176M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15092, image: ORACLE.EXE (J001) *** 2025-06-14 06:00:09.993 *** SESSION ID:(193.6123) 2025-06-14 06:00:09.993 *** 2025-06-14 06:00:09.993 Process diagnostic dump for ORACLE.EXE (J001), OS id=15092, pid: 24, proc_ser: 81, sid: 193, sess_ser: 6123 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15092 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 snap_id=1 wait times: snap=0.345130 sec, exc=0.345130 sec, total=0.345130 sec wait times: max=0.500000 sec, heur=8.444105 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.000008 sec since current wait 0: 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000005 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.500194 sec, exc=0.500194 sec, total=0.500194 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.501553 sec, exc=0.501553 sec, total=0.501553 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 193 serial 6123 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 06:00:01 - 06:00:09] idle wait at each sample [session created at: 06:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 06:00:09.993 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15092, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 05:48:45.582073 :A47132F8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=81 time=2855868766 2025-06-14 05:48:45.582073 :A47132F9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:48:45.582074 :A47132FA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:48:45.582074 :A47132FB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:48:45.582074 :A47132FC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:48:45.582075 :A47132FD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:48:45.582546 :A47132FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:49:15.667778 :A4713CF3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:49:45.644655 :A47146CB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:49:45.633 2025-06-14 05:49:45.644657 :A47146CC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16304 pso_num=24 pso_serial#=71 2025-06-14 05:49:45.644777 :A47146CD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16304 osp_idx=22 osp_ver=403500 osp_pg=0 (spawn #283567) 2025-06-14 05:49:45.644778 :A47146CE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:49:45.644778 :A47146CF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=82 time=2855928829 2025-06-14 05:49:45.644779 :A47146D0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:49:45.644779 :A47146D1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:49:45.644779 :A47146D2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:49:45.644780 :A47146D3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:49:45.644780 :A47146D4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:49:45.645275 :A47146D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:50:15.697261 :A4715092:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:50:45.725189 :A4715B4F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:50:45.714 2025-06-14 05:50:45.725191 :A4715B50:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16896 pso_num=24 pso_serial#=72 2025-06-14 05:50:45.725324 :A4715B51:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16896 osp_idx=22 osp_ver=403501 osp_pg=0 (spawn #283569) 2025-06-14 05:50:45.725324 :A4715B52:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:50:45.725325 :A4715B53:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=83 time=2855988907 2025-06-14 05:50:45.725325 :A4715B54:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:50:45.725326 :A4715B55:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:50:45.725326 :A4715B56:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:50:45.725326 :A4715B57:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:50:45.725326 :A4715B58:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:50:45.725800 :A4715B59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:51:15.789602 :A4716604:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:51:45.809686 :A4716FDA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:51:45.798 2025-06-14 05:51:45.809689 :A4716FDB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16868 pso_num=24 pso_serial#=73 2025-06-14 05:51:45.809831 :A4716FDC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16868 osp_idx=22 osp_ver=403502 osp_pg=0 (spawn #283571) 2025-06-14 05:51:45.809832 :A4716FDD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:51:45.809832 :A4716FDE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=84 time=2856049000 2025-06-14 05:51:45.809832 :A4716FDF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:51:45.809833 :A4716FE0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:51:45.809833 :A4716FE1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:51:45.809833 :A4716FE2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:51:45.809834 :A4716FE3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:51:45.810339 :A4716FE4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:52:15.894625 :A47179C6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:52:45.921359 :A471838A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:52:45.910 2025-06-14 05:52:45.921362 :A471838B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12636 pso_num=24 pso_serial#=74 2025-06-14 05:52:45.921555 :A471838C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12636 osp_idx=22 osp_ver=403503 osp_pg=0 (spawn #283574) 2025-06-14 05:52:45.921557 :A471838D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:52:45.921558 :A471838E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=85 time=2856109110 2025-06-14 05:52:45.921558 :A471838F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:52:45.921558 :A4718390:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:52:45.921559 :A4718391:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:52:45.921559 :A4718392:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:52:45.921559 :A4718393:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:52:45.922050 :A4718394:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:53:15.958863 :A4718D54:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:53:46.024546 :A471970D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:53:46.013 2025-06-14 05:53:46.024548 :A471970E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17292 pso_num=24 pso_serial#=75 2025-06-14 05:53:46.024685 :A471970F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17292 osp_idx=22 osp_ver=403504 osp_pg=0 (spawn #283576) 2025-06-14 05:53:46.024685 :A4719710:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:53:46.024686 :A4719711:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=86 time=2856169204 2025-06-14 05:53:46.024686 :A4719712:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:53:46.024686 :A4719713:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:53:46.024687 :A4719714:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:53:46.024687 :A4719715:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:53:46.024687 :A4719716:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:53:46.025161 :A4719717:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:54:16.074042 :A471A0FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:54:46.122456 :A471AB28:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:54:46.111 2025-06-14 05:54:46.122458 :A471AB29:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12820 pso_num=24 pso_serial#=76 2025-06-14 05:54:46.122589 :A471AB2A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12820 osp_idx=22 osp_ver=403505 osp_pg=0 (spawn #283579) 2025-06-14 05:54:46.122590 :A471AB2B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:54:46.122591 :A471AB2C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=87 time=2856229313 2025-06-14 05:54:46.122591 :A471AB2D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:54:46.122592 :A471AB2E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:54:46.122592 :A471AB2F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:54:46.122593 :A471AB30:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:54:46.122594 :A471AB31:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:54:46.123110 :A471AB32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:55:16.207370 :A471B500:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:55:46.195624 :A471BF05:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:55:46.183 2025-06-14 05:55:46.195626 :A471BF06:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14340 pso_num=24 pso_serial#=77 2025-06-14 05:55:46.195766 :A471BF07:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14340 osp_idx=22 osp_ver=403506 osp_pg=0 (spawn #283581) 2025-06-14 05:55:46.195767 :A471BF08:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:55:46.195767 :A471BF09:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=88 time=2856289375 2025-06-14 05:55:46.195768 :A471BF0A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:55:46.195768 :A471BF0B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:55:46.195768 :A471BF0C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:55:46.195769 :A471BF0D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:55:46.195769 :A471BF0E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:55:46.196268 :A471BF0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:56:16.296001 :A471C96C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:56:46.284583 :A471D2E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:56:46.274 2025-06-14 05:56:46.284585 :A471D2E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16256 pso_num=24 pso_serial#=78 2025-06-14 05:56:46.284720 :A471D2E3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16256 osp_idx=22 osp_ver=403507 osp_pg=0 (spawn #283583) 2025-06-14 05:56:46.284721 :A471D2E4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:56:46.284722 :A471D2E5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=89 time=2856349469 2025-06-14 05:56:46.284722 :A471D2E6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:56:46.284722 :A471D2E7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:56:46.284722 :A471D2E8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:56:46.284722 :A471D2E9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:56:46.284723 :A471D2EA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:56:46.285196 :A471D2EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:57:11.321177 :A471DB0A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 05:57:11.321210*:A471DB0B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1749851831 eid=16908542 ctx=0x000000077BD98A08 cwh=0x00000000232801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-14 05:57:11.321210*:A471DB0C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F708 pubTabIdxCnt=0 2025-06-14 05:57:11.321210*:A471DB0D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-06-14 05:57:11.321210*:A471DB0E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-06-14 05:57:11.321210*:A471DB0F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F708 xsc->flg4=65792 flags=2 2025-06-14 05:57:11.321210*:A471DB10:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-06-14 05:57:11.321777 :A471DB11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 05:57:11.327181 :A471DB19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 05:57:11.328175 :A471DB21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 05:57:11.328315 :A471DB2A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 05:57:41.402293 :A471E4A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:57:46.361268 :A471E682:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:57:46.350 2025-06-14 05:57:46.361270 :A471E683:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15628 pso_num=24 pso_serial#=79 2025-06-14 05:57:46.361410 :A471E684:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15628 osp_idx=22 osp_ver=403508 osp_pg=0 (spawn #283585) 2025-06-14 05:57:46.361411 :A471E685:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:57:46.361411 :A471E686:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=90 time=2856409547 2025-06-14 05:57:46.361412 :A471E687:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:57:46.361412 :A471E688:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:57:46.361412 :A471E689:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:57:46.361413 :A471E68A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:57:46.361413 :A471E68B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:57:46.361885 :A471E68C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:58:16.419032 :A471F039:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:58:46.420738 :A471FA00:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:58:46.410 2025-06-14 05:58:46.420740 :A471FA01:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12736 pso_num=24 pso_serial#=80 2025-06-14 05:58:46.420874 :A471FA02:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12736 osp_idx=22 osp_ver=403509 osp_pg=0 (spawn #283587) 2025-06-14 05:58:46.420875 :A471FA03:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:58:46.420875 :A471FA04:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=91 time=2856469610 2025-06-14 05:58:46.420876 :A471FA05:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:58:46.420876 :A471FA06:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:58:46.420876 :A471FA07:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:58:46.420877 :A471FA08:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:58:46.420877 :A471FA09:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:58:46.421368 :A471FA0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 05:59:16.485295 :A472041A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-14 05:59:46.505368 :A4720E2A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-14 05:59:46.493 2025-06-14 05:59:46.505370 :A4720E2B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15092 pso_num=24 pso_serial#=81 2025-06-14 05:59:46.505490 :A4720E2C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15092 osp_idx=22 osp_ver=403510 osp_pg=0 (spawn #283590) 2025-06-14 05:59:46.505490 :A4720E2D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 05:59:46.505491 :A4720E2E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=92 time=2856529688 2025-06-14 05:59:46.505491 :A4720E2F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 05:59:46.505492 :A4720E30:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 05:59:46.505492 :A4720E31:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 05:59:46.505492 :A4720E32:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 05:59:46.505493 :A4720E33:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 05:59:46.506023 :A4720E34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.523338 :A47214D2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.523338*:A47214E1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5B898 estart=1749852000 eid=16782292 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-14 06:00:01.523338*:A47214E2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 pubTabIdxCnt=0 2025-06-14 06:00:01.523338*:A47214E3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5B898 2025-06-14 06:00:01.523338*:A47214E4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5B898 2025-06-14 06:00:01.523338*:A47214E5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 xsc->flg4=65792 flags=2 2025-06-14 06:00:01.523338*:A47214E6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5B898 2025-06-14 06:00:01.529122 :A47214F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.529169 :A47214F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-06-14 06:00:01.534157 :A472151E:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-14 06:00:01.534747 :A4721532:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 7b06120000000000 2025-06-14 06:00:01.536417 :A4721548:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.536481 :A4721555:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.536489 :A472155C:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-06-14 06:00:01.536494 :A472155D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.536519 :A472156A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.537329 :A4721575:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 228326609) 2025-06-14 06:00:01.537333 :A4721576:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.537362 :A472157F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.537370 :A472158C:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1) 2025-06-14 06:00:01.537373 :A472158F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.537402 :A472159B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.537406 :A472159D:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560) 2025-06-14 06:00:01.537409 :A47215A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.537459 :A47215D2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.538183 :A4721664:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.538210 :A472166D:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-06-14 06:00:01.539083 :A4721678:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 7c06120000000000 2025-06-14 06:00:01.539485 :A4721679:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 228326609) 2025-06-14 06:00:01.539494 :A472167A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.539523 :A4721685:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-14 06:00:01.547920 :A47216E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-06-14 06:00:01.549582 :A47216F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.549622 :A47216FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-14 06:00:01.549691 :A4721708:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-14 06:00:01.648084 :A4721842:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 15092, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de