Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250524060008\orcl_j002_2472_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:37295M/63366M, Ph+PgF:44315M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 2472, image: ORACLE.EXE (J002) *** 2025-05-24 06:00:08.817 *** SESSION ID:(225.12091) 2025-05-24 06:00:08.817 *** 2025-05-24 06:00:08.817 Process diagnostic dump for ORACLE.EXE (J002), OS id=2472, pid: 28, proc_ser: 30, sid: 225, sess_ser: 12091 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 2472 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12476, hash value=1024636364 Current Wait Stack: Not in wait; last wait ended 1.056492 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.056517 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000309 sec, exc=0.000309 sec, total=0.000309 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'log file sequential read' log#=0x27e1, block#=0x0, blocks=0x0 wait_id=133 seq_num=134 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.076799 sec of elapsed time 2: waited for 'log file sequential read' log#=0x27e1, block#=0x0, blocks=0x0 wait_id=132 seq_num=133 snap_id=1 wait times: snap=0.000016 sec, exc=0.000016 sec, total=0.000016 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x12000, blocks=0x800 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000295 sec, exc=0.000295 sec, total=0.000295 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#=0x11800, blocks=0x800 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000306 sec, exc=0.000306 sec, total=0.000306 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x11000, blocks=0x800 wait_id=129 seq_num=130 snap_id=1 wait times: snap=0.000289 sec, exc=0.000289 sec, total=0.000289 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#=0x10800, blocks=0x800 wait_id=128 seq_num=129 snap_id=1 wait times: snap=0.000293 sec, exc=0.000293 sec, total=0.000293 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x10000, blocks=0x800 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.000344 sec, exc=0.000344 sec, total=0.000344 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000117 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0xf800, blocks=0x800 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.000409 sec, exc=0.000409 sec, total=0.000409 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.560147 sec of elapsed time 9: waited for 'log file sequential read' log#=0x27e1, block#=0x800, blocks=0x800 wait_id=125 seq_num=126 snap_id=1 wait times: snap=0.000204 sec, exc=0.000204 sec, total=0.000204 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002546 sec of elapsed time Sampled Session History of session 225 serial 12091 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 06:00:02 - 06:00:08] 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-05-24 06:00:08.817 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 2472, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-24 05:00:51.174980 :9B399A02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.176084 :9B399A0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.224059 :9B399A12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.225265 :9B399A1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.226129 :9B399A22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.226429 :9B399A2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.226690 :9B399A32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 05:00:51.228367 :9B399A3A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-24 05:09:51.041074 :9B3A50A8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 05:09:51.028 2025-05-24 05:09:51.041077 :9B3A50A9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14284 pso_num=28 pso_serial#=27 2025-05-24 05:09:51.041244 :9B3A50AA:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14284 osp_idx=26 osp_ver=136960 osp_pg=0 (spawn #213497) 2025-05-24 05:09:51.041245 :9B3A50AB:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:09:51.041245 :9B3A50AC:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=249 time=1039163282 2025-05-24 05:09:51.041246 :9B3A50AD:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:09:51.041246 :9B3A50AE:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:09:51.041247 :9B3A50AF:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:09:51.041247 :9B3A50B0:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:09:51.041248 :9B3A50B1:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:09:51.041761 :9B3A50B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:09:51.041813 :9B3A50B8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:09:51.045850 :9B3A50E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:09:51.045890 :9B3A50E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:09:51.046145 :9B3A50E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:09:51.046160 :9B3A50E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:09:51.046369 :9B3A50E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:09:51.046382 :9B3A50E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:09:51.046594 :9B3A50E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:09:51.046608 :9B3A50E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:09:51.046811 :9B3A50E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:09:51.046824 :9B3A50EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:09:51.047023 :9B3A50EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:09:51.047035 :9B3A50EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:09:51.049358 :9B3A50ED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-24 05:39:54.980619 :9B3CA1B2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 05:39:54.968 2025-05-24 05:39:54.980622 :9B3CA1B3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13628 pso_num=28 pso_serial#=28 2025-05-24 05:39:54.980758 :9B3CA1B4:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13628 osp_idx=26 osp_ver=136961 osp_pg=0 (spawn #213565) 2025-05-24 05:39:54.980759 :9B3CA1B5:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 05:39:54.980760 :9B3CA1B6:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=250 time=1040967219 2025-05-24 05:39:54.980760 :9B3CA1B7:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 05:39:54.980761 :9B3CA1B8:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 05:39:54.980761 :9B3CA1B9:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 05:39:54.980761 :9B3CA1BA:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 05:39:54.980762 :9B3CA1BB:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 05:39:54.981289 :9B3CA1BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 05:39:54.981327 :9B3CA1C2:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 05:39:54.985399 :9B3CA1DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:39:54.985443 :9B3CA1DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:39:54.985697 :9B3CA1DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:39:54.985713 :9B3CA1DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:39:54.985921 :9B3CA1DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:39:54.985934 :9B3CA1E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:39:54.986149 :9B3CA1E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:39:54.986162 :9B3CA1E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:39:54.986365 :9B3CA1E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:39:54.986378 :9B3CA1E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:39:54.986580 :9B3CA1E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-05-24 05:39:54.986593 :9B3CA1E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-05-24 05:39:54.989151 :9B3CA1E7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-24 06:00:00.042191 :9B3E2C7F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 06:00:00.029 2025-05-24 06:00:00.042193 :9B3E2C80:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10416 pso_num=28 pso_serial#=29 2025-05-24 06:00:00.042329 :9B3E2C81:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=10416 osp_idx=26 osp_ver=136962 osp_pg=0 (spawn #213609) 2025-05-24 06:00:00.042329 :9B3E2C82:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:00:00.042330 :9B3E2C83:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=251 time=1042172282 2025-05-24 06:00:00.042330 :9B3E2C84:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:00:00.042331 :9B3E2C85:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:00:00.042331 :9B3E2C86:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:00:00.042331 :9B3E2C87:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:00:00.042332 :9B3E2C88:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:00:00.042835 :9B3E2C89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.042879 :9B3E2C90:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 06:00:00.042961 :9B3E2CA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.046883 :9B3E2CB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.048052 :9B3E2CBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.048076 :9B3E2CBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.048115 :9B3E2CC8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 06:00:00.049967 :9B3E2CD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.049992 :9B3E2CD6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.050030 :9B3E2CE1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 06:00:00.050495 :9B3E2CEC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.052953 :9B3E2CF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.058325 :9B3E2CFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.059400 :9B3E2D04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.064257 :9B3E2D0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.065680 :9B3E2D14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.068637 :9B3E2D1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.070003 :9B3E2D24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.072999 :9B3E2D2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.076802 :9B3E2D34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.077184 :9B3E2D43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.077259 :9B3E2D46:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-05-24 06:00:00.077275 :9B3E2D47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-05-24 06:00:00.080011 :9B3E2D4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.080303 :9B3E2D5C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.080347 :9B3E2D5F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-05-24 06:00:00.080360 :9B3E2D60:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-05-24 06:00:00.083185 :9B3E2D66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.083480 :9B3E2D75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.083526 :9B3E2D78:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-05-24 06:00:00.083540 :9B3E2D79:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-05-24 06:00:00.083711 :9B3E2D7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.083722 :9B3E2D82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-24 06:00:00.083744 :9B3E2D8E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-24 06:00:00.085264 :9B3E2D98:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-24 06:00:02.045032 :9B3E2E5A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-24 06:00:02.039 2025-05-24 06:00:02.045033 :9B3E2E5B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2472 pso_num=28 pso_serial#=30 2025-05-24 06:00:02.045180 :9B3E2E5C:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=2472 osp_idx=26 osp_ver=136963 osp_pg=0 (spawn #213610) 2025-05-24 06:00:02.045181 :9B3E2E5D:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-24 06:00:02.045181 :9B3E2E5E:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=252 time=1042174297 2025-05-24 06:00:02.045182 :9B3E2E5F:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-24 06:00:02.045182 :9B3E2E60:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-24 06:00:02.045182 :9B3E2E61:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-24 06:00:02.045183 :9B3E2E62:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-24 06:00:02.045183 :9B3E2E63:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-24 06:00:02.045723 :9B3E2E68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.050813 :9B3E2E85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.050849 :9B3E2E88:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-05-24 06:00:02.068595 :9B3E30BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.068635 :9B3E30BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.068800 :9B3E30C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.068825 :9B3E30C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.068976 :9B3E30D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.069130 :9B3E30E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.069287 :9B3E30EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.069446 :9B3E30F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.069589 :9B3E30FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.069732 :9B3E3102:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.069878 :9B3E310A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070025 :9B3E3112:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070220 :9B3E311A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070439 :9B3E3125:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070462 :9B3E3128:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070622 :9B3E3142:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070773 :9B3E314A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.070919 :9B3E3152:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.071069 :9B3E315A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.071218 :9B3E3162:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.071450 :9B3E316A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.071627 :9B3E3172:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.071781 :9B3E317A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.071939 :9B3E3182:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.072090 :9B3E318A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.072239 :9B3E3192:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.072387 :9B3E319A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:02.091969 :9B3E31A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.627566*:9B3E3441:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1748037603 eid=18678346 ctx=0x0000000777968158 cwh=0x00000000301B09C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-05-24 06:00:03.627566*:9B3E3444:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x000000003E7D7918 pubTabIdxCnt=0 2025-05-24 06:00:03.627566*:9B3E3445:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-05-24 06:00:03.627566*:9B3E3446:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-05-24 06:00:03.627566*:9B3E3447:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301FB6F0 estart=1748037601 eid=16777341 ctx=0x000000077B54EAC8 cwh=0x00000000301B0518 cpu=1390625 buffg=74842 interb=196608 r=24 rb=196608 w=0 wb=0 2025-05-24 06:00:03.627566*:9B3E3448:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301FB6F0 curCtx=0x000000003E6B87D0 pubTabIdxCnt=0 2025-05-24 06:00:03.627566*:9B3E3449:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301FB6F0 2025-05-24 06:00:03.627566*:9B3E344A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301FB6F0 2025-05-24 06:00:03.627566*:9B3E344F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x000000003E7D7918 xsc->flg4=65792 flags=1 2025-05-24 06:00:03.627566*:9B3E3450:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-05-24 06:00:03.714857 :9B3E34D3:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 8, ext 0, nex 2 2025-05-24 06:00:03.714864 :9B3E34D4:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00818fe1 where: kduwh01: kdusru 2025-05-24 06:00:03.720689*:9B3E34E8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301FB6F0 curCtx=0x000000003E6B87D0 xsc->flg4=65792 flags=2 2025-05-24 06:00:03.720689*:9B3E34E9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301FB6F0 2025-05-24 06:00:03.724007 :9B3E34EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.744514 :9B3E3504:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.746465 :9B3E351D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-05-24 06:00:03.746639 :9B3E351E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-05-24 06:00:03.747576 :9B3E351F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.747663 :9B3E3522:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.748513 :9B3E3534:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.750102 :9B3E353C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.750384 :9B3E3544:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:03.798802*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-05-24 06:00:03.798802*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-05-24 06:00:03.798802*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-05-24 06:00:03.845670*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-05-24 06:00:03.845670*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-05-24 06:00:03.845670*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-05-24 06:00:03.845670*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-05-24 06:00:03.902173 :9B3E35B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:05.802746 :9B3E36D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:05.802756 :9B3E36D9:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0007.00d.0000c2e9 2025-05-24 06:00:08.221362 :9B3E3854:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:08.222752 :9B3E385C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:08.232868 :9B3E3864:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-24 06:00:08.256155 :9B3E386C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 2472, J002)