Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250725220008\orcl_j003_14400_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:36018M/63366M, Ph+PgF:41782M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14400, image: ORACLE.EXE (J003) *** 2025-07-25 22:00:08.318 *** SESSION ID:(233.30155) 2025-07-25 22:00:08.318 *** 2025-07-25 22:00:08.318 Process diagnostic dump for ORACLE.EXE (J003), OS id=14400, pid: 29, proc_ser: 234, sid: 233, sess_ser: 30155 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14400 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.403353 sec, exc=0.403353 sec, total=0.403353 sec wait times: max=0.500000 sec, heur=4.410916 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.504602 sec, exc=0.504602 sec, total=0.504602 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=6 seq_num=7 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.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000015 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.505107 sec, exc=0.505107 sec, total=0.505107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.497789 sec, exc=0.497789 sec, total=0.497789 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 233 serial 30155 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:03 - 22:00:08] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-25 22:00:08.318 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14400, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-25 01:00:24.159343 :B68FC7CD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=27 time=2086000048 2025-07-25 01:00:24.159343 :B68FC7CE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 01:00:24.159344 :B68FC7CF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 01:00:24.159344 :B68FC7D0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 01:00:24.159345 :B68FC7D1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 01:00:24.159346 :B68FC7D2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 01:00:24.159838 :B68FC7D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.159885 :B68FC7D9:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-25 01:00:24.164380 :B68FC7EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.187628 :B68FC801:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.202329 :B68FC809:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.232545 :B68FC811:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.236211 :B68FC819:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.236530 :B68FC821:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.240265 :B68FC829:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.242929 :B68FC831:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.244617 :B68FC839:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.245689 :B68FC841:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.249347 :B68FC862:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:24.266646 :B68FC86A:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 0, nex 2 2025-07-25 01:00:24.266654 :B68FC86B:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080fe73 where: kdiwh18: kdifind 2025-07-25 01:00:24.274577 :B68FC86C:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 1, nex 3 2025-07-25 01:00:24.274585 :B68FC86D:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080fe92 where: ktsphwh45_: ktsptrm_bmb 2025-07-25 01:00:24.292871 :B68FC86E:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 2, nex 4 2025-07-25 01:00:24.292879 :B68FC86F:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0080ffff where: kdiwh22: kdifind 2025-07-25 01:00:24.880871 :B68FC8A6:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 3, nex 5 2025-07-25 01:00:24.880880 :B68FC8A7:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008117ae where: kdiwh22: kdifind 2025-07-25 01:00:25.509514 :B68FC8B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:25.548925 :B68FC8BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:25.549246 :B68FC8C4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:25.549522 :B68FC8CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:25.549539 :B68FC8CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:25.549613 :B68FC8DF:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-25 01:00:25.552637 :B68FC8E9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 01:00:32.698466 :B68FCB81:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 01:00:32.684 2025-07-25 01:00:32.698468 :B68FCB82:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18064 pso_num=29 pso_serial#=228 2025-07-25 01:00:32.698602 :B68FCB83:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18064 (legacy spawn) 2025-07-25 01:00:32.705029 :B68FCB84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:32.705206 :B68FCB8D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-25 01:00:32.706004 :B68FCB8E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 01:00:32.714698 :B68FCB91:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 01:00:32.700 2025-07-25 01:00:32.714700 :B68FCB92:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14056 pso_num=29 pso_serial#=229 2025-07-25 01:00:32.714838 :B68FCB93:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14056 (legacy spawn) 2025-07-25 01:00:32.721249 :B68FCB94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:32.721415 :B68FCB9D:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-25 01:00:32.722213 :B68FCB9E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 01:00:32.926452 :B68FCBA1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 01:00:32.918 2025-07-25 01:00:32.926454 :B68FCBA2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=6024 pso_num=29 pso_serial#=230 2025-07-25 01:00:32.926588 :B68FCBA3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=6024 (legacy spawn) 2025-07-25 01:00:32.933016 :B68FCBA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:32.933187 :B68FCBAA:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-25 01:00:32.933992 :B68FCBAE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 01:00:33.696918 :B68FCC44:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 01:00:33.684 2025-07-25 01:00:33.696920 :B68FCC45:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12604 pso_num=29 pso_serial#=231 2025-07-25 01:00:33.697060 :B68FCC46:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12604 (legacy spawn) 2025-07-25 01:00:33.703560 :B68FCC47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:33.703733 :B68FCC50:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-25 01:00:33.704531 :B68FCC51:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 01:00:33.713200 :B68FCC54:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 01:00:33.700 2025-07-25 01:00:33.713202 :B68FCC55:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19304 pso_num=29 pso_serial#=232 2025-07-25 01:00:33.713344 :B68FCC56:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19304 (legacy spawn) 2025-07-25 01:00:33.719805 :B68FCC57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 01:00:33.719969 :B68FCC60:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-25 01:00:33.720787 :B68FCC61:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 16:00:17.203645 :B6D61245:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 16:00:17.188 2025-07-25 16:00:17.203647 :B6D61246:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18136 pso_num=29 pso_serial#=233 2025-07-25 16:00:17.203780 :B6D61247:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18136 osp_idx=27 osp_ver=15022 osp_pg=0 (spawn #421655) 2025-07-25 16:00:17.203781 :B6D61248:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 16:00:17.203781 :B6D61249:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=28 time=2139993095 2025-07-25 16:00:17.203782 :B6D6124A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 16:00:17.203782 :B6D6124B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 16:00:17.203783 :B6D6124C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 16:00:17.203783 :B6D6124D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 16:00:17.203783 :B6D6124E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 16:00:17.204281 :B6D6124F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.204322 :B6D61255:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-25 16:00:17.211483 :B6D61263:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.212537 :B6D6126B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.213038 :B6D61273:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.213473 :B6D6127B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.213889 :B6D61283:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.216380 :B6D6128B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.217441 :B6D61293:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.268163 :B6D612A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.269064 :B6D612AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.269300 :B6D612B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.269530 :B6D612BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 16:00:17.271301 :B6D612C7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-25 22:00:02.038346 :B6F26997:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-25 22:00:02.027 2025-07-25 22:00:02.038347 :B6F26998:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14400 pso_num=29 pso_serial#=234 2025-07-25 22:00:02.038469 :B6F2699E:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14400 osp_idx=27 osp_ver=15023 osp_pg=0 (spawn #422485) 2025-07-25 22:00:02.038470 :B6F2699F:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-25 22:00:02.038471 :B6F269A0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=29 time=2161577923 2025-07-25 22:00:02.038471 :B6F269A1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-25 22:00:02.038471 :B6F269A2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-25 22:00:02.038472 :B6F269A3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-25 22:00:02.038472 :B6F269A4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-25 22:00:02.038472 :B6F269A5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-25 22:00:02.039060 :B6F269A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-25 22:00:02.044175 :B6F26AF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:02.044225 :B6F26AF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-25 22:00:02.049867 :B6F26B21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:02.259937 :B6F26CCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:02.449654 :B6F26CDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:02.583043 :B6F26D07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.704421 :B6F26F3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.705413 :B6F26F43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.709316 :B6F26F4B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.710859 :B6F26F53:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.713820 :B6F26F5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.715411 :B6F26F63:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.716738 :B6F26F6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.736492 :B6F26F73:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.737113 :B6F26F7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.737612 :B6F26F83:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.738102 :B6F26F8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.740653 :B6F26F93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.741527 :B6F26F9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.742239 :B6F26F9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.743002 :B6F26F9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.743380 :B6F26F9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.743386 :B6F26F9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.743963 :B6F26FA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.744451 :B6F26FA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.744733 :B6F26FA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.745815 :B6F26FA3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.761174*:B6F26FA4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000344901F8 estart=1753452002 eid=16778524 ctx=0x000000077B42EF30 cwh=0x0000000034480338 cpu=15625 buffg=25 interb=0 r=0 rb=0 w=0 wb=0 2025-07-25 22:00:03.761174*:B6F26FA5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000344901F8 curCtx=0x00000000344AF5E8 pubTabIdxCnt=0 2025-07-25 22:00:03.761174*:B6F26FA6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000344901F8 2025-07-25 22:00:03.761174*:B6F26FA7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000344901F8 2025-07-25 22:00:03.761174*:B6F26FA8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000034485840 estart=1753452002 eid=16778524 ctx=0x000000077B447BE8 cwh=0x0000000031F1DDC8 cpu=15625 buffg=25 interb=0 r=0 rb=0 w=0 wb=0 2025-07-25 22:00:03.761174*:B6F26FA9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000034485840 curCtx=0x0000000034481DB8 pubTabIdxCnt=0 2025-07-25 22:00:03.761174*:B6F26FAA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000034485840 2025-07-25 22:00:03.761174*:B6F26FAB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000034485840 2025-07-25 22:00:03.761174*:B6F26FAC:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031F925F8 estart=1753452002 eid=16778524 ctx=0x000000077B458BE8 cwh=0x0000000031F1BC10 cpu=15625 buffg=448 interb=98304 r=12 rb=98304 w=0 wb=0 2025-07-25 22:00:03.761174*:B6F26FAD:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031F925F8 curCtx=0x000000003454C5B8 pubTabIdxCnt=0 2025-07-25 22:00:03.761174*:B6F26FAE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031F925F8 2025-07-25 22:00:03.761174*:B6F26FAF:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031F925F8 2025-07-25 22:00:03.761174*:B6F26FBC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000344901F8 curCtx=0x00000000344AF5E8 xsc->flg4=65792 flags=1 2025-07-25 22:00:03.761174*:B6F26FBD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000344901F8 2025-07-25 22:00:03.761174*:B6F26FBE:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000034485840 curCtx=0x0000000034481DB8 xsc->flg4=65792 flags=1 2025-07-25 22:00:03.761174*:B6F26FBF:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000034485840 2025-07-25 22:00:03.776803*:B6F26FCA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031F925F8 curCtx=0x000000003454C5B8 xsc->flg4=65792 flags=2 2025-07-25 22:00:03.776803*:B6F26FCB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031F925F8 2025-07-25 22:00:03.781885 :B6F26FCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.781940 :B6F26FCF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.782809 :B6F26FD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.783772 :B6F26FDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.784187 :B6F26FDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.805333 :B6F2701D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.805372 :B6F27020:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.806146 :B6F27026:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.806918 :B6F2702E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.807330 :B6F2702F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.825921 :B6F27037:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.825967 :B6F2703A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.826787 :B6F27040:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.827648 :B6F27048:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.828212 :B6F27049:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.849349 :B6F27051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.849400 :B6F27054:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.850316 :B6F2705A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.851177 :B6F27062:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.851603 :B6F27063:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.874484 :B6F2706F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.874531 :B6F27072:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.875506 :B6F27078:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.888092 :B6F27080:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-25 22:00:03.888799 :B6F27081:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.909685 :B6F27089:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.909748 :B6F2708C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-25 22:00:03.910771 :B6F27092:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.911327 :B6F2709A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.911732 :B6F270A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.911933 :B6F270AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.912076 :B6F270B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.912283 :B6F270BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203 2025-07-25 22:00:03.916658 :B6F270BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.916765 :B6F270BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-25 22:00:03.916839 :B6F270CB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 14400, J003)