Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250512220005\orcl_j001_8436_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:37430M/63366M, Ph+PgF:44756M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 8436, image: ORACLE.EXE (J001) *** 2025-05-12 22:00:05.975 *** SESSION ID:(209.51681) 2025-05-12 22:00:05.975 *** 2025-05-12 22:00:05.975 Process diagnostic dump for ORACLE.EXE (J001), OS id=8436, pid: 26, proc_ser: 174, sid: 209, sess_ser: 51681 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8436 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.414181 sec, exc=0.414181 sec, total=0.414181 sec wait times: max=0.500000 sec, heur=1.407754 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.493557 sec, exc=0.493557 sec, total=0.493557 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 51681 --------------------------------------------------- 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, 22:00:04 - 22:00:05] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-12 22:00:05.975 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 8436, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-12 21:57:43.644741 :96422211: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-12 21:57:43.645533 :96422212:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:58:16.805505 :96422C61:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:58:16.791 2025-05-12 21:58:16.805507 :96422C62:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7552 pso_num=26 pso_serial#=162 2025-05-12 21:58:16.805648 :96422C63:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=7552 (legacy spawn) 2025-05-12 21:58:16.812032 :96422C64: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-12 21:58:16.812222 :96422C6D: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-12 21:58:16.812994 :96422C6E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:58:16.831328 :96422C71:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:58:16.822 2025-05-12 21:58:16.831330 :96422C72:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7548 pso_num=26 pso_serial#=163 2025-05-12 21:58:16.831467 :96422C73:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=7548 (legacy spawn) 2025-05-12 21:58:16.837940 :96422C74: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-12 21:58:16.838103 :96422C7C: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-12 21:58:16.838908 :96422C7D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:58:20.017605 :96422D6B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:58:20.004 2025-05-12 21:58:20.017607 :96422D6C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6832 pso_num=26 pso_serial#=164 2025-05-12 21:58:20.017741 :96422D6D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=6832 (legacy spawn) 2025-05-12 21:58:20.024197 :96422D6E: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-12 21:58:20.024368 :96422D74: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-12 21:58:20.025150 :96422D78:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:58:20.033725 :96422D7B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:58:20.020 2025-05-12 21:58:20.033727 :96422D7C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9460 pso_num=26 pso_serial#=165 2025-05-12 21:58:20.033860 :96422D7D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9460 (legacy spawn) 2025-05-12 21:58:20.040554 :96422D7E: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-12 21:58:20.040729 :96422D87: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-12 21:58:20.041556 :96422D88:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:58:27.758368 :96422FF9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:58:27.750 2025-05-12 21:58:27.758370 :96422FFA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11740 pso_num=26 pso_serial#=166 2025-05-12 21:58:27.758510 :96422FFB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11740 (legacy spawn) 2025-05-12 21:58:27.764926 :96422FFC: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-12 21:58:27.765101 :96423001: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-12 21:58:27.765885 :96423006:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:58:27.867374 :96423009:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:58:27.859 2025-05-12 21:58:27.867376 :9642300A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11924 pso_num=26 pso_serial#=167 2025-05-12 21:58:27.867511 :9642300B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11924 (legacy spawn) 2025-05-12 21:58:27.873927 :9642300C: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-12 21:58:27.874096 :96423015: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-12 21:58:27.874930 :96423016:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:59:29.629476 :964243DD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:59:29.615 2025-05-12 21:59:29.629478 :964243DE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12276 pso_num=26 pso_serial#=168 2025-05-12 21:59:29.629612 :964243DF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12276 (legacy spawn) 2025-05-12 21:59:29.636017 :964243E0: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-12 21:59:29.636189 :964243E6: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-12 21:59:29.636987 :964243EA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:59:29.652943 :964243ED:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:59:29.646 2025-05-12 21:59:29.652945 :964243EE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9360 pso_num=26 pso_serial#=169 2025-05-12 21:59:29.653089 :964243EF:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9360 (legacy spawn) 2025-05-12 21:59:29.659600 :964243F0: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-12 21:59:29.659791 :964243F9: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-12 21:59:29.660593 :964243FA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:59:30.076283 :96424420:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:59:30.068 2025-05-12 21:59:30.076285 :96424421:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11460 pso_num=26 pso_serial#=170 2025-05-12 21:59:30.076419 :96424422:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11460 (legacy spawn) 2025-05-12 21:59:30.082841 :96424423: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-12 21:59:30.083006 :9642442C: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-12 21:59:30.083793 :9642442D:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:59:32.913246 :9642452D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:59:32.901 2025-05-12 21:59:32.913248 :9642452E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11948 pso_num=26 pso_serial#=171 2025-05-12 21:59:32.913382 :9642452F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11948 (legacy spawn) 2025-05-12 21:59:32.919884 :96424530: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-12 21:59:32.920063 :96424535: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-12 21:59:32.920917 :9642453A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:59:32.938611 :9642453D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:59:32.932 2025-05-12 21:59:32.938612 :9642453E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9648 pso_num=26 pso_serial#=172 2025-05-12 21:59:32.938749 :9642453F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9648 (legacy spawn) 2025-05-12 21:59:32.945230 :96424540: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-12 21:59:32.945399 :96424549: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-12 21:59:32.946179 :9642454A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 21:59:35.851686 :9642463D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 21:59:35.837 2025-05-12 21:59:35.851688 :9642463E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12064 pso_num=26 pso_serial#=173 2025-05-12 21:59:35.851830 :9642463F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12064 (legacy spawn) 2025-05-12 21:59:35.858426 :96424640: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-12 21:59:35.858594 :96424649: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-12 21:59:35.859467 :9642464A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-12 22:00:02.043022 :96424F4E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-12 22:00:02.032 2025-05-12 22:00:02.043024 :96424F4F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8436 pso_num=26 pso_serial#=174 2025-05-12 22:00:02.043154 :96424F50:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8436 osp_idx=24 osp_ver=254153 osp_pg=0 (spawn #175821) 2025-05-12 22:00:02.043155 :96424F51: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-12 22:00:02.043155 :96424F52:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=99 time=63011907 2025-05-12 22:00:02.043155 :96424F53:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-12 22:00:02.043156 :96424F54:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-12 22:00:02.043156 :96424F55:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-12 22:00:02.043156 :96424F56:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-12 22:00:02.043156 :96424F57:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-12 22:00:02.043620 :96424F58: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-12 22:00:02.048632 :96424F80: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-12 22:00:02.048676 :96424F83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-05-12 22:00:02.052156 :96424F9E: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-12 22:00:02.427159 :96425333: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-12 22:00:02.470928 :9642533B: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-12 22:00:02.486751 :9642536B: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-12 22:00:02.544330 :9642537B: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-12 22:00:02.677932 :9642538B: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-12 22:00:02.798732 :96425393: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-12 22:00:02.808271 :9642539B: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-12 22:00:02.823085 :964253A3: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-12 22:00:02.837563 :964253AB: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-12 22:00:02.852635 :964253B3: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-12 22:00:02.862122 :964253BB: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-12 22:00:02.876717 :964253C3: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-12 22:00:02.886345 :964253CB: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-12 22:00:02.901102 :964253D3: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-12 22:00:02.910529 :964253FE: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-12 22:00:02.919843 :96425406: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-12 22:00:02.934368 :9642540E: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-12 22:00:02.943346 :96425416: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-12 22:00:02.958262 :9642541E: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-12 22:00:02.973108 :96425426: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-12 22:00:02.988141 :9642542E: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-12 22:00:02.995488 :96425436: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-12 22:00:03.010398 :9642543E: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-12 22:00:03.020071 :96425446: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-12 22:00:03.034457 :9642544E: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-12 22:00:03.043848 :96425456: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-12 22:00:03.052503 :9642545E: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-12 22:00:03.067059 :96425466: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-12 22:00:03.075923 :9642546E: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-12 22:00:03.090679 :96425476: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-12 22:00:03.100640 :9642547E: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-12 22:00:03.115430 :96425486: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-12 22:00:03.129915 :9642548E: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-12 22:00:03.138113 :96425496: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-12 22:00:03.145732 :9642549E: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-12 22:00:03.155228 :964254A6: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-12 22:00:03.164506 :964254AE: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-12 22:00:03.179337 :964254C2: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-12 22:00:03.193921 :964254CA: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-12 22:00:03.208359 :964254D2: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-12 22:00:03.217587 :964254DA: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-12 22:00:03.227600 :964254E2: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-12 22:00:03.236960 :964254EA: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-12 22:00:03.251857 :964254F2: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-12 22:00:03.261068 :964254FA: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-12 22:00:03.270380 :96425502: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-12 22:00:03.277567 :9642550A: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-12 22:00:03.281944*:9642551C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003C0DA188 estart=1747058402 eid=16788009 ctx=0x0000000783637648 cwh=0x00000000291D0068 cpu=15625 buffg=149 interb=0 r=0 rb=0 w=0 wb=0 2025-05-12 22:00:03.281944*:9642551D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003C0DA188 curCtx=0x000000003BFEFEB8 pubTabIdxCnt=0 2025-05-12 22:00:03.281944*:9642551E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003C0DA188 2025-05-12 22:00:03.281944*:9642551F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003C0DA188 2025-05-12 22:00:03.281944*:96425520:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000271EC288 estart=1747058401 eid=16777265 ctx=0x000000079F654AA8 cwh=0x00000000271E0158 cpu=1156250 buffg=86619 interb=24477696 r=1214 rb=22159360 w=12 wb=2318336 2025-05-12 22:00:03.281944*:96425521:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000271EC288 curCtx=0x000000003BFEEFE0 pubTabIdxCnt=0 2025-05-12 22:00:03.281944*:96425522:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000271EC288 2025-05-12 22:00:03.281944*:96425523:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000271EC288 2025-05-12 22:00:03.284725 :96425524: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-12 22:00:03.284725*:9642552C:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003C0DA188 curCtx=0x000000003BFEFEB8 xsc->flg4=65792 flags=0 2025-05-12 22:00:03.284725*:9642552D:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003C0DA188 2025-05-12 22:00:03.291935 :9642552E: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-12 22:00:03.299836 :96425536: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-12 22:00:03.307305 :9642553E: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-12 22:00:03.314444 :96425546: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-12 22:00:03.321697 :9642554E: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-12 22:00:03.329728 :96425556: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-12 22:00:03.337192 :9642555E: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-12 22:00:03.344465 :96425566: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-12 22:00:03.351611 :9642556E: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-12 22:00:03.359102 :96425576: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-12 22:00:03.366890 :9642557E: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-12 22:00:03.374422 :96425586: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-12 22:00:03.381886 :9642558E: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-12 22:00:03.389561 :96425596: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-12 22:00:04.456423 :964257B0: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-12 22:00:04.458762 :964257B8: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-12 22:00:04.458991 :964257C0: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-12 22:00:04.466630 :964257C8: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-12 22:00:04.473871 :964257D0: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-12 22:00:04.481037 :964257D8: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-12 22:00:04.489280 :964257E0: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-12 22:00:04.491485 :964257E8: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-12 22:00:04.491719 :964257FE: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-12 22:00:04.498988 :96425806: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-12 22:00:04.506080 :9642580E: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-12 22:00:04.513368 :96425816: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-12 22:00:04.520867 :9642581E: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-12 22:00:04.529052 :96425826: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-12 22:00:04.531193 :9642582E: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-12 22:00:04.531459 :96425836: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-12 22:00:04.538856 :9642583E: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-12 22:00:04.546156 :96425846: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-12 22:00:04.553784 :9642584E: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-12 22:00:04.561548 :96425856: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-12 22:00:04.561888 :9642585E: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-12 22:00:04.561888*:96425861:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000271EC288 curCtx=0x000000003BFEEFE0 xsc->flg4=65792 flags=2 2025-05-12 22:00:04.561888*:96425862:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000271EC288 2025-05-12 22:00:04.562143 :96425868:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-05-12 22:00:04.566890 :96425869: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-12 22:00:04.566975 :9642586C: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-12 22:00:04.567069 :96425875: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: 8436, J001)