Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250724220006\orcl_j003_14024_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:36088M/63366M, Ph+PgF:41883M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14024, image: ORACLE.EXE (J003) *** 2025-07-24 22:00:06.691 *** SESSION ID:(233.30035) 2025-07-24 22:00:06.691 *** 2025-07-24 22:00:06.691 Process diagnostic dump for ORACLE.EXE (J003), OS id=14024, pid: 29, proc_ser: 175, sid: 233, sess_ser: 30035 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14024 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12803, hash value=1163157255 Current Wait Stack: Not in wait; last wait ended 2.842765 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.842792 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000159 sec, exc=0.000159 sec, total=0.000159 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.063999 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.040853 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000174 sec, exc=0.000174 sec, total=0.000174 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.708643 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025448 sec of elapsed time 4: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012886 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000076 sec, exc=0.000076 sec, total=0.000076 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049435 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.005130 sec, exc=0.005130 sec, total=0.005130 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008042 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.001838 sec, exc=0.001838 sec, total=0.001838 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.266263 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.006386 sec, exc=0.006386 sec, total=0.006386 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000230 sec of elapsed time 9: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000141 sec, exc=0.000141 sec, total=0.000141 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007933 sec of elapsed time Sampled Session History of session 233 serial 30035 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 22:00:03 - 22:00:06] not in wait at each sample [1 sample, 22:00:02] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-24 22:00:06.691 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14024, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-24 00:06:00.410299 :B61A4B2B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 00:06:00.401 2025-07-24 00:06:00.410301 :B61A4B2C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18612 pso_num=29 pso_serial#=164 2025-07-24 00:06:00.410431 :B61A4B2D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18612 (legacy spawn) 2025-07-24 00:06:00.416989 :B61A4B2E: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-24 00:06:00.417157 :B61A4B37: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-24 00:06:00.417978 :B61A4B38:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 00:06:06.116277 :B61A4D19:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 00:06:06.103 2025-07-24 00:06:06.116279 :B61A4D1A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17692 pso_num=29 pso_serial#=165 2025-07-24 00:06:06.116417 :B61A4D1B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17692 (legacy spawn) 2025-07-24 00:06:06.122960 :B61A4D1C: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-24 00:06:06.123138 :B61A4D21: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-24 00:06:06.123928 :B61A4D26:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 00:06:10.712055 :B61A4EBA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 00:06:10.697 2025-07-24 00:06:10.712057 :B61A4EBB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17944 pso_num=29 pso_serial#=166 2025-07-24 00:06:10.712207 :B61A4EBC:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17944 (legacy spawn) 2025-07-24 00:06:10.718635 :B61A4EBD: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-24 00:06:10.718806 :B61A4EC6: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-24 00:06:10.719631 :B61A4EC7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 00:06:10.728211 :B61A4ECA:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 00:06:10.713 2025-07-24 00:06:10.728213 :B61A4ECB:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1216 pso_num=29 pso_serial#=167 2025-07-24 00:06:10.728350 :B61A4ECC:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=1216 (legacy spawn) 2025-07-24 00:06:10.734879 :B61A4ECD: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-24 00:06:10.735048 :B61A4ED6: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-24 00:06:10.735886 :B61A4ED7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 01:00:07.209298 :B61E8A98:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 01:00:07.196 2025-07-24 01:00:07.209300 :B61E8A99:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17060 pso_num=29 pso_serial#=168 2025-07-24 01:00:07.209449 :B61E8A9A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17060 (legacy spawn) 2025-07-24 01:00:07.216127 :B61E8A9B: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-24 01:00:07.216307 :B61E8AA0: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-24 01:00:07.217146 :B61E8AA5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 01:00:12.006860 :B61E8C4E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 01:00:11.992 2025-07-24 01:00:12.006862 :B61E8C4F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12004 pso_num=29 pso_serial#=169 2025-07-24 01:00:12.007006 :B61E8C50:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12004 (legacy spawn) 2025-07-24 01:00:12.013424 :B61E8C51: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-24 01:00:12.013596 :B61E8C58: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-24 01:00:12.014406 :B61E8C5B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 01:00:12.023107 :B61E8C5E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 01:00:12.008 2025-07-24 01:00:12.023109 :B61E8C5F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15748 pso_num=29 pso_serial#=170 2025-07-24 01:00:12.023244 :B61E8C60:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15748 (legacy spawn) 2025-07-24 01:00:12.029751 :B61E8C61: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-24 01:00:12.029914 :B61E8C6A: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-24 01:00:12.030742 :B61E8C6B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 01:00:15.224641 :B61E8D63:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 01:00:15.211 2025-07-24 01:00:15.224643 :B61E8D64:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18848 pso_num=29 pso_serial#=171 2025-07-24 01:00:15.224784 :B61E8D65:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18848 (legacy spawn) 2025-07-24 01:00:15.231303 :B61E8D66: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-24 01:00:15.231334 :B61E8D69: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-24 01:00:15.231493 :B61E8D70: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-24 01:00:15.232283 :B61E8D7D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 01:00:15.240951 :B61E8D80:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 01:00:15.227 2025-07-24 01:00:15.240952 :B61E8D81:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18728 pso_num=29 pso_serial#=172 2025-07-24 01:00:15.241087 :B61E8D82:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18728 (legacy spawn) 2025-07-24 01:00:15.247528 :B61E8D83: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-24 01:00:15.247689 :B61E8D8C: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-24 01:00:15.248493 :B61E8D8D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 01:00:21.593244 :B61E8FA9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 01:00:21.588 2025-07-24 01:00:21.593246 :B61E8FAA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15428 pso_num=29 pso_serial#=173 2025-07-24 01:00:21.593387 :B61E8FAB:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15428 osp_idx=27 osp_ver=14962 osp_pg=0 (spawn #416241) 2025-07-24 01:00:21.593388 :B61E8FAC: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-24 01:00:21.593389 :B61E8FAD:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=17 time=1999597486 2025-07-24 01:00:21.593389 :B61E8FAE:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 01:00:21.593390 :B61E8FAF:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 01:00:21.593390 :B61E8FB0:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 01:00:21.593390 :B61E8FB1:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 01:00:21.593391 :B61E8FB2:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 01:00:21.593878 :B61E8FB3: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-24 01:00:21.593922 :B61E8FB9: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-24 01:00:21.598438 :B61E8FCC: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-24 01:00:21.623228 :B61E8FD4: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-24 01:00:21.637954 :B61E8FDC: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-24 01:00:21.668627 :B61E8FE4: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-24 01:00:21.672393 :B61E8FEC: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-24 01:00:21.672746 :B61E8FF4: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-24 01:00:21.676545 :B61E8FFC: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-24 01:00:21.679315 :B61E9004: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-24 01:00:21.680911 :B61E900C: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-24 01:00:21.681961 :B61E9014: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-24 01:00:21.685833 :B61E901C: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-24 01:00:22.806684*:B61E9030:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B568460 estart=1753290021 eid=16780174 ctx=0x000000077BA4B1C8 cwh=0x000000002B520738 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-24 01:00:22.806684*:B61E9031:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B568460 curCtx=0x000000002DB2AC08 pubTabIdxCnt=0 2025-07-24 01:00:22.806684*:B61E9032:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B568460 2025-07-24 01:00:22.806684*:B61E9033:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B568460 2025-07-24 01:00:22.806684*:B61E9034:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B566130 estart=1753290021 eid=16780157 ctx=0x0000000787AD5BF0 cwh=0x000000002B520828 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-24 01:00:22.806684*:B61E9035:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B566130 curCtx=0x000000002DB2CB10 pubTabIdxCnt=0 2025-07-24 01:00:22.806684*:B61E9036:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B566130 2025-07-24 01:00:22.806684*:B61E9037:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B566130 2025-07-24 01:00:22.869195*:B61E9038:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B568460 curCtx=0x000000002DB2AC08 xsc->flg4=65792 flags=1 2025-07-24 01:00:22.869195*:B61E9039:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B568460 2025-07-24 01:00:22.869195*:B61E903A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B566130 curCtx=0x000000002DB2CB10 xsc->flg4=65792 flags=2 2025-07-24 01:00:22.869195*:B61E903B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B566130 2025-07-24 01:00:22.941582 :B61E903C: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-24 01:00:22.981967 :B61E9044: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-24 01:00:22.982313 :B61E904C: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-24 01:00:22.982593 :B61E9054: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-24 01:00:22.982609 :B61E9057: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-24 01:00:22.982677 :B61E9067: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-24 01:00:22.985967 :B61E9071:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 16:00:17.544249 :B6651727:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 16:00:17.542 2025-07-24 16:00:17.544251 :B6651728:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17856 pso_num=29 pso_serial#=174 2025-07-24 16:00:17.544383 :B6651729:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17856 osp_idx=27 osp_ver=14963 osp_pg=0 (spawn #418320) 2025-07-24 16:00:17.544384 :B665172A: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-24 16:00:17.544384 :B665172B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=18 time=2053593423 2025-07-24 16:00:17.544385 :B665172C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 16:00:17.544385 :B665172D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 16:00:17.544385 :B665172E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 16:00:17.544385 :B665172F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 16:00:17.544386 :B6651730:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 16:00:17.544895 :B6651731: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-24 16:00:17.544937 :B6651737: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-24 16:00:17.552174 :B6651745: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-24 16:00:17.553203 :B665174D: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-24 16:00:17.553672 :B6651755: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-24 16:00:17.554106 :B665175D: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-24 16:00:17.554530 :B6651765: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-24 16:00:17.557068 :B665176D: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-24 16:00:17.558201 :B6651775: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-24 16:00:17.607680 :B665177D: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-24 16:00:17.608581 :B6651785: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-24 16:00:17.608820 :B665178D: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-24 16:00:17.609038 :B6651795: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-24 16:00:17.610849 :B665179D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-24 22:00:02.041539 :B681059D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-24 22:00:02.030 2025-07-24 22:00:02.041541 :B681059E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14024 pso_num=29 pso_serial#=175 2025-07-24 22:00:02.041672 :B68105A2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14024 osp_idx=27 osp_ver=14964 osp_pg=0 (spawn #419152) 2025-07-24 22:00:02.041672 :B68105A3: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-24 22:00:02.041673 :B68105A4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=19 time=2075177923 2025-07-24 22:00:02.041673 :B68105A5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-24 22:00:02.041673 :B68105A6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-24 22:00:02.041674 :B68105A7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-24 22:00:02.041674 :B68105A8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-24 22:00:02.041675 :B68105A9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-24 22:00:02.042239 :B68105B8: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-24 22:00:02.047186 :B68105D5: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-24 22:00:02.047221 :B68105D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-24 22:00:02.064312 :B681066B: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-24 22:00:02.064531 :B6810673: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-24 22:00:02.064702 :B681067B: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-24 22:00:02.064863 :B6810683: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-24 22:00:02.065018 :B681068B: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-24 22:00:02.065180 :B6810693: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-24 22:00:02.065411 :B681069B: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-24 22:00:02.065574 :B68106A3: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-24 22:00:02.065732 :B68106AB: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-24 22:00:02.065896 :B68106B3: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-24 22:00:02.066059 :B68106BB: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-24 22:00:02.066215 :B68106C3: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-24 22:00:02.066372 :B68106CB: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-24 22:00:02.066587 :B68106D3: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-24 22:00:02.066746 :B68106DB: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-24 22:00:02.066905 :B68106E3: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-24 22:00:02.067071 :B68106EB: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-24 22:00:02.067223 :B68106F3: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-24 22:00:02.067378 :B68106FB: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-24 22:00:02.067526 :B6810703: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-24 22:00:02.067676 :B681070B: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-24 22:00:02.067837 :B6810713: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-24 22:00:02.067990 :B681071B: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-24 22:00:02.068670 :B6810723: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-24 22:00:02.068883 :B681072B: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-24 22:00:02.095899 :B6810733: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-24 22:00:03.773453 :B6810BDC: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-24 22:00:03.793708 :B6810BFE: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-24 22:00:03.795640 :B6810C06:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-24 22:00:03.795867 :B6810C07:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-24 22:00:03.796845 :B6810C08: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-24 22:00:03.796939 :B6810C0B: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-24 22:00:03.797889 :B6810C1D: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-24 22:00:03.799399 :B6810C25: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-24 22:00:03.799671 :B6810C2D: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-24 22:00:03.811428*:B6810C3E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000033FA0230 estart=1753365602 eid=16778162 ctx=0x000000079F4EAD08 cwh=0x0000000031F91058 cpu=15625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-24 22:00:03.811428*:B6810C3F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000033FA0230 curCtx=0x00000000512C52F8 pubTabIdxCnt=0 2025-07-24 22:00:03.811428*:B6810C40:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000033FA0230 2025-07-24 22:00:03.811428*:B6810C41:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000033FA0230 2025-07-24 22:00:03.811428*:B6810C61:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000033FA0230 curCtx=0x00000000512C52F8 xsc->flg4=65792 flags=1 2025-07-24 22:00:03.811428*:B6810C62:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000033FA0230 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-24 22:00:03.858316*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-24 22:00:03.942521 :B6810CF4: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-24 22:00:05.833934 :B6810DE5: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-24 22:00:05.833947 :B6810DE6:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.001.0001d4d0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 14024, J003)