Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250510100016\orcl_j000_12784_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:37521M/63366M, Ph+PgF:44933M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12784, image: ORACLE.EXE (J000) *** 2025-05-10 10:00:16.844 *** SESSION ID:(177.11849) 2025-05-10 10:00:16.844 *** 2025-05-10 10:00:16.844 Process diagnostic dump for ORACLE.EXE (J000), OS id=12784, pid: 22, proc_ser: 31, sid: 177, sess_ser: 11849 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12784 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12405, hash value=2602713580 Current Wait Stack: Not in wait; last wait ended 0.731437 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.731463 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=41 seq_num=42 snap_id=1 wait times: snap=0.000160 sec, exc=0.000160 sec, total=0.000160 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 3.591391 sec of elapsed time 1: 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.000157 sec, exc=0.000157 sec, total=0.000157 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.044658 sec of elapsed time 2: 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.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.755184 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.024631 sec of elapsed time 4: 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.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012835 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000113 sec, exc=0.000113 sec, total=0.000113 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049160 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.007296 sec, exc=0.007296 sec, total=0.007296 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007748 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.000117 sec, exc=0.000117 sec, total=0.000117 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.260088 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.004141 sec, exc=0.004141 sec, total=0.004141 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000190 sec of elapsed time 9: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000126 sec, exc=0.000126 sec, total=0.000126 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008114 sec of elapsed time Sampled Session History of session 177 serial 11849 --------------------------------------------------- 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, 10:00:11 - 10:00:16] not in wait at each sample [1 sample, 10:00:10] idle wait at each sample [session created at: 10:00:10] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-10 10:00:16.844 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12784, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-10 09:58:36.410484 :952B9EC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-10 09:58:36.410840 :952B9EC7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:58:36.410876 :952B9ECA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-10 09:58:36.411705 :952B9ED0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:58:36.411855 :952B9ED9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-10 09:59:06.536364 :952BA879:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-10 09:59:22.286038 :952BAD5C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-10 09:59:22.278 2025-05-10 09:59:22.286040 :952BAD5D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13216 pso_num=22 pso_serial#=26 2025-05-10 09:59:22.286170 :952BAD5E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13216 (legacy spawn) 2025-05-10 09:59:22.292524 :952BAD5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:59:22.292557 :952BAD62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:59:22.292708 :952BAD66:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-10 09:59:22.293483 :952BAD76:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-10 09:59:31.475659 :952BB050:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-10 09:59:31.469 2025-05-10 09:59:31.475661 :952BB051:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9552 pso_num=22 pso_serial#=27 2025-05-10 09:59:31.475795 :952BB052:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9552 osp_idx=0 osp_ver=174192 osp_pg=0 (spawn #167370) 2025-05-10 09:59:31.475796 :952BB053:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 09:59:31.475797 :952BB054:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=23 time=4141948640 2025-05-10 09:59:31.475797 :952BB055:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 09:59:31.475797 :952BB056:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 09:59:31.475798 :952BB057:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 09:59:31.475798 :952BB058:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 09:59:31.475798 :952BB059:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 09:59:31.476346 :952BB05A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 09:59:31.477011 :952BB06F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:59:31.482441 :952BB08B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:59:31.483394 :952BB093:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 09:59:31.483519 :952BB09C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-10 09:59:36.480191 :952BB247:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-10 10:00:06.602686 :952BBC19:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-10 10:00:08.374415 :952BBCA0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-10 10:00:08.364 2025-05-10 10:00:08.374417 :952BBCA1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10380 pso_num=22 pso_serial#=28 2025-05-10 10:00:08.374552 :952BBCA2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10380 (legacy spawn) 2025-05-10 10:00:08.381025 :952BBCA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:08.381200 :952BBCA8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-05-10 10:00:08.381985 :952BBCAD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-10 10:00:10.635600 :952BBD90:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-10 10:00:10.630 2025-05-10 10:00:10.635602 :952BBD91:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6516 pso_num=22 pso_serial#=29 2025-05-10 10:00:10.635738 :952BBD92:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6516 osp_idx=0 osp_ver=174194 osp_pg=0 (spawn #167372) 2025-05-10 10:00:10.635739 :952BBD93:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 10:00:10.635740 :952BBD94:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=24 time=4141987796 2025-05-10 10:00:10.635740 :952BBD95:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 10:00:10.635741 :952BBD96:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 10:00:10.635741 :952BBD97:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 10:00:10.635741 :952BBD98:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 10:00:10.635742 :952BBD99:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 10:00:10.636231 :952BBD9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.636275 :952BBDA0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-10 10:00:10.640432 :952BBDB3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-05-10 10:00:10.640474 :952BBDB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-05-10 10:00:10.640732 :952BBDB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-05-10 10:00:10.640749 :952BBDB6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-05-10 10:00:10.640963 :952BBDB7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-05-10 10:00:10.640976 :952BBDB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-05-10 10:00:10.641177 :952BBDBB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-05-10 10:00:10.641189 :952BBDC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-05-10 10:00:10.641491 :952BBDC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-05-10 10:00:10.641504 :952BBDC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-05-10 10:00:10.641713 :952BBDCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-05-10 10:00:10.641726 :952BBDCF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-05-10 10:00:10.644272 :952BBDE4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-10 10:00:10.654030 :952BBE1B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-10 10:00:10.646 2025-05-10 10:00:10.654031 :952BBE1C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12360 pso_num=22 pso_serial#=30 2025-05-10 10:00:10.654177 :952BBE1D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12360 osp_idx=0 osp_ver=174195 osp_pg=0 (spawn #167375) 2025-05-10 10:00:10.654179 :952BBE1E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 10:00:10.654180 :952BBE1F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=25 time=4141987812 2025-05-10 10:00:10.654180 :952BBE20:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 10:00:10.654181 :952BBE21:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 10:00:10.654181 :952BBE22:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 10:00:10.654182 :952BBE23:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 10:00:10.654182 :952BBE24:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 10:00:10.654794 :952BBE25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.654845 :952BBE2B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-05-10 10:00:10.663195 :952BBE4D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-10 10:00:10.761687 :952BBF8D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-10 10:00:10.755 2025-05-10 10:00:10.761689 :952BBF8E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12784 pso_num=22 pso_serial#=31 2025-05-10 10:00:10.761846 :952BBF8F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12784 osp_idx=0 osp_ver=174196 osp_pg=0 (spawn #167376) 2025-05-10 10:00:10.761847 :952BBF90:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-10 10:00:10.761847 :952BBF91:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=26 time=4141987921 2025-05-10 10:00:10.761848 :952BBF92:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-10 10:00:10.761848 :952BBF93:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-10 10:00:10.761849 :952BBF94:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-10 10:00:10.761849 :952BBF95:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-10 10:00:10.761850 :952BBF96:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-10 10:00:10.762445 :952BBF97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.767958 :952BBFB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.768000 :952BBFB9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-05-10 10:00:10.785124 :952BBFF9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.785332 :952BC001:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.785502 :952BC009:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.785702 :952BC011:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.785935 :952BC019:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.786097 :952BC021:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.786261 :952BC029:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.786412 :952BC031:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.786559 :952BC039:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.786713 :952BC041:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.786861 :952BC049:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787008 :952BC051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787154 :952BC059:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787306 :952BC061:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787451 :952BC069:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787599 :952BC071:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787747 :952BC079:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.787906 :952BC081:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.788051 :952BC089:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.788201 :952BC091:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.788351 :952BC099:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.788497 :952BC0A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.788641 :952BC0A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.788791 :952BC0B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.789279 :952BC0B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.789590 :952BC0C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.789759 :952BC0CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.789922 :952BC0D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.790080 :952BC0DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.790229 :952BC0E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.790378 :952BC0ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.790526 :952BC0F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.790667 :952BC0FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.790835 :952BC107:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.791001 :952BC10F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.791152 :952BC117:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.791504 :952BC11F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.791750 :952BC127:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.791907 :952BC12F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792062 :952BC137:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792215 :952BC13F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792364 :952BC147:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792508 :952BC14F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792654 :952BC157:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792800 :952BC15F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.792943 :952BC167:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.793085 :952BC16F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.793230 :952BC177:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.793436 :952BC17F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.793597 :952BC187:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.793744 :952BC18F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.793900 :952BC197:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.794489 :952BC1A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.794651 :952BC1A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.794808 :952BC1B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.794967 :952BC1B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:10.817589 :952BC1C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.129931*:952BC333:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B143AD0 estart=1746842412 eid=18237488 ctx=0x0000000777968158 cwh=0x000000000FD00AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-10 10:00:12.129931*:952BC334:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B143AD0 curCtx=0x000000003120C4B8 pubTabIdxCnt=0 2025-05-10 10:00:12.129931*:952BC335:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B143AD0 2025-05-10 10:00:12.129931*:952BC336:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B143AD0 2025-05-10 10:00:12.129931*:952BC337:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B17AAF8 estart=1746842410 eid=16777312 ctx=0x000000077B54EAC8 cwh=0x000000000FD00608 cpu=1125000 buffg=63075 interb=204800 r=25 rb=204800 w=0 wb=0 2025-05-10 10:00:12.129931*:952BC338:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B17AAF8 curCtx=0x0000000031021240 pubTabIdxCnt=0 2025-05-10 10:00:12.129931*:952BC339:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B17AAF8 2025-05-10 10:00:12.129931*:952BC33A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B17AAF8 2025-05-10 10:00:12.129931*:952BC33B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B143AD0 curCtx=0x000000003120C4B8 xsc->flg4=65792 flags=1 2025-05-10 10:00:12.129931*:952BC33C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B143AD0 2025-05-10 10:00:12.452988*:952BC3F7:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B17AAF8 curCtx=0x0000000031021240 xsc->flg4=65792 flags=2 2025-05-10 10:00:12.452988*:952BC3F8:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B17AAF8 2025-05-10 10:00:12.463729 :952BC3F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.484203 :952BC410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.486208 :952BC418:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-10 10:00:12.486402 :952BC419:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-10 10:00:12.487222 :952BC41A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.487312 :952BC41D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.488126 :952BC42F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.489670 :952BC437:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.489977 :952BC43F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-10 10:00:12.531120*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-10 10:00:12.603537 :952BC447:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:14.509534 :952BC4D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:14.509547 :952BC4D9:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x000a.020.001684e3 2025-05-10 10:00:16.116096 :952BC669:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:16.117726 :952BC671:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:16.128478 :952BC679:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-10 10:00:16.140447*:952BC681:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031226798 estart=1746842415 eid=16777408 ctx=0x000000079F4B9050 cwh=0x000000000FD008D8 cpu=15625 buffg=4108 interb=0 r=0 rb=0 w=0 wb=0 2025-05-10 10:00:16.140447*:952BC682:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031226798 curCtx=0x0000000031241088 pubTabIdxCnt=0 2025-05-10 10:00:16.140447*:952BC683:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031226798 2025-05-10 10:00:16.140447*:952BC684:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031226798 2025-05-10 10:00:16.140447*:952BC685:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031226798 curCtx=0x0000000031241088 xsc->flg4=65792 flags=1 2025-05-10 10:00:16.140447*:952BC686:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031226798 2025-05-10 10:00:16.152159 :952BC687:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] 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 22 (osid: 12784, J000)