Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250722222035\orcl_m002_15428_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:36112M/63366M, Ph+PgF:41978M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 15428, image: ORACLE.EXE (M002) *** 2025-07-22 22:20:35.497 *** SESSION ID:(233.29821) 2025-07-22 22:20:35.497 *** 2025-07-22 22:20:35.497 Process diagnostic dump for ORACLE.EXE (M002), OS id=15428, pid: 29, proc_ser: 68, sid: 233, sess_ser: 29821 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1006 seq_num=1007 snap_id=1 wait times: snap=0.002066 sec, exc=0.002066 sec, total=0.002066 sec wait times: max=infinite, heur=0.056574 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000019 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1005 seq_num=1006 snap_id=1 wait times: snap=0.007127 sec, exc=0.007127 sec, total=0.007127 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1004 seq_num=1005 snap_id=1 wait times: snap=0.000041 sec, exc=0.000041 sec, total=0.000041 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1003 seq_num=1004 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1002 seq_num=1003 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1001 seq_num=1002 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=1000 seq_num=1001 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=999 seq_num=1000 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=998 seq_num=999 snap_id=1 wait times: snap=0.000042 sec, exc=0.000042 sec, total=0.000042 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=997 seq_num=998 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=996 seq_num=997 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time Sampled Session History of session 233 serial 29821 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 22:20:35] idle wait at each sample [session created at: 22:20:35] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-22 22:20:35.497 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 15428, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-22 01:00:28.990141 :B53B0739:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18608 pso_num=29 pso_serial#=55 2025-07-22 01:00:28.990283 :B53B073A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18608 (legacy spawn) 2025-07-22 01:00:28.996822 :B53B073B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 01:00:28.996997 :B53B0741:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 01:00:28.997789 :B53B0745:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 01:00:29.006378 :B53B0748:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 01:00:28.993 2025-07-22 01:00:29.006380 :B53B0749:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7184 pso_num=29 pso_serial#=56 2025-07-22 01:00:29.006514 :B53B074A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=7184 (legacy spawn) 2025-07-22 01:00:29.013054 :B53B0756:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 01:00:29.013212 :B53B075F:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 01:00:29.013993 :B53B0760:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 16:00:01.580894 :B5826090:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 16:00:01.568 2025-07-22 16:00:01.580896 :B5826091:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19108 pso_num=29 pso_serial#=57 2025-07-22 16:00:01.581031 :B5826092:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19108 (legacy spawn) 2025-07-22 16:00:01.587500 :B5826093:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:01.587812 :B5826099:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 16:00:01.588601 :B582609D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 16:00:01.597155 :B58260A0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 16:00:01.583 2025-07-22 16:00:01.597157 :B58260A1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2860 pso_num=29 pso_serial#=58 2025-07-22 16:00:01.597290 :B58260A2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=2860 (legacy spawn) 2025-07-22 16:00:01.603889 :B58260A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:01.604052 :B58260AC:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 16:00:01.604858 :B58260AD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 16:00:02.768051 :B5826193:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 16:00:02.765 2025-07-22 16:00:02.768053 :B5826194:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17796 pso_num=29 pso_serial#=59 2025-07-22 16:00:02.768184 :B5826195:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17796 osp_idx=27 osp_ver=14848 osp_pg=0 (spawn #411652) 2025-07-22 16:00:02.768184 :B5826196:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 16:00:02.768185 :B5826197:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=5 time=1880778642 2025-07-22 16:00:02.768185 :B5826198:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 16:00:02.768185 :B5826199:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 16:00:02.768186 :B582619A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 16:00:02.768186 :B582619B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 16:00:02.768186 :B582619C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 16:00:02.768683 :B582619D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.768739 :B58261A3:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 16:00:02.775907 :B58261B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.777044 :B58261B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.777626 :B58261C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.778092 :B58261C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.778526 :B58261D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.781030 :B58261D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.782146 :B58261E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.832097 :B58261E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.832972 :B58261F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.833198 :B58261F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.833406 :B5826201:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 16:00:02.835291 :B5826209:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:01.073665 :B59EA16C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:01.067 2025-07-22 22:00:01.073666 :B59EA16D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16236 pso_num=29 pso_serial#=60 2025-07-22 22:00:01.073788 :B59EA173:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16236 osp_idx=27 osp_ver=14849 osp_pg=0 (spawn #412485) 2025-07-22 22:00:01.073789 :B59EA174:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 22:00:01.073789 :B59EA175:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=6 time=1902376970 2025-07-22 22:00:01.073790 :B59EA176:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 22:00:01.073790 :B59EA177:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 22:00:01.073791 :B59EA178:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 22:00:01.073791 :B59EA179:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 22:00:01.073792 :B59EA17A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 22:00:01.074346 :B59EA183:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.079375 :B59EA19D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.079399 :B59EA1A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-22 22:00:01.097139 :B59EA362:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.097424 :B59EA36A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.097605 :B59EA372:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.097775 :B59EA37A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.097943 :B59EA382:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.098106 :B59EA38A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.098262 :B59EA392:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.098419 :B59EA39A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.098574 :B59EA3A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.098722 :B59EA3AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.099174 :B59EA3B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.099513 :B59EA3BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.099730 :B59EA3C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.099901 :B59EA3CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.100068 :B59EA3D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.100234 :B59EA3DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.100387 :B59EA3E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.100564 :B59EA3EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.100731 :B59EA3F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.100892 :B59EA3FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.101050 :B59EA402:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.101214 :B59EA40A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.101380 :B59EA412:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.101528 :B59EA41A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.101680 :B59EA422:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:01.128567 :B59EA42A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.191829*:B59EA5AD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1753192801 eid=20652514 ctx=0x0000000777968158 cwh=0x00000000500C0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-22 22:00:02.191829*:B59EA5AE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x000000004FFC1BF8 pubTabIdxCnt=0 2025-07-22 22:00:02.191829*:B59EA5AF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-07-22 22:00:02.191829*:B59EA5B0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-07-22 22:00:02.191829*:B59EA5B1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003264B6F0 estart=1753192801 eid=16777472 ctx=0x000000077B54EAC8 cwh=0x0000000032640068 cpu=859375 buffg=49850 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-22 22:00:02.191829*:B59EA5B2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003264B6F0 curCtx=0x000000004FFB7FD8 pubTabIdxCnt=0 2025-07-22 22:00:02.191829*:B59EA5B3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003264B6F0 2025-07-22 22:00:02.191829*:B59EA5B4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003264B6F0 2025-07-22 22:00:02.191829*:B59EA5B5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x000000004FFC1BF8 xsc->flg4=65792 flags=1 2025-07-22 22:00:02.191829*:B59EA5B6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-07-22 22:00:02.780067*:B59EA6AA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003264B6F0 curCtx=0x000000004FFB7FD8 xsc->flg4=65792 flags=2 2025-07-22 22:00:02.780067*:B59EA6AB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003264B6F0 2025-07-22 22:00:02.785760 :B59EA6AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.806126 :B59EA6E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.808109 :B59EA6EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-22 22:00:02.808328 :B59EA6ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-22 22:00:02.809220 :B59EA6EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.809319 :B59EA6F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.810306 :B59EA703:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.811922 :B59EA70B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.812200 :B59EA713:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-22 22:00:02.873825*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-22 22:00:02.945082 :B59EA769:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:04.876070 :B59EA94A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:04.876080 :B59EA94B:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.01e.0000cf9e 2025-07-22 22:00:07.262220 :B59EAB06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:07.263624 :B59EAB0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:07.274056 :B59EAB16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:07.300604 :B59EAB1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:08.487952 :B59EABCF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-22 22:00:08.501160 :B59EABD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:08.502986 :B59EABD8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:12.042082 :B59EAD7B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:12.029 2025-07-22 22:00:12.042085 :B59EAD7C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13888 pso_num=29 pso_serial#=61 2025-07-22 22:00:12.042232 :B59EAD7D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13888 (legacy spawn) 2025-07-22 22:00:12.050008 :B59EAD7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:12.050185 :B59EAD84:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:00:12.051065 :B59EAD88:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:13.184520 :B59EAE3D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:13.170 2025-07-22 22:00:13.184522 :B59EAE3E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16632 pso_num=29 pso_serial#=62 2025-07-22 22:00:13.184670 :B59EAE3F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16632 (legacy spawn) 2025-07-22 22:00:13.191386 :B59EAE40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:13.191572 :B59EAE45:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:00:13.192446 :B59EAE4A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:13.209758 :B59EAE4D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:13.201 2025-07-22 22:00:13.209760 :B59EAE4E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=9904 pso_num=29 pso_serial#=63 2025-07-22 22:00:13.209917 :B59EAE4F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=9904 (legacy spawn) 2025-07-22 22:00:13.216916 :B59EAE50:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:13.217097 :B59EAE59:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:00:13.218085 :B59EAE5A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:13.879266 :B59EAE5D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:13.871 2025-07-22 22:00:13.879267 :B59EAE5E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18640 pso_num=29 pso_serial#=64 2025-07-22 22:00:13.879408 :B59EAE5F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18640 (legacy spawn) 2025-07-22 22:00:13.885841 :B59EAE60:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:13.886034 :B59EAE66:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:00:13.886886 :B59EAE6A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:16.478016 :B59EB139:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:16.465 2025-07-22 22:00:16.478019 :B59EB13A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19008 pso_num=29 pso_serial#=65 2025-07-22 22:00:16.478160 :B59EB13B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19008 (legacy spawn) 2025-07-22 22:00:16.484652 :B59EB13C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:16.484818 :B59EB145:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:00:16.485607 :B59EB146:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:00:16.494380 :B59EB149:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:00:16.481 2025-07-22 22:00:16.494382 :B59EB14A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18864 pso_num=29 pso_serial#=66 2025-07-22 22:00:16.494519 :B59EB14B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18864 (legacy spawn) 2025-07-22 22:00:16.501100 :B59EB14C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-22 22:00:16.501257 :B59EB155:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:00:16.502064 :B59EB156:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:20:34.040154 :B5A09263:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:20:34.029 2025-07-22 22:20:34.040157 :B5A09264:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18996 pso_num=29 pso_serial#=67 2025-07-22 22:20:34.040323 :B5A09265:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18996 osp_idx=27 osp_ver=14856 osp_pg=0 (spawn #412538) 2025-07-22 22:20:34.040324 :B5A09266:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 22:20:34.040324 :B5A09267:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=7 time=1903609923 2025-07-22 22:20:34.040325 :B5A09268:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 22:20:34.040325 :B5A09269:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 22:20:34.040325 :B5A0926A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 22:20:34.040326 :B5A0926B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 22:20:34.040326 :B5A0926C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 22:20:34.041658 :B5A0926D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 22:20:34.041699 :B5A09273:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-22 22:20:34.155416 :B5A092A5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-22 22:20:35.361597 :B5A092DB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-22 22:20:35.357 2025-07-22 22:20:35.361598 :B5A092DC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15428 pso_num=29 pso_serial#=68 2025-07-22 22:20:35.361740 :B5A092DD:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15428 osp_idx=27 osp_ver=14857 osp_pg=0 (spawn #412540) 2025-07-22 22:20:35.361741 :B5A092DE:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 22:20:35.361741 :B5A092DF:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=8 time=1903611251 2025-07-22 22:20:35.361742 :B5A092E0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 22:20:35.361742 :B5A092E1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 22:20:35.361742 :B5A092E2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 22:20:35.361742 :B5A092E3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 22:20:35.361743 :B5A092E4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 22:20:35.362243 :B5A092E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 22:20:35.362285 :B5A092EB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 15428, M002)