Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250711220006\orcl_j004_15600_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:36423M/63366M, Ph+PgF:42490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 15600, image: ORACLE.EXE (J004) *** 2025-07-11 22:00:06.168 *** SESSION ID:(241.3043) 2025-07-11 22:00:06.168 *** 2025-07-11 22:00:06.168 Process diagnostic dump for ORACLE.EXE (J004), OS id=15600, pid: 30, proc_ser: 158, sid: 241, sess_ser: 3043 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15600 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.154145 sec, exc=0.154145 sec, total=0.154145 sec wait times: max=0.500000 sec, heur=2.163919 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510111 sec, exc=0.510111 sec, total=0.510111 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.499635 sec, exc=0.499635 sec, total=0.499635 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 241 serial 3043 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [3 samples, 22:00:04 - 22:00:06] 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-07-11 22:00:06.168 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 15600, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-10 22:00:02.071150 :B045CAF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.081167 :B045CAF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.090660 :B045CB01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.099943 :B045CB09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.115298 :B045CB11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.125012 :B045CB19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.132249 :B045CB21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.139657 :B045CB29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.146883 :B045CB31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.154181 :B045CB39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.161772 :B045CB41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.169249 :B045CB49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.176714 :B045CB51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.541057*:B045CB85:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000034476918 estart=1752156001 eid=16777433 ctx=0x00000007831EA738 cwh=0x00000000344A0248 cpu=359375 buffg=55410 interb=2433024 r=15 rb=901120 w=8 wb=1531904 2025-07-10 22:00:02.541057*:B045CB86:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000034476918 curCtx=0x000000004DE81600 pubTabIdxCnt=0 2025-07-10 22:00:02.541057*:B045CB87:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000034476918 2025-07-10 22:00:02.541057*:B045CB88:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000034476918 2025-07-10 22:00:02.541057*:B045CB89:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003196C288 estart=1752156000 eid=16777324 ctx=0x000000079F654AA8 cwh=0x0000000031960158 cpu=1640625 buffg=142516 interb=29384704 r=1383 rb=25559040 w=20 wb=3825664 2025-07-10 22:00:02.541057*:B045CB8A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003196C288 curCtx=0x000000004DBDDEC8 pubTabIdxCnt=0 2025-07-10 22:00:02.541057*:B045CB8B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003196C288 2025-07-10 22:00:02.541057*:B045CB8C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003196C288 2025-07-10 22:00:02.714026*:B045CCC1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000034476918 curCtx=0x000000004DE81600 xsc->flg4=65792 flags=1 2025-07-10 22:00:02.714026*:B045CCC2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000034476918 2025-07-10 22:00:03.301319 :B045CD74:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.301648 :B045CD7C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.301648*:B045CD84:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003196C288 curCtx=0x000000004DBDDEC8 xsc->flg4=65792 flags=2 2025-07-10 22:00:03.301648*:B045CD85:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003196C288 2025-07-10 22:00:03.301946 :B045CD86:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-07-10 22:00:03.306290 :B045CD87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.306365 :B045CD8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-10 22:00:03.306449 :B045CD97:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 22:00:33.366462 :B045DB59:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:11.633772 :B04F45FA:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:11.624 2025-07-10 23:59:11.633774 :B04F45FB:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15280 pso_num=30 pso_serial#=149 2025-07-10 23:59:11.633917 :B04F45FC:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=15280 (legacy spawn) 2025-07-10 23:59:11.640637 :B04F45FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:11.640833 :B04F4602:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:11.641679 :B04F4607:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:11.669846 :B04F460A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:11.655 2025-07-10 23:59:11.669848 :B04F460B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=14300 pso_num=30 pso_serial#=150 2025-07-10 23:59:11.669990 :B04F460C:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=14300 (legacy spawn) 2025-07-10 23:59:11.677000 :B04F460D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:11.677212 :B04F4616:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:11.678173 :B04F4617:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:11.687799 :B04F461A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:11.687 2025-07-10 23:59:11.687801 :B04F461B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18416 pso_num=30 pso_serial#=151 2025-07-10 23:59:11.687992 :B04F461C:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=18416 (legacy spawn) 2025-07-10 23:59:11.695173 :B04F461D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:11.695367 :B04F4625:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:11.696293 :B04F4627:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:12.223813 :B04F4642:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:12.218 2025-07-10 23:59:12.223815 :B04F4643:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17600 pso_num=30 pso_serial#=152 2025-07-10 23:59:12.223961 :B04F4644:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=17600 osp_idx=28 osp_ver=1426 osp_pg=0 (spawn #372763) 2025-07-10 23:59:12.223961 :B04F4645:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 23:59:12.223962 :B04F4646:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=120 time=872728111 2025-07-10 23:59:12.223962 :B04F4647:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 23:59:12.223962 :B04F4648:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 23:59:12.223963 :B04F4649:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 23:59:12.223963 :B04F464A:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 23:59:12.223963 :B04F464B:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 23:59:12.224528 :B04F464C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-10 23:59:12.225229 :B04F4661:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:12.233105 :B04F467E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-10 23:59:12.233620 :B04F467F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:12.233659 :B04F4682:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-10 23:59:12.234561 :B04F4688:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:12.234682 :B04F4691:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:42.267203 :B04F51D4:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:44.729311 :B04F52D9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:44.714 2025-07-10 23:59:44.729314 :B04F52DA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11784 pso_num=30 pso_serial#=153 2025-07-10 23:59:44.729463 :B04F52DB:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11784 (legacy spawn) 2025-07-10 23:59:44.736192 :B04F52DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:44.736364 :B04F52E5:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:44.737217 :B04F52E6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:44.746274 :B04F52E9:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:44.745 2025-07-10 23:59:44.746276 :B04F52EA:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9264 pso_num=30 pso_serial#=154 2025-07-10 23:59:44.746417 :B04F52EB:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9264 (legacy spawn) 2025-07-10 23:59:44.753281 :B04F52EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:44.753448 :B04F52F5:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:44.754343 :B04F52F6:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:48.006709 :B04F541C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:48.000 2025-07-10 23:59:48.006711 :B04F541D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=18184 pso_num=30 pso_serial#=155 2025-07-10 23:59:48.006853 :B04F541E:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=18184 (legacy spawn) 2025-07-10 23:59:48.013693 :B04F541F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:48.013729 :B04F5422:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:48.013883 :B04F5426:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:48.014743 :B04F5436:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:48.023840 :B04F5439:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:48.015 2025-07-10 23:59:48.023842 :B04F543A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9248 pso_num=30 pso_serial#=156 2025-07-10 23:59:48.023976 :B04F543B:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9248 (legacy spawn) 2025-07-10 23:59:48.030622 :B04F543C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 23:59:48.030791 :B04F5445:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-10 23:59:48.031661 :B04F5446:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-10 23:59:48.082195 :B04F5449:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-10 23:59:48.078 2025-07-10 23:59:48.082197 :B04F544A:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=17732 pso_num=30 pso_serial#=157 2025-07-10 23:59:48.082339 :B04F544B:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=17732 (legacy spawn) 2025-07-10 23:59:48.096737 :B04F544C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 00:00:21.592722 :B04F621D:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-07-11 22:00:02.067548 :B0B7B062:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 07-11 22:00:02.054 2025-07-11 22:00:02.067549 :B0B7B063:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=15600 pso_num=30 pso_serial#=158 2025-07-11 22:00:02.067673 :B0B7B06A:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=15600 osp_idx=28 osp_ver=1432 osp_pg=0 (spawn #375825) 2025-07-11 22:00:02.067673 :B0B7B06B:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 22:00:02.067674 :B0B7B06C:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=121 time=951977954 2025-07-11 22:00:02.067674 :B0B7B06D:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 22:00:02.067675 :B0B7B06E:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 22:00:02.067675 :B0B7B06F:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 22:00:02.067675 :B0B7B070:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 22:00:02.067676 :B0B7B071:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 22:00:02.068211 :B0B7B072:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-11 22:00:02.072932 :B0B7B1BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:02.072977 :B0B7B1BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-07-11 22:00:02.078432 :B0B7B20E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:02.306599 :B0B7B34B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:02.504919 :B0B7B376:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:02.640472 :B0B7B386:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.788899 :B0B7B571:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.789838 :B0B7B57B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.793822 :B0B7B583:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.796155 :B0B7B593:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.798449 :B0B7B59B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.798693*:B0B7B5A7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000345832E8 estart=1752242403 eid=16785439 ctx=0x000000079F59C358 cwh=0x00000000301F09D0 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-11 22:00:03.798693*:B0B7B5A8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000345832E8 curCtx=0x0000000034624A98 pubTabIdxCnt=0 2025-07-11 22:00:03.798693*:B0B7B5A9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000345832E8 2025-07-11 22:00:03.798693*:B0B7B5AA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000345832E8 2025-07-11 22:00:03.798693*:B0B7B5B3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000345832E8 curCtx=0x0000000034624A98 xsc->flg4=65792 flags=1 2025-07-11 22:00:03.798693*:B0B7B5B4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000345832E8 2025-07-11 22:00:03.799923 :B0B7B5B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.805107 :B0B7B5BD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.823961 :B0B7B5FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.824533 :B0B7B606:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.825034 :B0B7B60E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.825520 :B0B7B616:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.828139 :B0B7B61E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.829019 :B0B7B626:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.829821 :B0B7B627:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.830656 :B0B7B628:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.831078 :B0B7B629:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.831084 :B0B7B62A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.831651 :B0B7B62B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.832125 :B0B7B62C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.832380 :B0B7B62D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.833470 :B0B7B62E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.875654 :B0B7B62F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.875701 :B0B7B632:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.876601 :B0B7B638:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.877618 :B0B7B640:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.878147 :B0B7B641:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.899748 :B0B7B64E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.899811 :B0B7B651:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.899999 :B0B7B658:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] KSL POST RCVD poster=32 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-11 22:00:03.900625 :B0B7B663:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.901500 :B0B7B66E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.901931 :B0B7B66F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.927073 :B0B7B687:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.927126 :B0B7B68A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.927989 :B0B7B690:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.928850 :B0B7B698:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.929326 :B0B7B699:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.950655 :B0B7B6A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.950716 :B0B7B6A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.951436 :B0B7B6AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.952374 :B0B7B6B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.952810 :B0B7B6B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.973158 :B0B7B6C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.973213 :B0B7B6C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:03.974103 :B0B7B6CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:03.986642 :B0B7B6D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-07-11 22:00:03.987256 :B0B7B6D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.005280 :B0B7B6DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.005335 :B0B7B6E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-07-11 22:00:04.006157 :B0B7B6E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.006630 :B0B7B6EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.007005 :B0B7B6F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.007178 :B0B7B6FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.007318 :B0B7B706:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.007507 :B0B7B70E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-07-11 22:00:04.008156 :B0B7B70F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.011663 :B0B7B717:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-11 22:00:04.011782 :B0B7B71A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 30 (osid: 15600, J004)