Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250808220005\orcl_j003_14400_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:35644M/63366M, Ph+PgF:41141M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14400, image: ORACLE.EXE (J003) *** 2025-08-08 22:00:06.103 *** SESSION ID:(233.31361) 2025-08-08 22:00:06.103 *** 2025-08-08 22:00:06.103 Process diagnostic dump for ORACLE.EXE (J003), OS id=14400, pid: 29, proc_ser: 62, sid: 233, sess_ser: 31361 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14400 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12880, hash value=109214704 Current Wait Stack: Not in wait; last wait ended 2.263636 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.263662 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.000151 sec, exc=0.000151 sec, total=0.000151 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.063035 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.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.760829 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.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.027182 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.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012977 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.000097 sec, exc=0.000097 sec, total=0.000097 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.050169 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.006010 sec, exc=0.006010 sec, total=0.006010 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007787 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.000103 sec, exc=0.000103 sec, total=0.000103 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261827 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.003133 sec, exc=0.003133 sec, total=0.003133 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000167 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.000114 sec, exc=0.000114 sec, total=0.000114 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007872 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.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000055 sec of elapsed time Sampled Session History of session 233 serial 31361 --------------------------------------------------- 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-08 22:00:06.103 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14400, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-08 00:06:14.379950 :BCC32BB4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17492 pso_num=29 pso_serial#=52 2025-08-08 00:06:14.380089 :BCC32BB5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17492 (legacy spawn) 2025-08-08 00:06:14.386512 :BCC32BB6: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-08 00:06:14.386684 :BCC32BBF: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-08 00:06:14.387542 :BCC32BC0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:03.011810 :BCC755E3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:02.997 2025-08-08 01:00:03.011812 :BCC755E4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10604 pso_num=29 pso_serial#=53 2025-08-08 01:00:03.011956 :BCC755E5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10604 (legacy spawn) 2025-08-08 01:00:03.018548 :BCC755F0: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-08 01:00:03.018709 :BCC755F9: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-08 01:00:03.019511 :BCC755FA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:03.028375 :BCC755FD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:03.028 2025-08-08 01:00:03.028378 :BCC755FE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19676 pso_num=29 pso_serial#=54 2025-08-08 01:00:03.028512 :BCC755FF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19676 (legacy spawn) 2025-08-08 01:00:03.035286 :BCC75600: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-08 01:00:03.035454 :BCC75607: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-08 01:00:03.036267 :BCC7560A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:03.949508 :BCC75646:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:03.944 2025-08-08 01:00:03.949510 :BCC75647:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19872 pso_num=29 pso_serial#=55 2025-08-08 01:00:03.949643 :BCC75648:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19872 osp_idx=27 osp_ver=15609 osp_pg=0 (spawn #466234) 2025-08-08 01:00:03.949644 :BCC75649: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-08 01:00:03.949644 :BCC7564A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=174 time=3295473251 2025-08-08 01:00:03.949644 :BCC7564B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 01:00:03.949645 :BCC7564C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 01:00:03.949645 :BCC7564D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 01:00:03.949645 :BCC7564E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 01:00:03.949646 :BCC7564F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 01:00:03.950175 :BCC75650: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-08 01:00:03.950217 :BCC75656: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-08 01:00:03.954613 :BCC7566A: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-08 01:00:03.978040 :BCC75672: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-08 01:00:03.992379 :BCC7567A: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-08 01:00:04.024858 :BCC75682: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-08 01:00:04.028614 :BCC7568A: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-08 01:00:04.032342 :BCC75692: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-08 01:00:04.036604 :BCC7569A: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-08 01:00:04.037702 :BCC756A2: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-08 01:00:04.056142 :BCC756AA: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-08 01:00:04.056159 :BCC756AB:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0005.007.0000c7f3 2025-08-08 01:00:04.056644 :BCC756B3:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 1, nex 2 2025-08-08 01:00:04.056651 :BCC756B4:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081519a where: ktspbwh1: ktspfsrch 2025-08-08 01:00:04.059336 :BCC756B5: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-08 01:00:04.059349 :BCC756B6:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0005.01b.0000c7fa 2025-08-08 01:00:04.059630 :BCC756BE:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 2, nex 3 2025-08-08 01:00:04.059637 :BCC756BF:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081519d where: ktspbwh1: ktspfsrch 2025-08-08 01:00:04.079875 :BCC756C0: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-08 01:00:04.079888 :BCC756C1:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0005.018.0000c7ef 2025-08-08 01:00:04.080165 :BCC756C9:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 3, nex 4 2025-08-08 01:00:04.080171 :BCC756CA:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008150bb where: ktspbwh1: ktspfsrch 2025-08-08 01:00:04.083930 :BCC756CB:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 4, nex 5 2025-08-08 01:00:04.083937 :BCC756CC:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008156b5 where: kdiwh22: kdifind 2025-08-08 01:00:04.649371 :BCC756D9:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 5, nex 6 2025-08-08 01:00:04.649378 :BCC756DA:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081569c where: kdiwh22: kdifind 2025-08-08 01:00:04.657672 :BCC756DB:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 6, nex 7 2025-08-08 01:00:04.657680 :BCC756DC:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008151ab where: kdiwh22: kdifind 2025-08-08 01:00:05.296720 :BCC75705: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-08 01:00:05.340572 :BCC75720: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-08 01:00:05.340876 :BCC75728: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-08 01:00:05.341146 :BCC75730: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-08 01:00:05.341163 :BCC75733:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-08 01:00:05.341223 :BCC7573F: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-08 01:00:05.344141 :BCC7574D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:06.201413 :BCC75831:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:06.199 2025-08-08 01:00:06.201415 :BCC75832:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20352 pso_num=29 pso_serial#=56 2025-08-08 01:00:06.201556 :BCC75833:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20352 (legacy spawn) 2025-08-08 01:00:06.208044 :BCC75834: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-08 01:00:06.208219 :BCC75839: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-08 01:00:06.209028 :BCC7583E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:06.217864 :BCC75841:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:06.215 2025-08-08 01:00:06.217866 :BCC75842:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20220 pso_num=29 pso_serial#=57 2025-08-08 01:00:06.218003 :BCC75843:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20220 (legacy spawn) 2025-08-08 01:00:06.224601 :BCC75844: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-08 01:00:06.224780 :BCC7584D: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-08 01:00:06.225605 :BCC7584E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:13.643125 :BCC75A54:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:13.630 2025-08-08 01:00:13.643127 :BCC75A55:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12980 pso_num=29 pso_serial#=58 2025-08-08 01:00:13.643265 :BCC75A56:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12980 (legacy spawn) 2025-08-08 01:00:13.650131 :BCC75A57: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-08 01:00:13.650303 :BCC75A5C: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-08 01:00:13.651119 :BCC75A61:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 01:00:27.488094 :BCC75F9B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 01:00:27.480 2025-08-08 01:00:27.488096 :BCC75F9C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8296 pso_num=29 pso_serial#=59 2025-08-08 01:00:27.488285 :BCC75F9D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8296 (legacy spawn) 2025-08-08 01:00:27.495062 :BCC75F9E: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-08 01:00:27.495234 :BCC75FA3: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-08 01:00:27.496116 :BCC75FA8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 08:00:16.100700 :BCE873A5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 08:00:16.094 2025-08-08 08:00:16.100703 :BCE873A6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19680 pso_num=29 pso_serial#=60 2025-08-08 08:00:16.103283 :BCE873A7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19680 osp_idx=27 osp_ver=15614 osp_pg=0 (spawn #467203) 2025-08-08 08:00:16.103285 :BCE873A8: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-08 08:00:16.103285 :BCE873A9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=175 time=3320685392 2025-08-08 08:00:16.103285 :BCE873AA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 08:00:16.103286 :BCE873AB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 08:00:16.103286 :BCE873AC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 08:00:16.103287 :BCE873AD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 08:00:16.103287 :BCE873AE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 08:00:16.106629 :BCE873AF: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-08 08:00:16.106686 :BCE873B5: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-08 08:00:16.107136 :BCE873B6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 08:00:18.479896 :BCE8751D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 08:00:18.469 2025-08-08 08:00:18.479898 :BCE8751E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21232 pso_num=29 pso_serial#=61 2025-08-08 08:00:18.480027 :BCE8751F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21232 osp_idx=27 osp_ver=15615 osp_pg=0 (spawn #467205) 2025-08-08 08:00:18.480028 :BCE87520: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-08 08:00:18.480029 :BCE87521:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=176 time=3320687783 2025-08-08 08:00:18.480029 :BCE87522:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 08:00:18.480029 :BCE87523:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 08:00:18.480030 :BCE87524:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 08:00:18.480030 :BCE87525:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 08:00:18.480030 :BCE87526:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 08:00:18.480537 :BCE87527: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-08 08:00:18.480573 :BCE8752D: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-08 08:00:18.480971 :BCE8752E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-08 22:00:02.046958 :BD2998F8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-08 22:00:02.040 2025-08-08 22:00:02.046959 :BD2998F9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14400 pso_num=29 pso_serial#=62 2025-08-08 22:00:02.047091 :BD2998FE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14400 osp_idx=27 osp_ver=15616 osp_pg=0 (spawn #469148) 2025-08-08 22:00:02.047091 :BD2998FF: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-08 22:00:02.047092 :BD299900:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=177 time=3371071345 2025-08-08 22:00:02.047092 :BD299901:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-08 22:00:02.047093 :BD299902:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-08 22:00:02.047094 :BD299903:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-08 22:00:02.047094 :BD299904:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-08 22:00:02.047094 :BD299905:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-08 22:00:02.047608 :BD29990E: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-08 22:00:02.052873 :BD29993C: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-08 22:00:02.052906 :BD29993F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-08 22:00:02.070222 :BD299B0B: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-08 22:00:02.070452 :BD299B13: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-08 22:00:02.070743 :BD299B1B: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-08 22:00:02.071139 :BD299B23: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-08 22:00:02.071343 :BD299B2B: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-08 22:00:02.071509 :BD299B33: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-08 22:00:02.071777 :BD299B3B: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-08 22:00:02.072026 :BD299B43: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-08 22:00:02.072272 :BD299B4B: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-08 22:00:02.072803 :BD299B53: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-08 22:00:02.073017 :BD299B5B: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-08 22:00:02.073277 :BD299B63: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-08 22:00:02.073428 :BD299B6B: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-08 22:00:02.073580 :BD299B73: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-08 22:00:02.073734 :BD299B7B: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-08 22:00:02.073880 :BD299B83: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-08 22:00:02.074028 :BD299B8B: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-08 22:00:02.074171 :BD299B93: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-08 22:00:02.074355 :BD299B9B: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-08 22:00:02.074511 :BD299BA3: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-08 22:00:02.074660 :BD299BAB: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-08 22:00:02.074820 :BD299BB3: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-08 22:00:02.075105 :BD299BBB: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-08 22:00:02.075267 :BD299BC3: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-08 22:00:02.075433 :BD299BCB: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-08 22:00:02.075591 :BD299BD3: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-08 22:00:02.075810 :BD299BDB: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-08 22:00:02.102371 :BD299BE3: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-08 22:00:03.149319*:BD299DCD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1754661603 eid=21150582 ctx=0x0000000777968158 cwh=0x000000002DC20AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-08 22:00:03.149319*:BD299DCE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x000000005523C4B8 pubTabIdxCnt=0 2025-08-08 22:00:03.149319*:BD299DCF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-08-08 22:00:03.149319*:BD299DD0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-08-08 22:00:03.149319*:BD299DD1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003261AAF8 estart=1754661602 eid=16777505 ctx=0x000000077B54EAC8 cwh=0x000000002DC20608 cpu=859375 buffg=49471 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-08 22:00:03.149319*:BD299DD2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003261AAF8 curCtx=0x0000000055051240 pubTabIdxCnt=0 2025-08-08 22:00:03.149319*:BD299DD3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003261AAF8 2025-08-08 22:00:03.149319*:BD299DD4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003261AAF8 2025-08-08 22:00:03.149319*:BD299DD5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x000000005523C4B8 xsc->flg4=65792 flags=1 2025-08-08 22:00:03.149319*:BD299DD6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-08-08 22:00:03.758704*:BD299ECD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003261AAF8 curCtx=0x0000000055051240 xsc->flg4=65792 flags=2 2025-08-08 22:00:03.758704*:BD299ECE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003261AAF8 2025-08-08 22:00:03.764139 :BD299ECF: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-08 22:00:03.784777 :BD299F19: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-08 22:00:03.786772 :BD299F21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-08 22:00:03.787004 :BD299F22:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-08 22:00:03.787914 :BD299F23: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-08 22:00:03.788018 :BD299F26: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-08 22:00:03.788914 :BD299F38: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-08 22:00:03.790310 :BD299F40: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-08 22:00:03.790584 :BD299F48: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-08 22:00:03.852458*: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-08 22:00:03.852458*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-08 22:00:03.852458*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-08 22:00:03.852458*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-08 22:00:03.852458*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-08 22:00:03.852458*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-08 22:00:03.852458*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-08 22:00:03.923042 :BD299FE9: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-08 22:00:05.813370 :BD29A333: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-08 22:00:05.813383 :BD29A334:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0006.01d.0000c872 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 14400, J003)