Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250705180841\orcl_j001_13480_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:37395M/63366M, Ph+PgF:43382M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 13480, image: ORACLE.EXE (J001) *** 2025-07-05 18:08:41.874 *** SESSION ID:(209.23663) 2025-07-05 18:08:41.874 *** 2025-07-05 18:08:41.874 Process diagnostic dump for ORACLE.EXE (J001), OS id=13480, pid: 26, proc_ser: 110, sid: 209, sess_ser: 23663 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13480 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.135172 sec, exc=0.135172 sec, total=0.135172 sec wait times: max=0.500000 sec, heur=2.635142 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=4 seq_num=5 snap_id=1 wait times: snap=0.500169 sec, exc=0.500169 sec, total=0.500169 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.514072 sec, exc=0.514072 sec, total=0.514072 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.485691 sec, exc=0.485691 sec, total=0.485691 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 23663 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 18:08:39 - 18:08:41] idle wait at each sample [session created at: 18:08:39] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-05 18:08:41.874 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 13480, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-05 18:03:38.821206 :ADF43980:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:03:38.821207 :ADF43981:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:03:38.821208 :ADF43982:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:03:38.821208 :ADF43983:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:03:38.821208 :ADF43984:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:03:38.821767 :ADF43985: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-07-05 18:04:08.889428 :ADF443E1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-05 18:04:38.904619 :ADF44E11:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-05 18:04:38.892 2025-07-05 18:04:38.904622 :ADF44E12:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7196 pso_num=26 pso_serial#=105 2025-07-05 18:04:38.904738 :ADF44E13:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7196 osp_idx=24 osp_ver=567225 osp_pg=0 (spawn #355285) 2025-07-05 18:04:38.904739 :ADF44E14:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:04:38.904739 :ADF44E15:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=54 time=419454798 2025-07-05 18:04:38.904740 :ADF44E16:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:04:38.904740 :ADF44E17:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:04:38.904740 :ADF44E18:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:04:38.904741 :ADF44E19:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:04:38.904741 :ADF44E1A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:04:38.905312 :ADF44E1B: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-07-05 18:05:08.955203 :ADF45831:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-05 18:05:39.001742 :ADF46796:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-05 18:05:38.990 2025-07-05 18:05:39.001744 :ADF46797:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13716 pso_num=26 pso_serial#=106 2025-07-05 18:05:39.001861 :ADF46798:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13716 osp_idx=24 osp_ver=567226 osp_pg=0 (spawn #355287) 2025-07-05 18:05:39.001861 :ADF46799:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:05:39.001861 :ADF4679A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=55 time=419514892 2025-07-05 18:05:39.001861 :ADF4679B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:05:39.001862 :ADF4679C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:05:39.001862 :ADF4679D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:05:39.001862 :ADF4679E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:05:39.001862 :ADF4679F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:05:39.002462 :ADF467A0: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-07-05 18:06:09.067671 :ADF4736F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-05 18:06:39.095437 :ADF47D80:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-05 18:06:39.084 2025-07-05 18:06:39.095439 :ADF47D81:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4556 pso_num=26 pso_serial#=107 2025-07-05 18:06:39.095547 :ADF47D82:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=4556 osp_idx=24 osp_ver=567227 osp_pg=0 (spawn #355290) 2025-07-05 18:06:39.095548 :ADF47D83:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:06:39.095548 :ADF47D84:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=56 time=419574986 2025-07-05 18:06:39.095549 :ADF47D85:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:06:39.095549 :ADF47D86:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:06:39.095550 :ADF47D87:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:06:39.095550 :ADF47D88:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:06:39.095550 :ADF47D89:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:06:39.096091 :ADF47D8A: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-07-05 18:07:09.173915 :ADF4879F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-05 18:07:39.192312 :ADF491ED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-05 18:07:39.180 2025-07-05 18:07:39.192314 :ADF491EE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11464 pso_num=26 pso_serial#=108 2025-07-05 18:07:39.192432 :ADF491EF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11464 osp_idx=24 osp_ver=567228 osp_pg=0 (spawn #355292) 2025-07-05 18:07:39.192433 :ADF491F0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:07:39.192433 :ADF491F1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=57 time=419635079 2025-07-05 18:07:39.192433 :ADF491F2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:07:39.192434 :ADF491F3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:07:39.192435 :ADF491F4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:07:39.192435 :ADF491F5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:07:39.192435 :ADF491F6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:07:39.192981 :ADF491F7: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-07-05 18:08:09.280770 :ADF49C36:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-05 18:08:33.560356 :ADF4A474:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-05 18:08:33.548 2025-07-05 18:08:33.560357 :ADF4A475:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16148 pso_num=26 pso_serial#=109 2025-07-05 18:08:33.560549 :ADF4A476:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16148 osp_idx=24 osp_ver=567229 osp_pg=0 (spawn #355294) 2025-07-05 18:08:33.560550 :ADF4A477:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:08:33.560550 :ADF4A478:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=58 time=419689454 2025-07-05 18:08:33.560551 :ADF4A479:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:08:33.560551 :ADF4A47A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:08:33.560552 :ADF4A47B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:08:33.560552 :ADF4A47C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:08:33.560552 :ADF4A47D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:08:33.561194 :ADF4A47E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.561239 :ADF4A484:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-05 18:08:33.561310 :ADF4A492:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.565888 :ADF4A4A4: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-07-05 18:08:33.618789 :ADF4A4AC: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-07-05 18:08:33.621210 :ADF4A4B4: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-07-05 18:08:33.622168 :ADF4A4BC: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-07-05 18:08:33.622190 :ADF4A4BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.622233 :ADF4A4CA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-05 18:08:33.623311 :ADF4A4D5: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-07-05 18:08:33.623334 :ADF4A4D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.623363 :ADF4A4E3:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-05 18:08:33.623849 :ADF4A4EE: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-07-05 18:08:33.626259 :ADF4A4F6: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-07-05 18:08:33.631474 :ADF4A4FE: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-07-05 18:08:33.636593 :ADF4A506: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-07-05 18:08:33.637578 :ADF4A50E: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-07-05 18:08:33.638818 :ADF4A516: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-07-05 18:08:33.641949 :ADF4A51E: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-07-05 18:08:33.643023 :ADF4A526: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-07-05 18:08:33.643031 :ADF4A527:db_trace:ktur.c@3079:ktuabt(): [10444:26:209] ABORT TRANSACTION - xid: 0x000a.003.0017163a 2025-07-05 18:08:33.645832 :ADF4A52F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.645991 :ADF4A53E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.646230 :ADF4A549: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-07-05 18:08:33.646274 :ADF4A54C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:ktcc.c:879 2025-07-05 18:08:33.646287 :ADF4A54D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:ktcc.c:879 2025-07-05 18:08:33.648199 :ADF4A563:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.648378 :ADF4A565: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-07-05 18:08:33.648420 :ADF4A568:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:ktcc.c:879 2025-07-05 18:08:33.648432 :ADF4A569:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:ktcc.c:879 2025-07-05 18:08:33.648629 :ADF4A56F: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-07-05 18:08:33.648645 :ADF4A572:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-05 18:08:33.648691 :ADF4A57D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-05 18:08:33.650787 :ADF4A588:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-05 18:08:33.658369 :ADF4A5AA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-05 18:08:33.657 2025-07-05 18:08:33.658371 :ADF4A5AB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13480 pso_num=26 pso_serial#=110 2025-07-05 18:08:33.658511 :ADF4A5AC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13480 osp_idx=24 osp_ver=567230 osp_pg=0 (spawn #355296) 2025-07-05 18:08:33.658512 :ADF4A5AD:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-05 18:08:33.658513 :ADF4A5AE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=59 time=419689548 2025-07-05 18:08:33.658513 :ADF4A5AF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-05 18:08:33.658514 :ADF4A5B0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-05 18:08:33.658514 :ADF4A5B1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-05 18:08:33.658515 :ADF4A5B2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-05 18:08:33.658515 :ADF4A5B3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-05 18:08:33.659088 :ADF4A5BD: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-07-05 18:08:33.767209 :ADF4A8DC: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-07-05 18:08:33.772570 :ADF4A8F5: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-07-05 18:08:33.772611 :ADF4A8F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-05 18:08:33.776819 :ADF4A908: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-07-05 18:08:33.884232 :ADF4A910: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-07-05 18:08:34.011933 :ADF4A92B: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-07-05 18:08:34.137315 :ADF4A933: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-07-05 18:08:35.254455 :ADF4A9E3: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-07-05 18:08:35.257682 :ADF4A9EB: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-07-05 18:08:35.258644 :ADF4A9F3: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-07-05 18:08:35.260315 :ADF4A9FB: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-07-05 18:08:35.262812 :ADF4AA03: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-07-05 18:08:35.265127 :ADF4AA0B: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-07-05 18:08:35.281359 :ADF4AA13: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-07-05 18:08:35.282106 :ADF4AA1B: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-07-05 18:08:35.282314*:ADF4AA2D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028C2EED0 estart=1751710115 eid=16788098 ctx=0x000000077F821100 cwh=0x0000000024D10CA0 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-07-05 18:08:35.282314*:ADF4AA2E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028C2EED0 curCtx=0x0000000028CB37C8 pubTabIdxCnt=0 2025-07-05 18:08:35.282314*:ADF4AA2F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028C2EED0 2025-07-05 18:08:35.282314*:ADF4AA30:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028C2EED0 2025-07-05 18:08:35.282314*:ADF4AA31:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028C2EED0 curCtx=0x0000000028CB37C8 xsc->flg4=65792 flags=2 2025-07-05 18:08:35.282314*:ADF4AA32:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028C2EED0 2025-07-05 18:08:35.282715 :ADF4AA33: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-07-05 18:08:35.283253 :ADF4AA3B: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-07-05 18:08:35.285881 :ADF4AA43: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-07-05 18:08:35.286783 :ADF4AA4B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.287532 :ADF4AA4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.288273 :ADF4AA4D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.288705 :ADF4AA4E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.288712 :ADF4AA4F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.289302 :ADF4AA50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.289886 :ADF4AA51:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.290209 :ADF4AA52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.291275 :ADF4AA53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.329461 :ADF4AA5F: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-07-05 18:08:35.329502 :ADF4AA62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.330379 :ADF4AA68: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-07-05 18:08:35.331308 :ADF4AA70:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.331754 :ADF4AA71: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-07-05 18:08:35.352507 :ADF4AA79: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-07-05 18:08:35.352554 :ADF4AA7C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.353353 :ADF4AA82: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-07-05 18:08:35.364933 :ADF4AA8A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.365447 :ADF4AA8B: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-07-05 18:08:35.383067 :ADF4AA93: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-07-05 18:08:35.383116 :ADF4AA96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.383935 :ADF4AA9C: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-07-05 18:08:35.384853 :ADF4AAA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:35.385347 :ADF4AAA5: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-07-05 18:08:35.407100 :ADF4AAAD: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-07-05 18:08:35.407155 :ADF4AAB0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:35.408122 :ADF4AAB6: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-07-05 18:08:35.408580 :ADF4AABE: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-07-05 18:08:35.408942 :ADF4AAC8: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-07-05 18:08:35.409127 :ADF4AAD0: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-07-05 18:08:35.409281 :ADF4AADA: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-07-05 18:08:35.409425 :ADF4AAE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-05 18:08:35.413490 :ADF4AAE3: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-07-05 18:08:35.413570 :ADF4AAE6: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-07-05 18:08:35.413668 :ADF4AAF3: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 2025-07-05 18:08:39.234939 :ADF4ACF8: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-07-05 18:08:39.234966*:ADF4ACFD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D0BB58 estart=1751710118 eid=16942666 ctx=0x000000077BD98A08 cwh=0x0000000024B000E0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-05 18:08:39.234966*:ADF4ACFE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D0BB58 curCtx=0x0000000024B0A310 pubTabIdxCnt=0 2025-07-05 18:08:39.234966*:ADF4ACFF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D0BB58 2025-07-05 18:08:39.234966*:ADF4AD00:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D0BB58 2025-07-05 18:08:39.234966*:ADF4AD01:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D0BB58 curCtx=0x0000000024B0A310 xsc->flg4=65792 flags=2 2025-07-05 18:08:39.234966*:ADF4AD02:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D0BB58 2025-07-05 18:08:39.235528 :ADF4AD03: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-07-05 18:08:39.243067 :ADF4AD0B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:26:209] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 18:08:39.243450 :ADF4AD0C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:26:209] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-05 18:08:39.245505 :ADF4AD0D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-05 18:08:39.245911 :ADF4AD0E: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-07-05 18:08:39.245947 :ADF4AD11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-05 18:08:39.246914 :ADF4AD17: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-07-05 18:08:39.247047 :ADF4AD20: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: 13480, J001)