Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250818220008\orcl_j003_19788_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:35379M/63366M, Ph+PgF:40583M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 19788, image: ORACLE.EXE (J003) *** 2025-08-18 22:00:08.964 *** SESSION ID:(233.32311) 2025-08-18 22:00:08.964 *** 2025-08-18 22:00:08.964 Process diagnostic dump for ORACLE.EXE (J003), OS id=19788, pid: 29, proc_ser: 25, sid: 233, sess_ser: 32311 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19788 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12942, hash value=2018245891 Current Wait Stack: Not in wait; last wait ended 1.156272 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.156297 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000302 sec, exc=0.000302 sec, total=0.000302 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#=0x29db, block#=0x0, blocks=0x0 wait_id=138 seq_num=139 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.023693 sec of elapsed time 2: waited for 'log file sequential read' log#=0x29db, block#=0x0, blocks=0x0 wait_id=137 seq_num=138 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.519519 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x3000, blocks=0x800 wait_id=136 seq_num=137 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.000037 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x2800, blocks=0x800 wait_id=135 seq_num=136 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.005147 sec of elapsed time 5: waited for 'log file sequential read' log#=0x29db, block#=0x0, blocks=0x0 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000128 sec of elapsed time 6: waited for 'log file sequential read' log#=0x29db, block#=0x2, blocks=0x7fe wait_id=133 seq_num=134 snap_id=1 wait times: snap=0.000199 sec, exc=0.000199 sec, total=0.000199 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000156 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=132 seq_num=133 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time 8: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x0, filetype=0x3 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000205 sec, exc=0.000205 sec, total=0.000205 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000129 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 233 serial 32311 --------------------------------------------------- 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, 22:00:02 - 22:00:08] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-18 22:00:08.964 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 19788, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 23:59:04.938193 :C125815C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 23:59:05.194288 :C1258195:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 23:59:05.182 2025-08-17 23:59:05.194289 :C1258196:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21480 pso_num=29 pso_serial#=13 2025-08-17 23:59:05.194437 :C1258197:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21480 (legacy spawn) 2025-08-17 23:59:05.208698 :C1258198: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-17 23:59:38.360667 :C1258DCC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 23:59:38.477921 :C1258DCF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 23:59:38.466 2025-08-17 23:59:38.477924 :C1258DD0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20384 pso_num=29 pso_serial#=14 2025-08-17 23:59:38.478092 :C1258DD1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20384 (legacy spawn) 2025-08-17 23:59:38.494573 :C1258DD2: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-17 23:59:41.627359 :C1258EE8: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-17 23:59:41.627545 :C1258EED: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-17 23:59:41.628777 :C1258EF2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 23:59:41.881270 :C1258EF5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 23:59:41.876 2025-08-17 23:59:41.881273 :C1258EF6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19592 pso_num=29 pso_serial#=15 2025-08-17 23:59:41.881418 :C1258EF7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19592 (legacy spawn) 2025-08-17 23:59:41.896132 :C1258EF8: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-18 00:00:15.131178 :C1259A6A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 00:00:43.581385 :C125A69E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 00:00:43.568 2025-08-18 00:00:43.581388 :C125A69F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18548 pso_num=29 pso_serial#=16 2025-08-18 00:00:43.581527 :C125A6A0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18548 (legacy spawn) 2025-08-18 00:00:43.588073 :C125A6A1: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-18 00:00:43.588393 :C125A6A8: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-18 00:00:43.589195 :C125A6AB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 00:00:43.597866 :C125A6AE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 00:00:43.583 2025-08-18 00:00:43.597868 :C125A6AF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21784 pso_num=29 pso_serial#=17 2025-08-18 00:00:43.598001 :C125A6B0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21784 (legacy spawn) 2025-08-18 00:00:43.604522 :C125A6B1: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-18 00:00:43.604691 :C125A6BA: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-18 00:00:43.605547 :C125A6BB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 00:00:44.563209 :C125A740:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 00:00:44.552 2025-08-18 00:00:44.563212 :C125A741:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2648 pso_num=29 pso_serial#=18 2025-08-18 00:00:44.563342 :C125A742:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=2648 osp_idx=27 osp_ver=16082 osp_pg=0 (spawn #499441) 2025-08-18 00:00:44.563343 :C125A743: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-18 00:00:44.563343 :C125A744:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=30 time=4155913861 2025-08-18 00:00:44.563343 :C125A745:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-18 00:00:44.563344 :C125A746:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-18 00:00:44.563344 :C125A747:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-18 00:00:44.563344 :C125A748:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-18 00:00:44.563344 :C125A749:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-18 00:00:44.563843 :C125A74A: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-18 00:00:44.563886 :C125A750: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-18 00:00:44.571894 :C125A75E: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-18 00:00:44.572992 :C125A766: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-18 00:00:44.573599 :C125A76E: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-18 00:00:44.574078 :C125A776: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-18 00:00:44.574520 :C125A77E: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-18 00:00:44.576986 :C125A786: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-18 00:00:44.578157 :C125A78E: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-18 00:00:44.629430 :C125A796: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-18 00:00:44.630313 :C125A79E: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-18 00:00:44.630565 :C125A7A6: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-18 00:00:44.630843 :C125A7AE: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-18 00:00:44.632738 :C125A7B6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 01:00:05.684860 :C12A492B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 01:00:05.672 2025-08-18 01:00:05.684862 :C12A492C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21060 pso_num=29 pso_serial#=19 2025-08-18 01:00:05.684999 :C12A492D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21060 (legacy spawn) 2025-08-18 01:00:05.691488 :C12A492E: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-18 01:00:05.691672 :C12A4933: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-18 01:00:05.692493 :C12A4938:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 01:00:05.701072 :C12A493B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 01:00:05.687 2025-08-18 01:00:05.701075 :C12A493C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21180 pso_num=29 pso_serial#=20 2025-08-18 01:00:05.701205 :C12A493D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21180 (legacy spawn) 2025-08-18 01:00:05.707633 :C12A493E: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-18 01:00:05.707800 :C12A4947: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-18 01:00:05.708577 :C12A4948:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 01:00:18.640930 :C12A4DA4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 01:00:18.632 2025-08-18 01:00:18.640932 :C12A4DA5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4148 pso_num=29 pso_serial#=21 2025-08-18 01:00:18.641075 :C12A4DA6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4148 (legacy spawn) 2025-08-18 01:00:18.647614 :C12A4DA7: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-18 01:00:18.647786 :C12A4DAC: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-18 01:00:18.648719 :C12A4DB1:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 01:00:30.323216 :C12A50E8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 01:00:30.310 2025-08-18 01:00:30.323218 :C12A50E9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10320 pso_num=29 pso_serial#=22 2025-08-18 01:00:30.323357 :C12A50EA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10320 (legacy spawn) 2025-08-18 01:00:30.329865 :C12A50EB: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-18 01:00:30.330039 :C12A50F0: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-18 01:00:30.330838 :C12A50F5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 01:00:52.584686 :C12A5A7A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 01:00:52.573 2025-08-18 01:00:52.584688 :C12A5A7B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19488 pso_num=29 pso_serial#=23 2025-08-18 01:00:52.584832 :C12A5A7C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19488 osp_idx=27 osp_ver=16087 osp_pg=0 (spawn #499580) 2025-08-18 01:00:52.584832 :C12A5A7D: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-18 01:00:52.584833 :C12A5A7E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=31 time=4159521876 2025-08-18 01:00:52.584833 :C12A5A7F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-18 01:00:52.584833 :C12A5A80:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-18 01:00:52.584834 :C12A5A81:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-18 01:00:52.584835 :C12A5A82:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-18 01:00:52.584835 :C12A5A83:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-18 01:00:52.585335 :C12A5A84: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-18 01:00:52.585378 :C12A5A8A: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-18 01:00:52.592830 :C12A5A98: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-18 01:00:52.593871 :C12A5AA0: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-18 01:00:52.594408 :C12A5AA8: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-18 01:00:52.594863 :C12A5AB0: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-18 01:00:52.595361 :C12A5AB8: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-18 01:00:52.597737 :C12A5AC0: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-18 01:00:52.598819 :C12A5AC8: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-18 01:00:52.650959 :C12A5ADB: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-18 01:00:52.651910 :C12A5AE3: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-18 01:00:52.652160 :C12A5AF9: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-18 01:00:52.652457 :C12A5B01: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-18 01:00:52.654380 :C12A5B09:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 01:30:57.896847 :C12CC1D2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 01:30:57.883 2025-08-18 01:30:57.896850 :C12CC1D3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19964 pso_num=29 pso_serial#=24 2025-08-18 01:30:57.896994 :C12CC1D4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19964 (legacy spawn) 2025-08-18 01:30:57.903584 :C12CC1D5: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-18 01:30:57.903749 :C12CC1DE: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-18 01:30:57.904553 :C12CC1DF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-18 22:00:02.053354 :C18C9374:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-18 22:00:02.042 2025-08-18 22:00:02.053355 :C18C9375:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19788 pso_num=29 pso_serial#=25 2025-08-18 22:00:02.053487 :C18C9377:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19788 osp_idx=27 osp_ver=16089 osp_pg=0 (spawn #502492) 2025-08-18 22:00:02.053488 :C18C9378: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-18 22:00:02.053488 :C18C937A:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=32 time=4235071361 2025-08-18 22:00:02.053488 :C18C937B:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-18 22:00:02.053489 :C18C937C:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-18 22:00:02.053489 :C18C937D:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-18 22:00:02.053489 :C18C937E:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-18 22:00:02.053490 :C18C937F:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-18 22:00:02.053972 :C18C9387: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-18 22:00:02.058654 :C18C939C: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-18 22:00:02.058689 :C18C939F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-18 22:00:02.075930 :C18C957C: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-18 22:00:02.076132 :C18C9584: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-18 22:00:02.076299 :C18C958C: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-18 22:00:02.076455 :C18C9594: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-18 22:00:02.076622 :C18C959C: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-18 22:00:02.076780 :C18C95A4: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-18 22:00:02.076946 :C18C95AC: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-18 22:00:02.077114 :C18C95B4: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-18 22:00:02.077268 :C18C95B7: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-18 22:00:02.077397 :C18C95BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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-08-18 22:00:02.077419 :C18C95C1: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-18 22:00:02.077570 :C18C95D3: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-18 22:00:02.077738 :C18C95DB: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-18 22:00:02.077890 :C18C95E3: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-18 22:00:02.078037 :C18C95EB: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-18 22:00:02.078273 :C18C95F3: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-18 22:00:02.078538 :C18C95FB: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-18 22:00:02.078695 :C18C9603: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-18 22:00:02.078859 :C18C960B: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-18 22:00:02.079021 :C18C9613: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-18 22:00:02.079175 :C18C961B: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-18 22:00:02.079330 :C18C9623: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-18 22:00:02.079485 :C18C962B: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-18 22:00:02.079645 :C18C9633: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-18 22:00:02.079794 :C18C963B: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-18 22:00:02.080260 :C18C9643: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-18 22:00:02.080491 :C18C964B: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-18 22:00:02.083888 :C18C9657:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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-08-18 22:00:02.108530 :C18C968A: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-18 22:00:03.698439*:C18C9907:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC83AD0 estart=1755525603 eid=21549474 ctx=0x0000000777968158 cwh=0x0000000058380158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-18 22:00:03.698439*:C18C9908:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC83AD0 curCtx=0x0000000058281BF8 pubTabIdxCnt=0 2025-08-18 22:00:03.698439*:C18C9909:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC83AD0 2025-08-18 22:00:03.698439*:C18C990A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC83AD0 2025-08-18 22:00:03.698439*:C18C990B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DCFB6F0 estart=1755525601 eid=16777531 ctx=0x000000077B54EAC8 cwh=0x000000002DCF0068 cpu=1390625 buffg=73096 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-18 22:00:03.698439*:C18C990C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DCFB6F0 curCtx=0x0000000058277FD8 pubTabIdxCnt=0 2025-08-18 22:00:03.698439*:C18C990D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DCFB6F0 2025-08-18 22:00:03.698439*:C18C990E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DCFB6F0 2025-08-18 22:00:03.698439*:C18C990F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC83AD0 curCtx=0x0000000058281BF8 xsc->flg4=65792 flags=1 2025-08-18 22:00:03.698439*:C18C9910:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC83AD0 2025-08-18 22:00:03.828738 :C18C9981:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 0, nex 3 2025-08-18 22:00:03.828746 :C18C9982:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00815e00 where: kdiwh22: kdifind 2025-08-18 22:00:03.828746*:C18C998B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DCFB6F0 curCtx=0x0000000058277FD8 xsc->flg4=65792 flags=2 2025-08-18 22:00:03.828746*:C18C998C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DCFB6F0 2025-08-18 22:00:03.831958 :C18C998D: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-18 22:00:03.854263 :C18C9996: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-18 22:00:03.856664 :C18C999E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-18 22:00:03.856879 :C18C999F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-18 22:00:03.857876 :C18C99A0: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-18 22:00:03.857970 :C18C99A3: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-18 22:00:03.858852 :C18C99B5: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-18 22:00:03.860426 :C18C99BD: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-18 22:00:03.860724 :C18C99C5: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-18 22:00:03.917187*: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-18 22:00:03.917187*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-18 22:00:03.917187*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-18 22:00:03.932812*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-18 22:00:03.932812*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-18 22:00:03.932812*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-18 22:00:03.932812*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-18 22:00:04.009116 :C18C9A94: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-18 22:00:05.882622 :C18C9C0C: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-18 22:00:05.882634 :C18C9C0D:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.013.0001d9ab 2025-08-18 22:00:08.266913 :C18C9DAA: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-18 22:00:08.268314 :C18C9DB2: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-18 22:00:08.278805 :C18C9DBA: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-18 22:00:08.307537 :C18C9DC2: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: 19788, J003)