Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607180539\orcl_j001_15936_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:36800M/63366M, Ph+PgF:43412M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15936, image: ORACLE.EXE (J001) *** 2025-06-07 18:05:39.631 *** SESSION ID:(209.28935) 2025-06-07 18:05:39.631 *** 2025-06-07 18:05:39.631 Process diagnostic dump for ORACLE.EXE (J001), OS id=15936, pid: 26, proc_ser: 57, sid: 209, sess_ser: 28935 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15936 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.400477 sec, exc=0.400477 sec, total=0.400477 sec wait times: max=0.500000 sec, heur=4.920263 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.000009 sec since current wait 0: 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510092 sec, exc=0.510092 sec, total=0.510092 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.509619 sec, exc=0.509619 sec, total=0.509619 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 209 serial 28935 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 18:05:34 - 18:05:39] idle wait at each sample [session created at: 18:05:34] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 18:05:39.631 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15936, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 17:58:19.723828 :A19C65D5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12500 osp_idx=24 osp_ver=406265 osp_pg=0 (spawn #261910) 2025-06-07 17:58:19.723829 :A19C65D6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 17:58:19.723829 :A19C65D7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=177 time=2294858360 2025-06-07 17:58:19.723829 :A19C65D8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 17:58:19.723830 :A19C65D9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 17:58:19.723830 :A19C65DA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 17:58:19.723830 :A19C65DB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 17:58:19.723831 :A19C65DC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 17:58:19.724312 :A19C65DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 17:58:49.834542 :A19C6F62:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 17:59:19.829399 :A19C78FC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 17:59:19.818 2025-06-07 17:59:19.829401 :A19C78FD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16136 pso_num=26 pso_serial#=51 2025-06-07 17:59:19.829534 :A19C78FE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16136 osp_idx=24 osp_ver=406266 osp_pg=0 (spawn #261912) 2025-06-07 17:59:19.829535 :A19C78FF:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 17:59:19.829536 :A19C7900:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=178 time=2294918469 2025-06-07 17:59:19.829536 :A19C7901:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 17:59:19.829537 :A19C7902:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 17:59:19.829537 :A19C7903:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 17:59:19.829537 :A19C7904:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 17:59:19.829538 :A19C7905:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 17:59:19.830038 :A19C7906:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 17:59:49.903403 :A19C82E7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 18:00:19.907544 :A19C8CB3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 18:00:19.896 2025-06-07 18:00:19.907546 :A19C8CB4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12232 pso_num=26 pso_serial#=52 2025-06-07 18:00:19.907680 :A19C8CB5:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12232 osp_idx=24 osp_ver=406267 osp_pg=0 (spawn #261914) 2025-06-07 18:00:19.907681 :A19C8CB6:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 18:00:19.907681 :A19C8CB7:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=179 time=2294978547 2025-06-07 18:00:19.907681 :A19C8CB8:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 18:00:19.907682 :A19C8CB9:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 18:00:19.907682 :A19C8CBA:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 18:00:19.907683 :A19C8CBB:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 18:00:19.907683 :A19C8CBC:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 18:00:19.908160 :A19C8CBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 18:00:49.987013 :A19C9878:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 18:01:19.993316 :A19CA208:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 18:01:19.982 2025-06-07 18:01:19.993318 :A19CA209:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9904 pso_num=26 pso_serial#=53 2025-06-07 18:01:19.993456 :A19CA20A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9904 osp_idx=24 osp_ver=406268 osp_pg=0 (spawn #261919) 2025-06-07 18:01:19.993457 :A19CA20B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 18:01:19.993458 :A19CA20C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=180 time=2295038641 2025-06-07 18:01:19.993458 :A19CA20D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 18:01:19.993459 :A19CA20E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 18:01:19.993459 :A19CA20F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 18:01:19.993459 :A19CA210:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 18:01:19.993460 :A19CA211:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 18:01:19.993947 :A19CA212:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 18:01:50.066827 :A19CAC55:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 18:02:20.087484 :A19CB5F2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 18:02:20.076 2025-06-07 18:02:20.087486 :A19CB5F3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14972 pso_num=26 pso_serial#=54 2025-06-07 18:02:20.087625 :A19CB5F4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14972 osp_idx=24 osp_ver=406269 osp_pg=0 (spawn #261921) 2025-06-07 18:02:20.087625 :A19CB5F5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 18:02:20.087626 :A19CB5F6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=181 time=2295098735 2025-06-07 18:02:20.087626 :A19CB5F7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 18:02:20.087627 :A19CB5F8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 18:02:20.087627 :A19CB5F9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 18:02:20.087628 :A19CB5FA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 18:02:20.087628 :A19CB5FB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 18:02:20.088155 :A19CB5FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 18:02:50.196536 :A19CBFA6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 18:03:20.173629 :A19CC98A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 18:03:20.163 2025-06-07 18:03:20.173631 :A19CC98B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12944 pso_num=26 pso_serial#=55 2025-06-07 18:03:20.173752 :A19CC98C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12944 osp_idx=24 osp_ver=406270 osp_pg=0 (spawn #261924) 2025-06-07 18:03:20.173754 :A19CC98D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 18:03:20.173755 :A19CC98E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=182 time=2295158813 2025-06-07 18:03:20.173755 :A19CC98F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 18:03:20.173756 :A19CC990:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 18:03:20.173756 :A19CC991:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 18:03:20.173756 :A19CC992:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 18:03:20.173757 :A19CC993:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 18:03:20.174241 :A19CC994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 18:03:50.227047 :A19CD32D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 18:03:55.206377 :A19CD4CE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 18:03:55.195 2025-06-07 18:03:55.206379 :A19CD4CF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13060 pso_num=26 pso_serial#=56 2025-06-07 18:03:55.206518 :A19CD4D0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13060 osp_idx=24 osp_ver=406271 osp_pg=0 (spawn #261926) 2025-06-07 18:03:55.206519 :A19CD4D1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 18:03:55.206519 :A19CD4D2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=183 time=2295193844 2025-06-07 18:03:55.206519 :A19CD4D3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 18:03:55.206520 :A19CD4D4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 18:03:55.206520 :A19CD4D5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 18:03:55.206520 :A19CD4D6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 18:03:55.206521 :A19CD4D7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 18:03:55.206993 :A19CD4D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 18:04:20.227821 :A19CDD10:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07 18:04:50.313796 :A19CE7A8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-07 18:05:20.295743 :A19CF111:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-07 18:05:20.285 2025-06-07 18:05:20.295745 :A19CF112:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15936 pso_num=26 pso_serial#=57 2025-06-07 18:05:20.295880 :A19CF113:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15936 osp_idx=24 osp_ver=406272 osp_pg=0 (spawn #261928) 2025-06-07 18:05:20.295881 :A19CF114:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 18:05:20.295881 :A19CF115:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=184 time=2295278938 2025-06-07 18:05:20.295882 :A19CF116:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 18:05:20.295882 :A19CF117:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 18:05:20.295882 :A19CF118:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 18:05:20.295883 :A19CF119:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 18:05:20.295883 :A19CF11A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 18:05:20.296374 :A19CF11B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 18:05:32.860268 :A19CF809:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07 18:05:32.960283 :A19CFB72:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-07 18:05:32.960283*:A19CFB81:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000272EC718 estart=1749290732 eid=16781901 ctx=0x000000077FD6A000 cwh=0x00000000272E0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-07 18:05:32.960283*:A19CFB82:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000272EC718 curCtx=0x00000000272E8938 pubTabIdxCnt=0 2025-06-07 18:05:32.960283*:A19CFB83:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000272EC718 2025-06-07 18:05:32.960283*:A19CFB84:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000272EC718 2025-06-07 18:05:32.960283*:A19CFB85:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000272EC718 curCtx=0x00000000272E8938 xsc->flg4=65792 flags=2 2025-06-07 18:05:32.960283*:A19CFB86:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000272EC718 2025-06-07 18:05:32.965469 :A19CFB91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:32.965509 :A19CFB94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-06-07 18:05:32.969511 :A19CFBA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:33.075888 :A19CFBAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:33.204555 :A19CFBC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:33.335155 :A19CFBCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.475621 :A19CFBEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.476613 :A19CFBF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.478434 :A19CFBFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.479957 :A19CFC04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.483294 :A19CFC0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.484798 :A19CFC14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.486383 :A19CFC1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.501371 :A19CFC24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.502032 :A19CFC2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.502581 :A19CFC34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.503179 :A19CFC3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.505802 :A19CFC44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.506777 :A19CFC4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.507570 :A19CFC4D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.508360 :A19CFC4E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.508804 :A19CFC4F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.508811 :A19CFC50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.509432 :A19CFC51:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.509930 :A19CFC52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.510257 :A19CFC53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.511439 :A19CFC54:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.546745 :A19CFC55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.546797 :A19CFC58:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.547767 :A19CFC5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.548693 :A19CFC66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.549179 :A19CFC67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.569432 :A19CFC6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.569486 :A19CFC72:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.570418 :A19CFC78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.571288 :A19CFC80:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.571782 :A19CFC81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.591149 :A19CFC91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.591218 :A19CFC94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.592219 :A19CFC9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.593203 :A19CFCA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.594004 :A19CFCA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.615465 :A19CFCE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.615527 :A19CFCEB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.615527*:A19CFCF1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000299DFCB8 estart=1749290734 eid=16778031 ctx=0x0000000773524750 cwh=0x000000002733C718 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-07 18:05:34.615527*:A19CFCF2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000299DFCB8 curCtx=0x00000000299C17F8 pubTabIdxCnt=0 2025-06-07 18:05:34.615527*:A19CFCF3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000299DFCB8 2025-06-07 18:05:34.615527*:A19CFCF4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000299DFCB8 2025-06-07 18:05:34.615527*:A19CFCF5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000299DFCB8 curCtx=0x00000000299C17F8 xsc->flg4=65792 flags=1 2025-06-07 18:05:34.615527*:A19CFCF6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000299DFCB8 2025-06-07 18:05:34.616501 :A19CFCF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.617490 :A19CFCFF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.617974 :A19CFD00:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.639981 :A19CFD08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.640033 :A19CFD0B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.640908 :A19CFD11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.652535 :A19CFD19:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.653070 :A19CFD1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.697980 :A19CFD22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.698031 :A19CFD25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.700097 :A19CFD2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.703499 :A19CFD2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.708957 :A19CFD2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.709129 :A19CFD2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.709277 :A19CFD31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.709284 :A19CFD32:db_trace:ktur.c@3079:ktuabt(): [10444:26:209] ABORT TRANSACTION - xid: 0x0002.015.0000c264 2025-06-07 18:05:34.709413 :A19CFD44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.709779 :A19CFD45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.709881 :A19CFD46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.710111 :A19CFD47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-06-07 18:05:34.710150 :A19CFD48:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-06-07 18:05:34.710979 :A19CFD49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.711438 :A19CFD51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.711804 :A19CFD59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.712057 :A19CFD61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.712192 :A19CFD69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.712342 :A19CFD71:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-06-07 18:05:34.716695 :A19CFD72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.716819 :A19CFD75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-06-07 18:05:34.716905 :A19CFD82:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 15936, J001)