Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250902220007\orcl_j002_19980_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:34989M/63366M, Ph+PgF:39867M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 19980, image: ORACLE.EXE (J002) *** 2025-09-02 22:00:07.537 *** SESSION ID:(225.50657) 2025-09-02 22:00:07.537 *** 2025-09-02 22:00:07.537 Process diagnostic dump for ORACLE.EXE (J002), OS id=19980, pid: 28, proc_ser: 80, sid: 225, sess_ser: 50657 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19980 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13019, hash value=1379848838 Current Wait Stack: Not in wait; last wait ended 0.070775 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.070801 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=146 seq_num=147 snap_id=1 wait times: snap=0.000095 sec, exc=0.000095 sec, total=0.000095 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.492991 sec of elapsed time 1: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=145 seq_num=146 snap_id=1 wait times: snap=0.000315 sec, exc=0.000315 sec, total=0.000315 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2a31, block#=0x0, blocks=0x0 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.134912 sec of elapsed time 3: waited for 'log file sequential read' log#=0x2a31, block#=0x0, blocks=0x0 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000015 sec, exc=0.000015 sec, total=0.000015 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0xf800, blocks=0x800 wait_id=142 seq_num=143 snap_id=1 wait times: snap=0.000329 sec, exc=0.000329 sec, total=0.000329 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0xf000, blocks=0x800 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000335 sec, exc=0.000335 sec, total=0.000335 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0xe800, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000392 sec, exc=0.000392 sec, total=0.000392 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000010 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0xe000, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000349 sec, exc=0.000349 sec, total=0.000349 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0xd800, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000315 sec, exc=0.000315 sec, total=0.000315 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000138 sec of elapsed time 9: waited for 'log file sequential read' log#=0x0, block#=0xd000, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000344 sec, exc=0.000344 sec, total=0.000344 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.457918 sec of elapsed time Sampled Session History of session 225 serial 50657 --------------------------------------------------- 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:01 - 22:00:06] not in wait at each sample [1 sample, 22:00:00] idle wait at each sample [session created at: 22:00:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-02 22:00:07.537 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 19980, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-02 15:22:27.126607 :C8088A2B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:22:27.126841 :C8088A2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:22:27.126861 :C8088A2D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:22:27.127103 :C8088A2E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:22:27.127115 :C8088A2F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:22:27.129421 :C8088A30:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 15:52:30.742408 :C80AE245:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 15:52:30.729 2025-09-02 15:52:30.742410 :C80AE246:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20640 pso_num=28 pso_serial#=72 2025-09-02 15:52:30.742552 :C80AE247:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20640 osp_idx=26 osp_ver=287250 osp_pg=0 (spawn #551643) 2025-09-02 15:52:30.742553 :C80AE248:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-02 15:52:30.742554 :C80AE249:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=48 time=1214002033 2025-09-02 15:52:30.742554 :C80AE24A:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 15:52:30.742555 :C80AE24B:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 15:52:30.742555 :C80AE24C:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 15:52:30.742556 :C80AE24D:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 15:52:30.742556 :C80AE24E:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 15:52:30.743072 :C80AE24F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-02 15:52:30.743115 :C80AE255:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 15:52:30.747301 :C80AE26E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:52:30.747347 :C80AE26F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:52:30.747607 :C80AE270:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:52:30.747623 :C80AE271:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:52:30.747836 :C80AE272:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:52:30.747849 :C80AE273:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:52:30.748060 :C80AE274:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:52:30.748079 :C80AE275:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:52:30.748364 :C80AE276:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:52:30.748377 :C80AE277:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:52:30.748590 :C80AE278:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-02 15:52:30.748603 :C80AE279:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-02 15:52:30.751006 :C80AE27A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 16:00:09.012495 :C80B7C43:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 16:00:09.005 2025-09-02 16:00:09.012497 :C80B7C44:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23404 pso_num=28 pso_serial#=73 2025-09-02 16:00:09.012633 :C80B7C45:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23404 (legacy spawn) 2025-09-02 16:00:09.019416 :C80B7C46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 16:00:09.019591 :C80B7C4E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 16:00:09.020626 :C80B7C50:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 16:00:09.028917 :C80B7C53:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 16:00:09.020 2025-09-02 16:00:09.028919 :C80B7C54:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16028 pso_num=28 pso_serial#=74 2025-09-02 16:00:09.029052 :C80B7C55:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16028 (legacy spawn) 2025-09-02 16:00:09.036369 :C80B7C56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 16:00:09.036537 :C80B7C5E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 16:00:09.037365 :C80B7C60:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 16:00:12.085737 :C80B7D54:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 16:00:12.072 2025-09-02 16:00:12.085738 :C80B7D55:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21088 pso_num=28 pso_serial#=75 2025-09-02 16:00:12.085875 :C80B7D56:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21088 (legacy spawn) 2025-09-02 16:00:12.092419 :C80B7D57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 16:00:12.092591 :C80B7D60:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 16:00:12.093387 :C80B7D61:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 16:00:12.102103 :C80B7D64:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 16:00:12.088 2025-09-02 16:00:12.102105 :C80B7D65:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22332 pso_num=28 pso_serial#=76 2025-09-02 16:00:12.102289 :C80B7D66:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22332 (legacy spawn) 2025-09-02 16:00:12.108864 :C80B7D67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 16:00:12.109036 :C80B7D70:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 16:00:12.109844 :C80B7D71:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 16:00:23.732527 :C80B810C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 16:00:23.722 2025-09-02 16:00:23.732530 :C80B810D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22036 pso_num=28 pso_serial#=77 2025-09-02 16:00:23.732679 :C80B810E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22036 (legacy spawn) 2025-09-02 16:00:23.739585 :C80B810F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 16:00:23.739768 :C80B8114:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 16:00:23.740743 :C80B8119:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 16:00:27.186364 :C80B8242:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 16:00:27.173 2025-09-02 16:00:27.186366 :C80B8243:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21776 pso_num=28 pso_serial#=78 2025-09-02 16:00:27.186508 :C80B8244:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21776 (legacy spawn) 2025-09-02 16:00:27.192997 :C80B8245:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 16:00:27.193168 :C80B824A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 16:00:27.193974 :C80B824F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 22:00:00.047097 :C827A6F7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 22:00:00.038 2025-09-02 22:00:00.047099 :C827A6F8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21176 pso_num=28 pso_serial#=79 2025-09-02 22:00:00.047237 :C827A6F9:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21176 osp_idx=26 osp_ver=287257 osp_pg=0 (spawn #552491) 2025-09-02 22:00:00.047238 :C827A6FA:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-02 22:00:00.047238 :C827A6FB:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=49 time=1236051346 2025-09-02 22:00:00.047239 :C827A6FC:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 22:00:00.047239 :C827A6FD:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 22:00:00.047239 :C827A6FE:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 22:00:00.047240 :C827A6FF:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 22:00:00.047240 :C827A700:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 22:00:00.047733 :C827A701:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.047777 :C827A708:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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-09-02 22:00:00.047858 :C827A71C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.051752 :C827A72B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.052913 :C827A733:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.052938 :C827A736:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.052980 :C827A741:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-02 22:00:00.054841 :C827A74C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.054868 :C827A74F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.054903 :C827A75A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-02 22:00:00.055352 :C827A765:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.057785 :C827A76D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.063133 :C827A775:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.068579 :C827A77D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.069990 :C827A785:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.071027 :C827A78D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.073667 :C827A795:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.074995 :C827A79D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.077923 :C827A7A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.081437 :C827A7AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.081778 :C827A7BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.081830 :C827A7BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-02 22:00:00.081847 :C827A7C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-02 22:00:00.083888 :C827A7C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.083933 :C827A7C9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-02 22:00:00.083946 :C827A7CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-02 22:00:00.085921 :C827A7D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.085967 :C827A7D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-02 22:00:00.085979 :C827A7D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-02 22:00:00.086149 :C827A7DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.086164 :C827A7DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-02 22:00:00.086203 :C827A7E8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-02 22:00:00.087799 :C827A7F3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-02 22:00:01.153166 :C827A84E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-02 22:00:01.147 2025-09-02 22:00:01.153169 :C827A84F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19980 pso_num=28 pso_serial#=80 2025-09-02 22:00:01.153342 :C827A852:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=19980 osp_idx=26 osp_ver=287258 osp_pg=0 (spawn #552492) 2025-09-02 22:00:01.153343 :C827A853:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-02 22:00:01.153343 :C827A854:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=50 time=1236052455 2025-09-02 22:00:01.153344 :C827A855:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-02 22:00:01.153344 :C827A856:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-02 22:00:01.153345 :C827A857:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-02 22:00:01.153345 :C827A858:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-02 22:00:01.153345 :C827A859:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-02 22:00:01.153932 :C827A85E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.159061 :C827A88B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.159105 :C827A88E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-09-02 22:00:01.176077 :C827AA8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.176302 :C827AA96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.176472 :C827AA9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.176733 :C827AAA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.176901 :C827AAAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.177061 :C827AAD0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.177221 :C827AAE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.177383 :C827AAE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.177545 :C827AAF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.177704 :C827AAF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.178108 :C827AB01:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.178347 :C827AB09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.178574 :C827AB11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.178839 :C827AB19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179011 :C827AB21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179181 :C827AB29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179341 :C827AB31:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179497 :C827AB3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179645 :C827AB55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179807 :C827AB5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.179970 :C827AB65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.180118 :C827AB6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.180263 :C827AB75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.180408 :C827AB7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.180568 :C827AB85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.180722 :C827AB8D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.180871 :C827AB95:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:01.207582 :C827AB9D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.941430 :C827AE96:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.961625 :C827AEB8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.963549 :C827AEC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-09-02 22:00:02.963743 :C827AEC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-09-02 22:00:02.964663 :C827AEC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.964770 :C827AEC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.965703 :C827AED7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.967116 :C827AEDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:02.967396 :C827AEE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-02 22:00:03.017114*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-02 22:00:03.079616*:C827AFA4:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000005BBF0230 estart=1756821602 eid=16778488 ctx=0x000000079F4EAD08 cwh=0x000000002B490518 cpu=15625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-09-02 22:00:03.079616*:C827AFA5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000005BBF0230 curCtx=0x000000005BEC84C0 pubTabIdxCnt=0 2025-09-02 22:00:03.079616*:C827AFA6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000005BBF0230 2025-09-02 22:00:03.079616*:C827AFA7:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000005BBF0230 2025-09-02 22:00:03.079616*:C827AFC9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000005BBF0230 curCtx=0x000000005BEC84C0 xsc->flg4=65792 flags=1 2025-09-02 22:00:03.079616*:C827AFCA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000005BBF0230 2025-09-02 22:00:03.101578 :C827AFCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:05.000659 :C827B11F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:05.000674 :C827B120:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0009.01a.0001dc9c 2025-09-02 22:00:07.428138 :C827B26B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:07.429596 :C827B274:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:07.440288 :C827B281:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-02 22:00:07.469178 :C827B298:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 19980, J002)