Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250828220005\orcl_j003_21864_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:35147M/63366M, Ph+PgF:40176M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 21864, image: ORACLE.EXE (J003) *** 2025-08-28 22:00:06.062 *** SESSION ID:(233.33289) 2025-08-28 22:00:06.062 *** 2025-08-28 22:00:06.062 Process diagnostic dump for ORACLE.EXE (J003), OS id=21864, pid: 29, proc_ser: 254, sid: 233, sess_ser: 33289 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21864 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12988, hash value=396445804 Current Wait Stack: Not in wait; last wait ended 2.176564 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.176589 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000172 sec, exc=0.000172 sec, total=0.000172 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.067172 sec of elapsed time 1: 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.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.765647 sec of elapsed time 2: 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.027147 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.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.013249 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000076 sec, exc=0.000076 sec, total=0.000076 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.050098 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.011956 sec, exc=0.011956 sec, total=0.011956 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008042 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000141 sec, exc=0.000141 sec, total=0.000141 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.265123 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.004796 sec, exc=0.004796 sec, total=0.004796 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000166 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000121 sec, exc=0.000121 sec, total=0.000121 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008010 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.000000 sec, exc=0.000000 sec, total=0.000000 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000056 sec of elapsed time Sampled Session History of session 233 serial 33289 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [4 samples, 22:00:02 - 22:00:05] not in wait at each sample [1 sample, 22:00:01] 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-08-28 22:00:06.062 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 21864, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-28 00:04:45.111853 :C5885ACE: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-08-28 00:04:45.112746 :C5885AD0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:04:48.220766 :C5885BBC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:04:48.207 2025-08-28 00:04:48.220769 :C5885BBD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23184 pso_num=29 pso_serial#=239 2025-08-28 00:04:48.220904 :C5885BBE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23184 (legacy spawn) 2025-08-28 00:04:48.227506 :C5885BBF: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-08-28 00:04:48.227680 :C5885BC4: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-08-28 00:04:48.228477 :C5885BC9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:04:48.237155 :C5885BCC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:04:48.223 2025-08-28 00:04:48.237157 :C5885BCD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22368 pso_num=29 pso_serial#=240 2025-08-28 00:04:48.237291 :C5885BCE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22368 (legacy spawn) 2025-08-28 00:04:48.243847 :C5885BCF: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-08-28 00:04:48.244011 :C5885BD8: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-08-28 00:04:48.244812 :C5885BD9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:04:48.809563 :C5885BEC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:04:48.801 2025-08-28 00:04:48.809565 :C5885BED:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18716 pso_num=29 pso_serial#=241 2025-08-28 00:04:48.809709 :C5885BEE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18716 (legacy spawn) 2025-08-28 00:04:48.816651 :C5885BEF: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-08-28 00:04:48.816855 :C5885BF4: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-08-28 00:04:48.817850 :C5885BF9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:24.487198 :C588678C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:24.474 2025-08-28 00:05:24.487200 :C588678D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20168 pso_num=29 pso_serial#=242 2025-08-28 00:05:24.487335 :C588678E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20168 (legacy spawn) 2025-08-28 00:05:24.493918 :C588678F: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-08-28 00:05:24.494088 :C5886794: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-08-28 00:05:24.494880 :C5886799:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:24.503577 :C588679C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:24.489 2025-08-28 00:05:24.503579 :C588679D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22852 pso_num=29 pso_serial#=243 2025-08-28 00:05:24.503710 :C588679E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22852 (legacy spawn) 2025-08-28 00:05:24.510183 :C588679F: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-08-28 00:05:24.510345 :C58867A7: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-08-28 00:05:24.511115 :C58867A9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:50.408415 :C5887135:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:50.403 2025-08-28 00:05:50.408417 :C5887136:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13028 pso_num=29 pso_serial#=244 2025-08-28 00:05:50.408557 :C5887137:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=13028 osp_idx=27 osp_ver=16563 osp_pg=0 (spawn #532781) 2025-08-28 00:05:50.408558 :C5887138:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 00:05:50.408558 :C5887139:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=243 time=725201705 2025-08-28 00:05:50.408559 :C588713A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 00:05:50.408559 :C588713B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 00:05:50.408559 :C588713C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 00:05:50.408560 :C588713D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 00:05:50.408560 :C588713E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 00:05:50.409066 :C588713F: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-08-28 00:05:50.409111 :C5887145: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-08-28 00:05:50.430127 :C58871B2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:51.049937 :C58871CC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:51.042 2025-08-28 00:05:51.049939 :C58871CD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22612 pso_num=29 pso_serial#=245 2025-08-28 00:05:51.050077 :C58871CE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22612 (legacy spawn) 2025-08-28 00:05:51.056580 :C58871CF: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-08-28 00:05:51.056755 :C58871D4: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-08-28 00:05:51.057622 :C58871D9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:05:51.832296 :C58871E8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:05:51.823 2025-08-28 00:05:51.832297 :C58871E9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22784 pso_num=29 pso_serial#=246 2025-08-28 00:05:51.832433 :C58871EA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22784 (legacy spawn) 2025-08-28 00:05:51.838895 :C58871EB: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-08-28 00:05:51.839056 :C58871F4: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-08-28 00:05:51.839851 :C58871F5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:21:46.735832 :C589AF37:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:21:46.733 2025-08-28 00:21:46.735834 :C589AF38:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23176 pso_num=29 pso_serial#=247 2025-08-28 00:21:46.735969 :C589AF39:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23176 (legacy spawn) 2025-08-28 00:21:46.753330 :C589AF47: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-08-28 00:21:46.753491 :C589AF50: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-08-28 00:21:46.754283 :C589AF51:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 00:35:54.307676 :C58AC5AC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 00:35:54.295 2025-08-28 00:35:54.307678 :C58AC5AD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23368 pso_num=29 pso_serial#=248 2025-08-28 00:35:54.307812 :C58AC5AE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23368 osp_idx=27 osp_ver=16567 osp_pg=0 (spawn #532849) 2025-08-28 00:35:54.307813 :C58AC5AF:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 00:35:54.307814 :C58AC5B0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=244 time=727005612 2025-08-28 00:35:54.307814 :C58AC5B1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 00:35:54.307814 :C58AC5B2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 00:35:54.307815 :C58AC5B3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 00:35:54.307815 :C58AC5B4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 00:35:54.307816 :C58AC5B5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 00:35:54.308329 :C58AC5B6: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-08-28 00:35:54.308369 :C58AC5BC: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-08-28 00:35:54.312552 :C58AC5D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.312593 :C58AC5D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.312856 :C58AC5D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.312872 :C58AC5D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313084 :C58AC5D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313099 :C58AC5DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313305 :C58AC5DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313318 :C58AC5DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313519 :C58AC5DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313531 :C58AC5DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.313805 :C58AC5DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-28 00:35:54.313818 :C58AC5E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-28 00:35:54.316114 :C58AC5E1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:16.671806 :C58CA394:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:16.665 2025-08-28 01:00:16.671808 :C58CA395:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19888 pso_num=29 pso_serial#=249 2025-08-28 01:00:16.671983 :C58CA396:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19888 (legacy spawn) 2025-08-28 01:00:16.678485 :C58CA397: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-08-28 01:00:16.678653 :C58CA3A0: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-08-28 01:00:16.679522 :C58CA3A1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:26.959215 :C58CA6AE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:26.950 2025-08-28 01:00:26.959217 :C58CA6AF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17404 pso_num=29 pso_serial#=250 2025-08-28 01:00:26.959358 :C58CA6B0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17404 (legacy spawn) 2025-08-28 01:00:26.966244 :C58CA6B1: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-08-28 01:00:26.966424 :C58CA6B6: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-08-28 01:00:26.967437 :C58CA6BB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:28.537743 :C58CA83A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:28.530 2025-08-28 01:00:28.537745 :C58CA83B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21988 pso_num=29 pso_serial#=251 2025-08-28 01:00:28.537882 :C58CA83C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21988 (legacy spawn) 2025-08-28 01:00:28.544344 :C58CA83D: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-08-28 01:00:28.544510 :C58CA846: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-08-28 01:00:28.545336 :C58CA847:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:32.086865 :C58CA92D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:32.072 2025-08-28 01:00:32.086867 :C58CA92E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22724 pso_num=29 pso_serial#=252 2025-08-28 01:00:32.087002 :C58CA92F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22724 (legacy spawn) 2025-08-28 01:00:32.093572 :C58CA93D: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-08-28 01:00:32.093738 :C58CA942: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-08-28 01:00:32.094527 :C58CA947:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 01:00:32.103266 :C58CA94A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 01:00:32.088 2025-08-28 01:00:32.103268 :C58CA94B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23456 pso_num=29 pso_serial#=253 2025-08-28 01:00:32.103427 :C58CA94C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=23456 (legacy spawn) 2025-08-28 01:00:32.110117 :C58CA94D: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-08-28 01:00:32.110282 :C58CA955: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-08-28 01:00:32.111077 :C58CA957:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-28 22:00:02.047713 :C5EF2938:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-28 22:00:02.041 2025-08-28 22:00:02.047715 :C5EF2939:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21864 pso_num=29 pso_serial#=254 2025-08-28 22:00:02.047860 :C5EF293A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21864 osp_idx=27 osp_ver=16573 osp_pg=0 (spawn #535817) 2025-08-28 22:00:02.047861 :C5EF293B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-28 22:00:02.047862 :C5EF293C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=245 time=804053346 2025-08-28 22:00:02.047863 :C5EF293D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-28 22:00:02.047863 :C5EF293E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-28 22:00:02.047864 :C5EF293F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-28 22:00:02.047864 :C5EF2940:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-28 22:00:02.047865 :C5EF2941:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-28 22:00:02.048439 :C5EF294B: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-08-28 22:00:02.053573 :C5EF2972: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-08-28 22:00:02.053611 :C5EF2977:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-28 22:00:02.071184 :C5EF2B58: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-08-28 22:00:02.071387 :C5EF2B60: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-08-28 22:00:02.071555 :C5EF2B68: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-08-28 22:00:02.071722 :C5EF2B70: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-08-28 22:00:02.071901 :C5EF2B78: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-08-28 22:00:02.072071 :C5EF2B80: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-08-28 22:00:02.072230 :C5EF2B88: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-08-28 22:00:02.072400 :C5EF2B90: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-08-28 22:00:02.072562 :C5EF2B98: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-08-28 22:00:02.072727 :C5EF2BA0: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-08-28 22:00:02.072904 :C5EF2BA8: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-08-28 22:00:02.073125 :C5EF2BB0: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-08-28 22:00:02.073335 :C5EF2BB8: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-08-28 22:00:02.073513 :C5EF2BC0: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-08-28 22:00:02.073673 :C5EF2BC8: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-08-28 22:00:02.073838 :C5EF2BD0: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-08-28 22:00:02.074192 :C5EF2BD8: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-08-28 22:00:02.074374 :C5EF2BE0: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-08-28 22:00:02.074537 :C5EF2BE8: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-08-28 22:00:02.074819 :C5EF2BF0: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-08-28 22:00:02.075097 :C5EF2BF8: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-08-28 22:00:02.075338 :C5EF2C00: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-08-28 22:00:02.075975 :C5EF2C08: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-08-28 22:00:02.103978 :C5EF2C10: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-08-28 22:00:03.807129 :C5EF2F23: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-08-28 22:00:03.829638 :C5EF2F2B: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-08-28 22:00:03.831870 :C5EF2F33:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-28 22:00:03.832118 :C5EF2F34:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-28 22:00:03.833150 :C5EF2F35: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-08-28 22:00:03.833349 :C5EF2F3D: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-08-28 22:00:03.834346 :C5EF2F45: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-08-28 22:00:03.836115 :C5EF2F4D: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-08-28 22:00:03.836482 :C5EF2F55: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-08-28 22:00:03.848070*:C5EF2F5E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000005AF1D688 estart=1756389602 eid=16778437 ctx=0x000000079F4EAD08 cwh=0x000000005ADCBB10 cpu=15625 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-08-28 22:00:03.848070*:C5EF2F61:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000005AF1D688 curCtx=0x000000005AF1C958 pubTabIdxCnt=0 2025-08-28 22:00:03.848070*:C5EF2F63:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000005AF1D688 2025-08-28 22:00:03.848070*:C5EF2F65:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000005AF1D688 2025-08-28 22:00:03.848070*:C5EF2F69:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000005AF1D688 curCtx=0x000000005AF1C958 xsc->flg4=65792 flags=1 2025-08-28 22:00:03.848070*:C5EF2F6A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000005AF1D688 2025-08-28 22:00:03.894947*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-28 22:00:03.894947*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-28 22:00:03.894947*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-28 22:00:03.910569*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-28 22:00:03.986999 :C5EF2F71: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-08-28 22:00:05.897611 :C5EF315C: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-08-28 22:00:05.897622 :C5EF315D:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.000.0000cb2e ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 21864, J003)