Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250811220221\orcl_m002_20484_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:35576M/63366M, Ph+PgF:41004M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 20484, image: ORACLE.EXE (M002) *** 2025-08-11 22:02:22.029 *** SESSION ID:(219.109) 2025-08-11 22:02:22.029 *** 2025-08-11 22:02:22.029 Process diagnostic dump for ORACLE.EXE (M002), OS id=20484, pid: 27, proc_ser: 100, sid: 219, sess_ser: 109 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=944 seq_num=945 snap_id=1 wait times: snap=0.002057 sec, exc=0.002057 sec, total=0.002057 sec wait times: max=infinite, heur=0.150637 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.000008 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=943 seq_num=944 snap_id=1 wait times: snap=0.000203 sec, exc=0.000203 sec, total=0.000203 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=942 seq_num=943 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=941 seq_num=942 snap_id=1 wait times: snap=0.000053 sec, exc=0.000053 sec, total=0.000053 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=940 seq_num=941 snap_id=1 wait times: snap=0.000073 sec, exc=0.000073 sec, total=0.000073 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000017 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=939 seq_num=940 snap_id=1 wait times: snap=0.004317 sec, exc=0.004317 sec, total=0.004317 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=938 seq_num=939 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 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=937 seq_num=938 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=936 seq_num=937 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 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=935 seq_num=936 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 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=934 seq_num=935 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.000007 sec of elapsed time Sampled Session History of session 219 serial 109 --------------------------------------------------- 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:02:21] waited for 'ADR block file read', seq_num: 142 p1: ' '=0x0 p2: ' '=0x0 p3: ' '=0x0 time_waited: >= 0 sec (still in wait) [1 sample, 22:02:20] idle wait at each sample [session created at: 22:02:21] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'ADR block file read' [1 sample, 22:02:21 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-11 22:02:22.029 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 20484, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-11 21:58:31.200763 :BE76DAA8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:58:31.186 2025-08-11 21:58:31.200766 :BE76DAA9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20876 pso_num=27 pso_serial#=87 2025-08-11 21:58:31.200903 :BE76DAAA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=20876 (legacy spawn) 2025-08-11 21:58:31.207360 :BE76DAAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:58:31.207534 :BE76DAB4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:58:31.208334 :BE76DAB5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:58:47.468113 :BE76DF92:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:58:47.460 2025-08-11 21:58:47.468115 :BE76DF93:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=1216 pso_num=27 pso_serial#=88 2025-08-11 21:58:47.468254 :BE76DF94:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=1216 (legacy spawn) 2025-08-11 21:58:47.474686 :BE76DF95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:58:47.474861 :BE76DF9A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:58:47.475680 :BE76DF9F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:58:48.983074 :BE76DFFB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:58:48.970 2025-08-11 21:58:48.983076 :BE76DFFC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=21136 pso_num=27 pso_serial#=89 2025-08-11 21:58:48.983211 :BE76DFFD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=21136 (legacy spawn) 2025-08-11 21:58:48.989677 :BE76DFFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:58:48.989849 :BE76E003:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:58:48.990647 :BE76E007:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:58:48.999370 :BE76E00A:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:58:48.986 2025-08-11 21:58:48.999372 :BE76E00B:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18680 pso_num=27 pso_serial#=90 2025-08-11 21:58:48.999515 :BE76E00C:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18680 (legacy spawn) 2025-08-11 21:58:49.006032 :BE76E00D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:58:49.006201 :BE76E016:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:58:49.007012 :BE76E017:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:59:34.613045 :BE76EEA6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:59:34.603 2025-08-11 21:59:34.613047 :BE76EEA7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16496 pso_num=27 pso_serial#=91 2025-08-11 21:59:34.613179 :BE76EEA8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16496 (legacy spawn) 2025-08-11 21:59:34.619666 :BE76EEA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:59:34.619831 :BE76EEAE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:59:34.620662 :BE76EEB3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:59:40.871679 :BE76F08D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:59:40.857 2025-08-11 21:59:40.871680 :BE76F08E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19264 pso_num=27 pso_serial#=92 2025-08-11 21:59:40.871815 :BE76F08F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19264 (legacy spawn) 2025-08-11 21:59:40.878321 :BE76F090:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:59:40.878498 :BE76F095:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:59:40.879290 :BE76F09A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:59:40.887956 :BE76F09D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:59:40.873 2025-08-11 21:59:40.887958 :BE76F09E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19388 pso_num=27 pso_serial#=93 2025-08-11 21:59:40.888094 :BE76F09F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=19388 (legacy spawn) 2025-08-11 21:59:40.894575 :BE76F0A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:59:40.894733 :BE76F0A9:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:59:40.895543 :BE76F0AA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:59:44.184323 :BE76F1C8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:59:44.170 2025-08-11 21:59:44.184326 :BE76F1C9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17888 pso_num=27 pso_serial#=94 2025-08-11 21:59:44.184479 :BE76F1CA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17888 (legacy spawn) 2025-08-11 21:59:44.191821 :BE76F1CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:59:44.192016 :BE76F1D0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:59:44.193022 :BE76F1D5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:59:44.210653 :BE76F1D8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:59:44.201 2025-08-11 21:59:44.210655 :BE76F1D9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17124 pso_num=27 pso_serial#=95 2025-08-11 21:59:44.210814 :BE76F1DA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17124 (legacy spawn) 2025-08-11 21:59:44.218212 :BE76F1DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:59:44.218395 :BE76F1E4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:59:44.219335 :BE76F1E5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 21:59:49.851409 :BE76F391:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 21:59:49.842 2025-08-11 21:59:49.851412 :BE76F392:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=18472 pso_num=27 pso_serial#=96 2025-08-11 21:59:49.851554 :BE76F393:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=18472 (legacy spawn) 2025-08-11 21:59:49.858287 :BE76F394:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 21:59:49.858530 :BE76F399:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 21:59:49.859428 :BE76F39E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 22:00:01.283627 :BE76F8C0:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 22:00:01.272 2025-08-11 22:00:01.283630 :BE76F8C1:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12948 pso_num=27 pso_serial#=97 2025-08-11 22:00:01.283766 :BE76F8C2:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12948 osp_idx=25 osp_ver=261990 osp_pg=0 (spawn #479154) 2025-08-11 22:00:01.283767 :BE76F8C3:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 22:00:01.283767 :BE76F8C4:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=46 time=3630270579 2025-08-11 22:00:01.283767 :BE76F8C5:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 22:00:01.283768 :BE76F8C6:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 22:00:01.283768 :BE76F8C7:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 22:00:01.283769 :BE76F8C8:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 22:00:01.283769 :BE76F8C9:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 22:00:01.284254 :BE76F8CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.289548 :BE76F8F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.289595 :BE76F8F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:jslv.c:9203 2025-08-11 22:00:01.293137 :BE76F910:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.692131 :BE76FC61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.735868 :BE76FC69:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.751411 :BE76FC79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.808758 :BE76FC81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:01.943148 :BE76FC95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.063626 :BE76FCC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.073471 :BE76FCCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.089518 :BE76FCD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.105123 :BE76FCDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.121271 :BE76FCE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.130758 :BE76FCED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.146550 :BE76FCF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.156101 :BE76FCFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.172214 :BE76FD05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.182104 :BE76FD0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.191464 :BE76FD15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.206978 :BE76FD1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.216700 :BE76FD25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.233459 :BE76FD2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.249539 :BE76FD35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.265179 :BE76FD3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.272538 :BE76FD45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.288298 :BE76FD4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.297943 :BE76FD55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.313529 :BE76FD5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.322679 :BE76FD65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.332588 :BE76FD6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.342806 :BE76FD75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.360238 :BE76FD7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.375994 :BE76FD85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.384871 :BE76FD8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.395100 :BE76FD95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.411926 :BE76FD9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.429915 :BE76FDA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.440267 :BE76FDAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.456610 :BE76FDB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.473403 :BE76FDBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.490024 :BE76FDC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.499848 :BE76FDCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.510744 :BE76FDD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.523220 :BE76FDDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.532691 :BE76FDE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.542089 :BE76FDED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.551462 :BE76FDF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.567426 :BE76FDFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.574611 :BE76FE05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.581830 :BE76FE0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.589253 :BE76FE15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.596621 :BE76FE1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.604073 :BE76FE25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.611649 :BE76FE2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.619196 :BE76FE35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.626509 :BE76FE3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.633665 :BE76FE45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:02.646829*:BE76FE4E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B516918 estart=1754920801 eid=16777497 ctx=0x00000007831EA738 cwh=0x000000002B540248 cpu=15625 buffg=792 interb=114688 r=7 rb=114688 w=0 wb=0 2025-08-11 22:00:02.646829*:BE76FE50:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B516918 curCtx=0x0000000056891CD0 pubTabIdxCnt=0 2025-08-11 22:00:02.646829*:BE76FE52:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B516918 2025-08-11 22:00:02.646829*:BE76FE53:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B516918 2025-08-11 22:00:02.646829*:BE76FE56:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028FFC288 estart=1754920800 eid=16777356 ctx=0x000000079F654AA8 cwh=0x0000000028FF0158 cpu=1312500 buffg=88573 interb=28033024 r=1431 rb=25714688 w=12 wb=2318336 2025-08-11 22:00:02.646829*:BE76FE59:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028FFC288 curCtx=0x00000000568D4F98 pubTabIdxCnt=0 2025-08-11 22:00:02.646829*:BE76FE5A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028FFC288 2025-08-11 22:00:02.646829*:BE76FE5B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028FFC288 2025-08-11 22:00:03.188197*:BE76FFF3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B516918 curCtx=0x0000000056891CD0 xsc->flg4=65792 flags=1 2025-08-11 22:00:03.188197*:BE76FFF4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B516918 2025-08-11 22:00:03.787148 :BE770023:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:03.787487 :BE77002B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:03.787487*:BE77002F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028FFC288 curCtx=0x00000000568D4F98 xsc->flg4=65792 flags=2 2025-08-11 22:00:03.787487*:BE770034:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028FFC288 2025-08-11 22:00:03.787754 :BE770035:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:jslv.c:9203 2025-08-11 22:00:03.792180 :BE770036:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-11 22:00:03.792265 :BE770039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-11 22:00:03.792360 :BE770046:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 22:00:04.014918 :BE770063:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-11 22:00:21.336583 :BE77085C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-11 22:00:51.407935 :BE7713B0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 22:01:21.450555 :BE771D57:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 22:01:21.439 2025-08-11 22:01:21.450557 :BE771D58:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19228 pso_num=27 pso_serial#=98 2025-08-11 22:01:21.450672 :BE771D59:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=19228 osp_idx=25 osp_ver=261991 osp_pg=0 (spawn #479162) 2025-08-11 22:01:21.450672 :BE771D5A:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 22:01:21.450673 :BE771D5B:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=47 time=3630350751 2025-08-11 22:01:21.450674 :BE771D5C:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 22:01:21.450674 :BE771D5D:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 22:01:21.450675 :BE771D5E:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 22:01:21.450675 :BE771D5F:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 22:01:21.450675 :BE771D60:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 22:01:21.451206 :BE771D61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-11 22:01:51.539671 :BE772714:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 22:02:19.912498 :BE779FA4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 22:02:19.910 2025-08-11 22:02:19.912500 :BE779FA5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=19176 pso_num=27 pso_serial#=99 2025-08-11 22:02:19.912672 :BE779FA6:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=19176 osp_idx=25 osp_ver=261992 osp_pg=0 (spawn #479164) 2025-08-11 22:02:19.912673 :BE779FA7:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 22:02:19.912674 :BE779FA8:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=48 time=3630409204 2025-08-11 22:02:19.912674 :BE779FA9:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 22:02:19.912674 :BE779FAA:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 22:02:19.912675 :BE779FAB:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 22:02:19.912675 :BE779FAC:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 22:02:19.912675 :BE779FAD:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 22:02:19.914029 :BE779FAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-11 22:02:19.914073 :BE779FB4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-08-11 22:02:20.005088 :BE779FE6:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-08-11 22:02:21.228885 :BE77A043:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 08-11 22:02:21.217 2025-08-11 22:02:21.228888 :BE77A044:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=20484 pso_num=27 pso_serial#=100 2025-08-11 22:02:21.229021 :BE77A045:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=20484 osp_idx=25 osp_ver=261993 osp_pg=0 (spawn #479166) 2025-08-11 22:02:21.229022 :BE77A046:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 22:02:21.229022 :BE77A047:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=49 time=3630410533 2025-08-11 22:02:21.229022 :BE77A048:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 22:02:21.229023 :BE77A049:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 22:02:21.229023 :BE77A04A:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 22:02:21.229023 :BE77A04B:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 22:02:21.229024 :BE77A04C:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 22:02:21.229518 :BE77A04D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-11 22:02:21.229560 :BE77A053:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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 27 (osid: 20484, M002)