Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250824060008\orcl_j003_6364_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:35234M/63366M, Ph+PgF:40341M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 6364, image: ORACLE.EXE (J003) *** 2025-08-24 06:00:08.872 *** SESSION ID:(233.32827) 2025-08-24 06:00:08.872 *** 2025-08-24 06:00:08.872 Process diagnostic dump for ORACLE.EXE (J003), OS id=6364, pid: 29, proc_ser: 26, sid: 233, sess_ser: 32827 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 6364 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12968, hash value=3881778026 Current Wait Stack: Not in wait; last wait ended 1.177100 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.177125 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000215 sec, exc=0.000215 sec, total=0.000215 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 1: waited for 'log file sequential read' log#=0x29fa, block#=0x0, blocks=0x0 wait_id=129 seq_num=130 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.006080 sec of elapsed time 2: waited for 'log file sequential read' log#=0x29fa, block#=0x0, blocks=0x0 wait_id=128 seq_num=129 snap_id=1 wait times: snap=0.000019 sec, exc=0.000019 sec, total=0.000019 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.028806 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x3800, blocks=0x800 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.000288 sec, exc=0.000288 sec, total=0.000288 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x3000, blocks=0x800 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.000321 sec, exc=0.000321 sec, total=0.000321 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000008 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x2800, blocks=0x800 wait_id=125 seq_num=126 snap_id=1 wait times: snap=0.000300 sec, exc=0.000300 sec, total=0.000300 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0x2000, blocks=0x800 wait_id=124 seq_num=125 snap_id=1 wait times: snap=0.000315 sec, exc=0.000315 sec, total=0.000315 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000097 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x1800, blocks=0x800 wait_id=123 seq_num=124 snap_id=1 wait times: snap=0.000279 sec, exc=0.000279 sec, total=0.000279 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.186916 sec of elapsed time 8: waited for 'log file sequential read' log#=0x29fa, block#=0x800, blocks=0x800 wait_id=122 seq_num=123 snap_id=1 wait times: snap=0.000231 sec, exc=0.000231 sec, total=0.000231 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.243992 sec of elapsed time 9: waited for 'log file sequential read' log#=0x29fa, block#=0x0, blocks=0x0 wait_id=121 seq_num=122 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000122 sec of elapsed time Sampled Session History of session 233 serial 32827 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [6 samples, 06:00:03 - 06:00:08] not in wait at each sample [1 sample, 06:00:02] 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-24 06:00:08.872 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 6364, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-24 01:00:24.080238 :C3CC2204:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22264 pso_num=29 pso_serial#=20 2025-08-24 01:00:24.080374 :C3CC2205:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22264 osp_idx=27 osp_ver=16339 osp_pg=0 (spawn #519573) 2025-08-24 01:00:24.080375 :C3CC2206: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-24 01:00:24.080376 :C3CC2207:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=151 time=382926096 2025-08-24 01:00:24.080376 :C3CC2208:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 01:00:24.080376 :C3CC2209:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 01:00:24.080377 :C3CC220A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 01:00:24.080377 :C3CC220B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 01:00:24.080377 :C3CC220C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 01:00:24.080872 :C3CC220D: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-24 01:00:24.080919 :C3CC2213: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-24 01:00:24.085411 :C3CC2226: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-24 01:00:24.107576 :C3CC222E: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-24 01:00:24.123060 :C3CC2236: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-24 01:00:24.156538 :C3CC223E: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-24 01:00:24.160231 :C3CC2246: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-24 01:00:24.163942 :C3CC224E: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-24 01:00:24.168229 :C3CC2256: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-24 01:00:24.169351 :C3CC225E: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-24 01:00:25.324916*:C3CC22C7:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB85D98 estart=1755968424 eid=16780923 ctx=0x000000077BA4B1C8 cwh=0x000000002DBF0F68 cpu=531250 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-08-24 01:00:25.324916*:C3CC22C8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB85D98 curCtx=0x000000002DB84AD0 pubTabIdxCnt=0 2025-08-24 01:00:25.324916*:C3CC22C9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB85D98 2025-08-24 01:00:25.324916*:C3CC22CA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB85D98 2025-08-24 01:00:25.324916*:C3CC22CB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B531080 estart=1755968424 eid=16780901 ctx=0x0000000787AD5BF0 cwh=0x000000002B5207D0 cpu=531250 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-08-24 01:00:25.324916*:C3CC22CC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B531080 curCtx=0x000000002DB86D38 pubTabIdxCnt=0 2025-08-24 01:00:25.324916*:C3CC22CD:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B531080 2025-08-24 01:00:25.324916*:C3CC22CE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B531080 2025-08-24 01:00:25.324916*:C3CC22CF:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB85D98 curCtx=0x000000002DB84AD0 xsc->flg4=65792 flags=1 2025-08-24 01:00:25.324916*:C3CC22D0:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB85D98 2025-08-24 01:00:25.324916*:C3CC22D1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B531080 curCtx=0x000000002DB86D38 xsc->flg4=65792 flags=2 2025-08-24 01:00:25.324916*:C3CC22D2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B531080 2025-08-24 01:00:25.399639 :C3CC22D3: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-24 01:00:25.446698 :C3CC22DB: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-24 01:00:25.447056 :C3CC22E3: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-24 01:00:25.447341 :C3CC22EB: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-24 01:00:25.447358 :C3CC22EE: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-24 01:00:25.447423 :C3CC22FB: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-24 01:00:25.450779 :C3CC2307:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-24 01:00:32.192062 :C3CC255B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-24 01:00:32.189 2025-08-24 01:00:32.192064 :C3CC255C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20684 pso_num=29 pso_serial#=21 2025-08-24 01:00:32.192210 :C3CC255D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20684 (legacy spawn) 2025-08-24 01:00:32.198735 :C3CC255E: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-24 01:00:32.198914 :C3CC2563: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-24 01:00:32.199713 :C3CC2568:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-24 01:00:32.208474 :C3CC256B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-24 01:00:32.205 2025-08-24 01:00:32.208476 :C3CC256C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22408 pso_num=29 pso_serial#=22 2025-08-24 01:00:32.208609 :C3CC256D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22408 (legacy spawn) 2025-08-24 01:00:32.215057 :C3CC256E: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-24 01:00:32.215222 :C3CC2577: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-24 01:00:32.216008 :C3CC2578:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-24 01:02:24.386231 :C3CC4B60:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-24 01:02:24.374 2025-08-24 01:02:24.386232 :C3CC4B61:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17836 pso_num=29 pso_serial#=23 2025-08-24 01:02:24.386445 :C3CC4B62:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17836 osp_idx=27 osp_ver=16342 osp_pg=0 (spawn #519580) 2025-08-24 01:02:24.386446 :C3CC4B63: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-24 01:02:24.386447 :C3CC4B64:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=152 time=383046393 2025-08-24 01:02:24.386447 :C3CC4B65:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 01:02:24.386448 :C3CC4B66:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 01:02:24.386448 :C3CC4B67:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 01:02:24.386449 :C3CC4B68:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 01:02:24.386449 :C3CC4B69:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 01:02:24.387012 :C3CC4B6A: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-24 01:02:24.387069 :C3CC4B70: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-24 01:02:24.395668 :C3CC4B85:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-24 01:22:26.982645 :C3CDE4C8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-24 01:22:26.970 2025-08-24 01:22:26.982647 :C3CDE4C9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21700 pso_num=29 pso_serial#=24 2025-08-24 01:22:26.982785 :C3CDE4CA:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21700 osp_idx=27 osp_ver=16343 osp_pg=0 (spawn #519626) 2025-08-24 01:22:26.982786 :C3CDE4CB: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-24 01:22:26.982786 :C3CDE4CC:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=153 time=384248987 2025-08-24 01:22:26.982787 :C3CDE4CD:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 01:22:26.982787 :C3CDE4CE:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 01:22:26.982788 :C3CDE4CF:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 01:22:26.982788 :C3CDE4D0:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 01:22:26.982788 :C3CDE4D1:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 01:22:26.983303 :C3CDE4D2: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-24 01:22:26.983346 :C3CDE4D8: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-24 01:22:26.987447 :C3CDE501:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:22:26.987491 :C3CDE502:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:22:26.987739 :C3CDE503:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:22:26.987754 :C3CDE504:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:22:26.987977 :C3CDE505:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:22:26.987998 :C3CDE506:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:22:26.988296 :C3CDE507:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:22:26.988309 :C3CDE508:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:22:26.988520 :C3CDE509:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:22:26.988533 :C3CDE50A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:22:26.988744 :C3CDE50B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:22:26.988757 :C3CDE50C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:22:26.991114 :C3CDE50D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-24 01:52:30.890692 :C3D03D11:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-24 01:52:30.878 2025-08-24 01:52:30.890694 :C3D03D12:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20856 pso_num=29 pso_serial#=25 2025-08-24 01:52:30.890833 :C3D03D13:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20856 osp_idx=27 osp_ver=16344 osp_pg=0 (spawn #519694) 2025-08-24 01:52:30.890834 :C3D03D14: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-24 01:52:30.890834 :C3D03D15:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=154 time=386052893 2025-08-24 01:52:30.890834 :C3D03D16:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 01:52:30.890835 :C3D03D17:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 01:52:30.890835 :C3D03D18:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 01:52:30.890835 :C3D03D19:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 01:52:30.890836 :C3D03D1A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 01:52:30.891338 :C3D03D1B: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-24 01:52:30.891384 :C3D03D21: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-24 01:52:30.895473 :C3D03D3A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:52:30.895519 :C3D03D3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:52:30.895779 :C3D03D3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:52:30.895794 :C3D03D3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:52:30.896017 :C3D03D3E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:52:30.896031 :C3D03D3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:52:30.896245 :C3D03D40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:52:30.896258 :C3D03D41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:52:30.896475 :C3D03D42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:52:30.896488 :C3D03D43:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:52:30.896695 :C3D03D44:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-24 01:52:30.896709 :C3D03D45:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-24 01:52:30.899090 :C3D03D46:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-24 06:00:02.040820 :C3E3B837:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-24 06:00:02.029 2025-08-24 06:00:02.040821 :C3E3B838:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=6364 pso_num=29 pso_serial#=26 2025-08-24 06:00:02.040950 :C3E3B839:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=6364 osp_idx=27 osp_ver=16345 osp_pg=0 (spawn #520267) 2025-08-24 06:00:02.040951 :C3E3B83A: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-24 06:00:02.040952 :C3E3B83B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=155 time=400904049 2025-08-24 06:00:02.040952 :C3E3B83C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-24 06:00:02.040953 :C3E3B83D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-24 06:00:02.040953 :C3E3B83E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-24 06:00:02.040953 :C3E3B83F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-24 06:00:02.040954 :C3E3B840:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-24 06:00:02.041458 :C3E3B84A: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-24 06:00:02.046403 :C3E3B85F: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-24 06:00:02.046440 :C3E3B862:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-24 06:00:02.063403 :C3E3BA7B: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-24 06:00:02.063615 :C3E3BA83: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-24 06:00:02.063769 :C3E3BA8B: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-24 06:00:02.063925 :C3E3BA93: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-24 06:00:02.064076 :C3E3BA9B: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-24 06:00:02.064222 :C3E3BAA3: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-24 06:00:02.064365 :C3E3BAAB: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-24 06:00:02.064590 :C3E3BAB3: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-24 06:00:02.064760 :C3E3BABB: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-24 06:00:02.064961 :C3E3BAC3: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-24 06:00:02.065166 :C3E3BACC: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-24 06:00:02.065420 :C3E3BAD4: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-24 06:00:02.065575 :C3E3BADC: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-24 06:00:02.065734 :C3E3BAE4: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-24 06:00:02.065886 :C3E3BAEC: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-24 06:00:02.066069 :C3E3BAF4: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-24 06:00:02.085886 :C3E3BB0B: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-24 06:00:03.586090*:C3E3BDD8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB83AD0 estart=1755986403 eid=21700094 ctx=0x0000000777968158 cwh=0x000000002DBB09C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-24 06:00:03.586090*:C3E3BDD9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB83AD0 curCtx=0x000000005A207918 pubTabIdxCnt=0 2025-08-24 06:00:03.586090*:C3E3BDDA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB83AD0 2025-08-24 06:00:03.586090*:C3E3BDDB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB83AD0 2025-08-24 06:00:03.586090*:C3E3BDDC:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DBFB6F0 estart=1755986401 eid=16777541 ctx=0x000000077B54EAC8 cwh=0x000000002DBB0518 cpu=1296875 buffg=71900 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-24 06:00:03.586090*:C3E3BDDD:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DBFB6F0 curCtx=0x000000005A0E87D0 pubTabIdxCnt=0 2025-08-24 06:00:03.586090*:C3E3BDDE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DBFB6F0 2025-08-24 06:00:03.586090*:C3E3BDDF:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DBFB6F0 2025-08-24 06:00:03.586090*:C3E3BDE0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB83AD0 curCtx=0x000000005A207918 xsc->flg4=65792 flags=1 2025-08-24 06:00:03.586090*:C3E3BDE1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB83AD0 2025-08-24 06:00:03.742335*:C3E3BE96:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DBFB6F0 curCtx=0x000000005A0E87D0 xsc->flg4=65792 flags=2 2025-08-24 06:00:03.742335*:C3E3BE97:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DBFB6F0 2025-08-24 06:00:03.746504 :C3E3BE98: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-24 06:00:03.768964 :C3E3BEA9: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-24 06:00:03.771242 :C3E3BEB1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-24 06:00:03.771465 :C3E3BEB2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-24 06:00:03.772416 :C3E3BEB3: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-24 06:00:03.772516 :C3E3BEB6: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-24 06:00:03.773462 :C3E3BEC8: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-24 06:00:03.775242 :C3E3BED0: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-24 06:00:03.775593 :C3E3BED8: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-24 06:00:03.836078*: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-24 06:00:03.836078*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-24 06:00:03.836078*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-24 06:00:03.836078*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-24 06:00:03.836078*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-24 06:00:03.836078*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-24 06:00:03.836078*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-24 06:00:03.914593 :C3E3BFBA: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-24 06:00:05.801504 :C3E3C161: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-24 06:00:05.801520 :C3E3C162:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.00c.0001dabf 2025-08-24 06:00:08.169571 :C3E3C330: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-24 06:00:08.171228 :C3E3C338: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-24 06:00:08.182726 :C3E3C340: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-24 06:00:08.212269 :C3E3C348: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 6364, J003)