Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250830060006\orcl_j003_20164_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:35114M/63366M, Ph+PgF:40110M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 20164, image: ORACLE.EXE (J003) *** 2025-08-30 06:00:06.585 *** SESSION ID:(233.33405) 2025-08-30 06:00:06.585 *** 2025-08-30 06:00:06.585 Process diagnostic dump for ORACLE.EXE (J003), OS id=20164, pid: 29, proc_ser: 56, sid: 233, sess_ser: 33405 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 20164 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12994, hash value=3152005041 Current Wait Stack: Not in wait; last wait ended 2.713282 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.713310 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000149 sec, exc=0.000149 sec, total=0.000149 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.062979 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.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.044063 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000149 sec, exc=0.000149 sec, total=0.000149 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.727910 sec of elapsed time 3: 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.000003 sec, exc=0.000003 sec, total=0.000003 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.026764 sec of elapsed time 4: 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.013003 sec of elapsed time 5: 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.000110 sec, exc=0.000110 sec, total=0.000110 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.050739 sec of elapsed time 6: 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.009275 sec, exc=0.009275 sec, total=0.009275 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008073 sec of elapsed time 7: 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.000067 sec, exc=0.000067 sec, total=0.000067 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261043 sec of elapsed time 8: 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.004734 sec, exc=0.004734 sec, total=0.004734 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000180 sec of elapsed time 9: 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.000118 sec, exc=0.000118 sec, total=0.000118 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008206 sec of elapsed time Sampled Session History of session 233 serial 33405 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 06:00:02 - 06:00:06] not in wait at each sample [1 sample, 06:00:01] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-30 06:00:06.585 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 20164, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-30 00:41:59.344700 :C66D4D8D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23248 pso_num=29 pso_serial#=50 2025-08-30 00:41:59.344839 :C66D4D8E:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23248 osp_idx=27 osp_ver=16624 osp_pg=0 (spawn #539521) 2025-08-30 00:41:59.344839 :C66D4D8F: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-30 00:41:59.344840 :C66D4D90:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=15 time=900170643 2025-08-30 00:41:59.344840 :C66D4D91:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 00:41:59.344841 :C66D4D92:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 00:41:59.344841 :C66D4D93:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 00:41:59.344841 :C66D4D94:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 00:41:59.344841 :C66D4D95:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 00:41:59.345371 :C66D4D96: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-30 00:41:59.345430 :C66D4D9C: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-30 00:41:59.349457 :C66D4DC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 00:41:59.349505 :C66D4DC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 00:41:59.349763 :C66D4DC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 00:41:59.349779 :C66D4DC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 00:41:59.349992 :C66D4DC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 00:41:59.350005 :C66D4DC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 00:41:59.350211 :C66D4DCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 00:41:59.350224 :C66D4DCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 00:41:59.350437 :C66D4DCC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 00:41:59.350449 :C66D4DCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 00:41:59.350657 :C66D4DCE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 00:41:59.350670 :C66D4DCF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 00:41:59.353003 :C66D4DD0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-30 01:00:02.048574 :C66EB5CF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-30 01:00:02.042 2025-08-30 01:00:02.048576 :C66EB5D0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12980 pso_num=29 pso_serial#=51 2025-08-30 01:00:02.048709 :C66EB5D1:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12980 osp_idx=27 osp_ver=16625 osp_pg=0 (spawn #539562) 2025-08-30 01:00:02.048710 :C66EB5D2: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-30 01:00:02.048710 :C66EB5D3:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=16 time=901253346 2025-08-30 01:00:02.048710 :C66EB5D4:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 01:00:02.048711 :C66EB5D5:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 01:00:02.048711 :C66EB5D6:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 01:00:02.048712 :C66EB5D7:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 01:00:02.048712 :C66EB5D8:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 01:00:02.049217 :C66EB5D9: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-30 01:00:02.049797 :C66EB5E3: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-30 01:00:02.065693 :C66EB5F5: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-30 01:00:02.065867 :C66EB5FE: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-30 01:00:32.120273 :C66EC0C6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-30 01:02:01.755102 :C66EDF2F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-30 01:02:01.743 2025-08-30 01:02:01.755103 :C66EDF30:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22612 pso_num=29 pso_serial#=52 2025-08-30 01:02:01.755240 :C66EDF31:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22612 osp_idx=27 osp_ver=16626 osp_pg=0 (spawn #539569) 2025-08-30 01:02:01.755241 :C66EDF32: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-30 01:02:01.755242 :C66EDF33:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=17 time=901373049 2025-08-30 01:02:01.755242 :C66EDF34:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 01:02:01.755243 :C66EDF35:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 01:02:01.755243 :C66EDF36:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 01:02:01.755244 :C66EDF37:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 01:02:01.755244 :C66EDF38:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 01:02:01.755756 :C66EDF39: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-30 01:02:01.755803 :C66EDF3F: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-30 01:02:01.764544 :C66EDF54:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-30 01:06:52.713309 :C66F45AB:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-30 01:06:52.710 2025-08-30 01:06:52.713312 :C66F45AC:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21540 pso_num=29 pso_serial#=53 2025-08-30 01:06:52.713455 :C66F45AD:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21540 (legacy spawn) 2025-08-30 01:06:52.730859 :C66F45BB: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-30 01:06:52.731005 :C66F45C4: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-30 01:06:52.731797 :C66F45C5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-30 01:12:02.964071 :C66FAB20:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-30 01:12:02.951 2025-08-30 01:12:02.964074 :C66FAB21:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20800 pso_num=29 pso_serial#=54 2025-08-30 01:12:02.964214 :C66FAB22:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20800 osp_idx=27 osp_ver=16628 osp_pg=0 (spawn #539592) 2025-08-30 01:12:02.964214 :C66FAB23: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-30 01:12:02.964215 :C66FAB24:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=18 time=901974268 2025-08-30 01:12:02.964215 :C66FAB25:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 01:12:02.964216 :C66FAB26:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 01:12:02.964216 :C66FAB27:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 01:12:02.964216 :C66FAB28:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 01:12:02.964217 :C66FAB29:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 01:12:02.964749 :C66FAB2A: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-30 01:12:02.964795 :C66FAB30: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-30 01:12:02.968875 :C66FAB49:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:12:02.968924 :C66FAB4A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:12:02.969190 :C66FAB4B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:12:02.969206 :C66FAB4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:12:02.969425 :C66FAB4D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:12:02.969439 :C66FAB4E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:12:02.969648 :C66FAB4F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:12:02.969661 :C66FAB50:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:12:02.969886 :C66FAB51:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:12:02.969900 :C66FAB52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:12:02.970110 :C66FAB53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:12:02.970122 :C66FAB54:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:12:02.972424 :C66FAB55:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-30 01:42:06.828979 :C672023E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-30 01:42:06.816 2025-08-30 01:42:06.828982 :C672023F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20312 pso_num=29 pso_serial#=55 2025-08-30 01:42:06.829120 :C6720240:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20312 osp_idx=27 osp_ver=16629 osp_pg=0 (spawn #539660) 2025-08-30 01:42:06.829120 :C6720241: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-30 01:42:06.829121 :C6720242:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=19 time=903778127 2025-08-30 01:42:06.829121 :C6720243:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 01:42:06.829122 :C6720244:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 01:42:06.829122 :C6720245:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 01:42:06.829123 :C6720246:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 01:42:06.829123 :C6720247:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 01:42:06.829640 :C6720248: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-30 01:42:06.829685 :C672024E: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-30 01:42:06.833917 :C6720277:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:42:06.833964 :C6720278:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:42:06.834222 :C6720279:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:42:06.834237 :C672027A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:42:06.834460 :C672027B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:42:06.834474 :C672027C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:42:06.834679 :C672027D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:42:06.834692 :C672027E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:42:06.834903 :C672027F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:42:06.834916 :C6720280:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:42:06.835126 :C6720281:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-30 01:42:06.835139 :C6720282:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-30 01:42:06.837760 :C6720283:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-30 06:00:02.055365 :C6863DEF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-30 06:00:02.049 2025-08-30 06:00:02.055366 :C6863DF0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20164 pso_num=29 pso_serial#=56 2025-08-30 06:00:02.055521 :C6863DF5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20164 osp_idx=27 osp_ver=16630 osp_pg=0 (spawn #540259) 2025-08-30 06:00:02.055521 :C6863DF6: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-30 06:00:02.055522 :C6863DF7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=20 time=919253362 2025-08-30 06:00:02.055523 :C6863DF8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 06:00:02.055523 :C6863DF9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 06:00:02.055523 :C6863DFA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 06:00:02.055524 :C6863DFB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 06:00:02.055525 :C6863DFC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 06:00:02.056053 :C6863E0A: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-30 06:00:02.061040 :C6863E21: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-30 06:00:02.061079 :C6863E24:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-30 06:00:02.079060 :C6863FD9: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-30 06:00:02.079413 :C6863FE1: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-30 06:00:02.079576 :C6863FE9: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-30 06:00:02.079734 :C6863FF8: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-30 06:00:02.079894 :C6864004: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-30 06:00:02.080059 :C686400C: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-30 06:00:02.080219 :C6864014: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-30 06:00:02.080364 :C686401C: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-30 06:00:02.080514 :C6864024: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-30 06:00:02.080658 :C686402C: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-30 06:00:02.080806 :C6864034: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-30 06:00:02.080963 :C686403C: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-30 06:00:02.081103 :C6864044: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-30 06:00:02.081327 :C686404C: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-30 06:00:02.081488 :C6864054: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-30 06:00:02.081635 :C686405C: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-30 06:00:02.081786 :C6864064: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-30 06:00:02.081933 :C686406C: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-30 06:00:02.082128 :C6864074: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-30 06:00:02.082316 :C686407C: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-30 06:00:02.082469 :C6864084: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-30 06:00:02.082623 :C686408C: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-30 06:00:02.082771 :C6864094: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-30 06:00:02.102644 :C686409C: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-30 06:00:03.699062*:C68643CB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031233AD0 estart=1756504803 eid=21852175 ctx=0x0000000777968158 cwh=0x00000000312609C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-30 06:00:03.699062*:C68643CC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031233AD0 curCtx=0x000000005AED7918 pubTabIdxCnt=0 2025-08-30 06:00:03.699062*:C68643CD:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031233AD0 2025-08-30 06:00:03.699062*:C68643CE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031233AD0 2025-08-30 06:00:03.699062*:C68643CF:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000312AB6F0 estart=1756504801 eid=16777551 ctx=0x000000077B54EAC8 cwh=0x0000000031260518 cpu=1406250 buffg=75728 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-30 06:00:03.699062*:C68643D0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000312AB6F0 curCtx=0x000000005ADB87D0 pubTabIdxCnt=0 2025-08-30 06:00:03.699062*:C68643D1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000312AB6F0 2025-08-30 06:00:03.699062*:C68643D2:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000312AB6F0 2025-08-30 06:00:03.699062*:C68643D3:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031233AD0 curCtx=0x000000005AED7918 xsc->flg4=65792 flags=1 2025-08-30 06:00:03.699062*:C68643D4:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031233AD0 2025-08-30 06:00:03.777200*:C6864459:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000312AB6F0 curCtx=0x000000005ADB87D0 xsc->flg4=65792 flags=2 2025-08-30 06:00:03.777200*:C686445A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000312AB6F0 2025-08-30 06:00:03.790151 :C686445B: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-30 06:00:03.810820 :C6864463: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-30 06:00:03.812776 :C686446B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-30 06:00:03.812992 :C686446C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-30 06:00:03.813840 :C686446D: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-30 06:00:03.813929 :C6864470: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-30 06:00:03.814805 :C6864482: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-30 06:00:03.816273 :C686448A: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-30 06:00:03.816541 :C6864492: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-30 06:00:03.870941*: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-30 06:00:03.870941*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-30 06:00:03.870941*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-30 06:00:03.917813*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-30 06:00:03.917813*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-30 06:00:03.917813*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-30 06:00:03.917813*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-30 06:00:03.978620 :C686449A: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-30 06:00:05.898225 :C68645B4: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-30 06:00:05.898237 :C68645B5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0003.01d.0000c9b8 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 20164, J003)