Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511060006\orcl_j001_13124_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:37494M/63366M, Ph+PgF:44863M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 13124, image: ORACLE.EXE (J001) *** 2025-05-11 06:00:06.741 *** SESSION ID:(209.36625) 2025-05-11 06:00:06.741 *** 2025-05-11 06:00:06.741 Process diagnostic dump for ORACLE.EXE (J001), OS id=13124, pid: 26, proc_ser: 73, sid: 209, sess_ser: 36625 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13124 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.401433 sec, exc=0.401433 sec, total=0.401433 sec wait times: max=0.500000 sec, heur=1.893539 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.000006 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.492092 sec, exc=0.492092 sec, total=0.492092 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 36625 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 06:00:04 - 06:00:06] idle wait at each sample [session created at: 06:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-11 06:00:06.741 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 13124, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-11 05:57:50.269573 :958818AA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12980 pso_num=26 pso_serial#=61 2025-05-11 05:57:50.269706 :958818AB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12980 (legacy spawn) 2025-05-11 05:57:50.276126 :958818AC: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-05-11 05:57:50.276320 :958818B1: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-05-11 05:57:50.277419 :958818B6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:58:39.525365 :95882A6D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:58:39.522 2025-05-11 05:58:39.525366 :95882A6E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12492 pso_num=26 pso_serial#=62 2025-05-11 05:58:39.525503 :95882A6F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12492 (legacy spawn) 2025-05-11 05:58:39.531996 :95882A70: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-05-11 05:58:39.532170 :95882A79: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-05-11 05:58:39.532977 :95882A7A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:58:39.541560 :95882A7D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:58:39.537 2025-05-11 05:58:39.541562 :95882A7E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12548 pso_num=26 pso_serial#=63 2025-05-11 05:58:39.541706 :95882A7F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12548 (legacy spawn) 2025-05-11 05:58:39.548058 :95882A80: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-05-11 05:58:39.548228 :95882A89: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-05-11 05:58:39.549085 :95882A8A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:58:42.727054 :95882B69:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:58:42.714 2025-05-11 05:58:42.727056 :95882B6A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12512 pso_num=26 pso_serial#=64 2025-05-11 05:58:42.727188 :95882B6B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12512 (legacy spawn) 2025-05-11 05:58:42.733630 :95882B6C: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-05-11 05:58:42.733802 :95882B75: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-05-11 05:58:42.734599 :95882B76:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:58:42.743197 :95882B79:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:58:42.730 2025-05-11 05:58:42.743200 :95882B7A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12840 pso_num=26 pso_serial#=65 2025-05-11 05:58:42.743340 :95882B7B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12840 (legacy spawn) 2025-05-11 05:58:42.749749 :95882B7C: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-05-11 05:58:42.749912 :95882B85: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-05-11 05:58:42.750716 :95882B86:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:58:51.062199 :95882E03:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:58:51.053 2025-05-11 05:58:51.062202 :95882E04:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12244 pso_num=26 pso_serial#=66 2025-05-11 05:58:51.062343 :95882E05:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12244 (legacy spawn) 2025-05-11 05:58:51.068786 :95882E06: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-05-11 05:58:51.068947 :95882E0B: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-05-11 05:58:51.069741 :95882E10:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:58:52.481198 :95882E95:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:58:52.473 2025-05-11 05:58:52.481199 :95882E96:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10596 pso_num=26 pso_serial#=67 2025-05-11 05:58:52.481380 :95882E97:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10596 (legacy spawn) 2025-05-11 05:58:52.487723 :95882E98: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-05-11 05:58:52.487908 :95882E9D: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-05-11 05:58:52.488710 :95882EA2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:59:52.428206 :95884188:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:59:52.425 2025-05-11 05:59:52.428208 :95884189:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12852 pso_num=26 pso_serial#=68 2025-05-11 05:59:52.428345 :9588418A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12852 (legacy spawn) 2025-05-11 05:59:52.434823 :9588418B: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-05-11 05:59:52.435003 :95884194: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-05-11 05:59:52.435814 :95884195:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:59:52.444620 :95884198:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:59:52.441 2025-05-11 05:59:52.444622 :95884199:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13036 pso_num=26 pso_serial#=69 2025-05-11 05:59:52.444756 :9588419A:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13036 (legacy spawn) 2025-05-11 05:59:52.451188 :9588419B: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-05-11 05:59:52.451353 :958841A3: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-05-11 05:59:52.452145 :958841A5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:59:54.683856 :95884282:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:59:54.675 2025-05-11 05:59:54.683858 :95884283:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11320 pso_num=26 pso_serial#=70 2025-05-11 05:59:54.683994 :95884284:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11320 (legacy spawn) 2025-05-11 05:59:54.690446 :95884285: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-05-11 05:59:54.690615 :9588428E: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-05-11 05:59:54.691470 :9588428F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:59:55.761367 :958842D2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:59:55.748 2025-05-11 05:59:55.761369 :958842D3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11488 pso_num=26 pso_serial#=71 2025-05-11 05:59:55.761507 :958842D4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11488 (legacy spawn) 2025-05-11 05:59:55.767987 :958842D5: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-05-11 05:59:55.768154 :958842DA: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-05-11 05:59:55.768931 :958842DF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 05:59:55.777574 :958842E2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 05:59:55.763 2025-05-11 05:59:55.777576 :958842E3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12532 pso_num=26 pso_serial#=72 2025-05-11 05:59:55.777704 :958842E4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12532 (legacy spawn) 2025-05-11 05:59:55.784153 :958842E5: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-05-11 05:59:55.784309 :958842EE: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-05-11 05:59:55.785135 :958842EF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-11 06:00:02.053976 :9588469E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-11 06:00:02.053 2025-05-11 06:00:02.053977 :9588469F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13124 pso_num=26 pso_serial#=73 2025-05-11 06:00:02.054089 :958846A0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13124 osp_idx=24 osp_ver=246657 osp_pg=0 (spawn #170199) 2025-05-11 06:00:02.054090 :958846A1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 06:00:02.054090 :958846A2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=156 time=4213979203 2025-05-11 06:00:02.054090 :958846A3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 06:00:02.054091 :958846A4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 06:00:02.054091 :958846A5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 06:00:02.054092 :958846A6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 06:00:02.054092 :958846A7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 06:00:02.054510 :958846A8: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-05-11 06:00:02.059275 :958846BF: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-05-11 06:00:02.059337 :958846CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-11 06:00:02.062815 :958846F2: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-05-11 06:00:02.438768 :95884A7D: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-05-11 06:00:02.482966 :95884A8D: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-05-11 06:00:02.499052 :95884A95: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-05-11 06:00:02.556761 :95884A9D: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-05-11 06:00:02.691408 :95884AB1: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-05-11 06:00:02.804936 :95884AB9: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-05-11 06:00:02.814650 :95884AC1: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-05-11 06:00:02.829125 :95884AC9: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-05-11 06:00:02.843320 :95884AD1: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-05-11 06:00:02.858126 :95884AD9: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-05-11 06:00:02.867815 :95884AE1: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-05-11 06:00:02.877075 :95884AE9: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-05-11 06:00:02.891319 :95884AF1: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-05-11 06:00:02.906004 :95884AF9: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-05-11 06:00:02.915828 :95884B01: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-05-11 06:00:02.925136 :95884B09: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-05-11 06:00:02.939623 :95884B11: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-05-11 06:00:02.948502 :95884B19: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-05-11 06:00:02.963403 :95884B21: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-05-11 06:00:02.978115 :95884B29: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-05-11 06:00:02.992604 :95884B31: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-05-11 06:00:02.999947 :95884B39: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-05-11 06:00:03.014533 :95884B41: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-05-11 06:00:03.024366 :95884B49: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-05-11 06:00:03.038886 :95884B51: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-05-11 06:00:03.048765 :95884B59: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-05-11 06:00:03.057678 :95884B61: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-05-11 06:00:03.072393 :95884B69: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-05-11 06:00:03.081289 :95884B71: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-05-11 06:00:03.095688 :95884B79: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-05-11 06:00:03.105533 :95884B81: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-05-11 06:00:03.120217 :95884B89: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-05-11 06:00:03.134446 :95884B91: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-05-11 06:00:03.144027 :95884B99: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-05-11 06:00:03.153604 :95884BA1: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-05-11 06:00:03.162253 :95884BA9: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-05-11 06:00:03.176480 :95884BB1: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-05-11 06:00:03.190990 :95884BB9: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-05-11 06:00:03.200357 :95884BC1: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-05-11 06:00:03.214501 :95884BDF: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-05-11 06:00:03.223903 :95884BE7: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-05-11 06:00:03.233903 :95884C2E: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-05-11 06:00:03.243385 :95884C36: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-05-11 06:00:03.257944 :95884C3E: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-05-11 06:00:03.267327 :95884C46: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-05-11 06:00:03.274367 :95884C4E: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-05-11 06:00:03.281371 :95884C56: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-05-11 06:00:03.288535 :95884C5E: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-05-11 06:00:03.295745 :95884C66: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-05-11 06:00:03.303489 :95884C6E: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-05-11 06:00:03.310630 :95884C99: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-05-11 06:00:03.317921 :95884CA1: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-05-11 06:00:03.325127 :95884CA9: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-05-11 06:00:03.332712 :95884CB1: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-05-11 06:00:03.339978 :95884CB9: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-05-11 06:00:03.347220 :95884CC1: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-05-11 06:00:03.381045*:95884CF0:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000291D6918 estart=1746914402 eid=16777313 ctx=0x00000007831EA738 cwh=0x0000000029200248 cpu=31250 buffg=7478 interb=114688 r=7 rb=114688 w=0 wb=0 2025-05-11 06:00:03.381045*:95884CF1:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000291D6918 curCtx=0x0000000034621CD0 pubTabIdxCnt=0 2025-05-11 06:00:03.381045*:95884CF2:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000291D6918 2025-05-11 06:00:03.381045*:95884CF3:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000291D6918 2025-05-11 06:00:03.381045*:95884CF4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000272EC288 estart=1746914401 eid=16777264 ctx=0x000000079F654AA8 cwh=0x00000000272E0158 cpu=1281250 buffg=96372 interb=28966912 r=1487 rb=26640384 w=12 wb=2326528 2025-05-11 06:00:03.381045*:95884CF5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000272EC288 curCtx=0x0000000034644F98 pubTabIdxCnt=0 2025-05-11 06:00:03.381045*:95884CF6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000272EC288 2025-05-11 06:00:03.381045*:95884CF7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000272EC288 2025-05-11 06:00:03.896684*:95884E66:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000291D6918 curCtx=0x0000000034621CD0 xsc->flg4=65792 flags=1 2025-05-11 06:00:03.896684*:95884E67:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000291D6918 2025-05-11 06:00:04.423122 :95884E71: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-05-11 06:00:04.425560 :95884E79: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-05-11 06:00:04.425793 :95884E81: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-05-11 06:00:04.434455 :95884E89: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-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=1 options=10264 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 0 and execution count: 0 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=10980 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=5 options=532496 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-11 06:00:04.756305*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-11 06:00:04.771931*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 0 and execution count: 0 2025-05-11 06:00:04.771931*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=10980 2025-05-11 06:00:04.771931*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-11 06:00:04.771931*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-11 06:00:04.794472 :95884ED0: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-05-11 06:00:04.794817 :95884ED8: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-05-11 06:00:04.802295 :95884EE0: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-05-11 06:00:04.809894 :95884EE8: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-05-11 06:00:04.818197 :95884EF0: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-05-11 06:00:04.820424 :95884EF8: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-05-11 06:00:04.820665 :95884F00: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-05-11 06:00:04.828207 :95884F08: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-05-11 06:00:04.835628 :95884F10: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-05-11 06:00:04.844081 :95884F18: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-05-11 06:00:04.844422 :95884F20: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-05-11 06:00:04.844422*:95884F28:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000272EC288 curCtx=0x0000000034644F98 xsc->flg4=65792 flags=2 2025-05-11 06:00:04.844422*:95884F29:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000272EC288 2025-05-11 06:00:04.844699 :95884F2A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-05-11 06:00:04.849201 :95884F2B: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-05-11 06:00:04.849282 :95884F2E: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-05-11 06:00:04.849382 :95884F3B: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: 13124, J001)