Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250724220008\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:36064M/63366M, Ph+PgF:41826M/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:08.930 *** SESSION ID:(233.30035) 2025-07-24 22:00:08.930 *** 2025-07-24 22:00:08.930 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 1.193046 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.193072 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000196 sec, exc=0.000196 sec, total=0.000196 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2949, block#=0x0, blocks=0x0 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.053430 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2949, block#=0x0, blocks=0x0 wait_id=129 seq_num=130 snap_id=1 wait times: snap=0.000017 sec, exc=0.000017 sec, total=0.000017 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.412526 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x6800, blocks=0x800 wait_id=128 seq_num=129 snap_id=1 wait times: snap=0.000332 sec, exc=0.000332 sec, total=0.000332 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000015 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x6000, blocks=0x800 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.006959 sec, exc=0.006959 sec, total=0.006959 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000171 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x5800, blocks=0x800 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.004199 sec, exc=0.004199 sec, total=0.004199 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.064918 sec of elapsed time 6: waited for 'log file sequential read' log#=0x2949, block#=0x800, blocks=0x800 wait_id=125 seq_num=126 snap_id=1 wait times: snap=0.000208 sec, exc=0.000208 sec, total=0.000208 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002663 sec of elapsed time 7: waited for 'log file sequential read' log#=0x2949, block#=0x0, blocks=0x0 wait_id=124 seq_num=125 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000143 sec of elapsed time 8: waited for 'log file sequential read' log#=0x2949, block#=0x2, blocks=0x7fe wait_id=123 seq_num=124 snap_id=1 wait times: snap=0.000197 sec, exc=0.000197 sec, total=0.000197 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000148 sec of elapsed time 9: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=122 seq_num=123 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 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 --------------------------------------------------- [6 samples, 22:00:03 - 22:00:08] 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:08.930 ------------------------------------------------------------------------------- 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: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 2025-07-24 22:00:08.202129*:B6810FA8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DCBBB08 estart=1753365607 eid=16777732 ctx=0x000000077F58CBF8 cwh=0x000000002B560428 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-07-24 22:00:08.202129*:B6810FA9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DCBBB08 curCtx=0x00000000511CE7B8 pubTabIdxCnt=0 2025-07-24 22:00:08.202129*:B6810FAA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DCBBB08 2025-07-24 22:00:08.202129*:B6810FAB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DCBBB08 2025-07-24 22:00:08.202129*:B6810FAC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DCBBB08 curCtx=0x00000000511CE7B8 xsc->flg4=65792 flags=1 2025-07-24 22:00:08.202129*:B6810FAD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DCBBB08 2025-07-24 22:00:08.208543 :B6810FAE: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:08.213897 :B6810FB6: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:08.228619 :B6810FBE: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:08.262003 :B6810FC6: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 14024, J003)