Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607060006\orcl_j003_15608_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:36828M/63366M, Ph+PgF:43464M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 15608, image: ORACLE.EXE (J003) *** 2025-06-07 06:00:06.594 *** SESSION ID:(233.24539) 2025-06-07 06:00:06.594 *** 2025-06-07 06:00:06.594 Process diagnostic dump for ORACLE.EXE (J003), OS id=15608, pid: 29, proc_ser: 253, sid: 233, sess_ser: 24539 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15608 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12550, hash value=1451341521 Current Wait Stack: Not in wait; last wait ended 2.627113 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.627138 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000177 sec, exc=0.000177 sec, total=0.000177 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.066776 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000010 sec, exc=0.000010 sec, total=0.000010 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.030796 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000175 sec, exc=0.000175 sec, total=0.000175 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.733036 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025278 sec of elapsed time 4: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.013227 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000113 sec, exc=0.000113 sec, total=0.000113 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052952 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.005574 sec, exc=0.005574 sec, total=0.005574 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008070 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000072 sec, exc=0.000072 sec, total=0.000072 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262699 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.005098 sec, exc=0.005098 sec, total=0.005098 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000326 sec of elapsed time 9: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000130 sec, exc=0.000130 sec, total=0.000130 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.009005 sec of elapsed time Sampled Session History of session 233 serial 24539 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [4 samples, 06:00:02 - 06:00:05] 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-06-07 06:00:06.594 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 15608, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 00:05:07.511088 :A1473313:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16084 pso_num=29 pso_serial#=241 2025-06-07 00:05:07.511284 :A1473314:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16084 (legacy spawn) 2025-06-07 00:05:07.517637 :A1473315: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-06-07 00:05:07.517802 :A147331E: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-06-07 00:05:07.518660 :A147331F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:05:10.187428 :A14733FE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:05:10.179 2025-06-07 00:05:10.187429 :A14733FF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15932 pso_num=29 pso_serial#=242 2025-06-07 00:05:10.187569 :A1473400:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15932 (legacy spawn) 2025-06-07 00:05:10.194173 :A1473401: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-06-07 00:05:10.194364 :A1473408: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-06-07 00:05:10.195182 :A147340A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:05:11.376949 :A1473419:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:05:11.367 2025-06-07 00:05:11.376951 :A147341A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16136 pso_num=29 pso_serial#=243 2025-06-07 00:05:11.377091 :A147341B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16136 (legacy spawn) 2025-06-07 00:05:11.383088 :A147341C: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-06-07 00:05:11.391746 :A1473424: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-06-07 00:05:11.391903 :A147342D: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-06-07 00:05:11.392755 :A147342E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:05:58.700472 :A1474B0D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:05:58.695 2025-06-07 00:05:58.700474 :A1474B0E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12684 pso_num=29 pso_serial#=244 2025-06-07 00:05:58.700628 :A1474B0F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12684 (legacy spawn) 2025-06-07 00:05:58.707294 :A1474B10: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-06-07 00:05:58.707496 :A1474B19: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-06-07 00:05:58.708368 :A1474B1A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:05:58.717318 :A1474B1D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:05:58.710 2025-06-07 00:05:58.717320 :A1474B1E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12492 pso_num=29 pso_serial#=245 2025-06-07 00:05:58.717452 :A1474B1F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12492 (legacy spawn) 2025-06-07 00:05:58.723932 :A1474B20: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-06-07 00:05:58.724092 :A1474B29: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-06-07 00:05:58.724906 :A1474B2A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:06:01.843230 :A1474C18:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:06:01.830 2025-06-07 00:06:01.843232 :A1474C19:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14960 pso_num=29 pso_serial#=246 2025-06-07 00:06:01.843372 :A1474C1A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14960 (legacy spawn) 2025-06-07 00:06:02.208024 :A1474C1B: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-06-07 00:06:02.208208 :A1474C20: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-06-07 00:06:02.209022 :A1474C25:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:06:02.217841 :A1474C28:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:06:02.205 2025-06-07 00:06:02.217843 :A1474C29:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14144 pso_num=29 pso_serial#=247 2025-06-07 00:06:02.217977 :A1474C2A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14144 (legacy spawn) 2025-06-07 00:06:02.224432 :A1474C2B: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-06-07 00:06:02.224592 :A1474C34: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-06-07 00:06:02.225430 :A1474C35:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:06:12.316825 :A1474F95:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:06:12.308 2025-06-07 00:06:12.316827 :A1474F96:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13284 pso_num=29 pso_serial#=248 2025-06-07 00:06:12.316968 :A1474F97:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13284 (legacy spawn) 2025-06-07 00:06:12.323446 :A1474F98: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-06-07 00:06:12.323612 :A1474F9D: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-06-07 00:06:12.324490 :A1474FAA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 00:06:15.601802 :A147509C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 00:06:15.592 2025-06-07 00:06:15.601804 :A147509D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16160 pso_num=29 pso_serial#=249 2025-06-07 00:06:15.601939 :A147509E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16160 (legacy spawn) 2025-06-07 00:06:15.608508 :A147509F: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-06-07 00:06:15.608681 :A14750A4: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-06-07 00:06:15.609489 :A14750A9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 01:00:05.508132 :A14B9514:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 01:00:05.499 2025-06-07 01:00:05.508134 :A14B9515:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10320 pso_num=29 pso_serial#=250 2025-06-07 01:00:05.508274 :A14B9516:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10320 (legacy spawn) 2025-06-07 01:00:05.514810 :A14B9517: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-06-07 01:00:05.514975 :A14B9520: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-06-07 01:00:05.515813 :A14B9521:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 01:00:15.921290 :A14B9860:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 01:00:15.912 2025-06-07 01:00:15.921291 :A14B9861:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=6832 pso_num=29 pso_serial#=251 2025-06-07 01:00:15.921428 :A14B9862:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=6832 (legacy spawn) 2025-06-07 01:00:15.927864 :A14B9863: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-06-07 01:00:15.928051 :A14B9868: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-06-07 01:00:15.928826 :A14B986D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 01:00:30.469215 :A14B9D3C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 01:00:30.464 2025-06-07 01:00:30.469217 :A14B9D3D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4200 pso_num=29 pso_serial#=252 2025-06-07 01:00:30.469353 :A14B9D3E:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=4200 osp_idx=27 osp_ver=12236 osp_pg=0 (spawn #259550) 2025-06-07 01:00:30.469354 :A14B9D3F:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 01:00:30.469355 :A14B9D40:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=33 time=2233789110 2025-06-07 01:00:30.469356 :A14B9D41:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 01:00:30.469357 :A14B9D42:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 01:00:30.469358 :A14B9D43:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 01:00:30.469358 :A14B9D44:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 01:00:30.469358 :A14B9D45:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 01:00:30.469856 :A14B9D46: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-06-07 01:00:30.469903 :A14B9D4C: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-06-07 01:00:30.474386 :A14B9D5F: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-06-07 01:00:30.499518 :A14B9D67: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-06-07 01:00:30.514775 :A14B9D6F: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-06-07 01:00:30.542405 :A14B9D77: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-06-07 01:00:30.546108 :A14B9D7F: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-06-07 01:00:30.546435 :A14B9D87: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-06-07 01:00:30.550202 :A14B9D8F: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-06-07 01:00:30.552789 :A14B9D97: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-06-07 01:00:30.554423 :A14B9D9F: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-06-07 01:00:30.555464 :A14B9DA7: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-06-07 01:00:30.559174 :A14B9DAF: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-06-07 01:00:31.672167*:A14B9E26:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B4687A8 estart=1749229230 eid=16779039 ctx=0x000000077BA4B1C8 cwh=0x000000002B420738 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-06-07 01:00:31.672167*:A14B9E27:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B4687A8 curCtx=0x000000002B52AD38 pubTabIdxCnt=0 2025-06-07 01:00:31.672167*:A14B9E28:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B4687A8 2025-06-07 01:00:31.672167*:A14B9E29:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B4687A8 2025-06-07 01:00:31.672167*:A14B9E2A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B466678 estart=1749229230 eid=16779029 ctx=0x0000000787AD5BF0 cwh=0x000000002B420828 cpu=500000 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-06-07 01:00:31.672167*:A14B9E2B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B466678 curCtx=0x000000002B52CC40 pubTabIdxCnt=0 2025-06-07 01:00:31.672167*:A14B9E2C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B466678 2025-06-07 01:00:31.672167*:A14B9E2D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B466678 2025-06-07 01:00:31.734670*:A14B9E2E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B4687A8 curCtx=0x000000002B52AD38 xsc->flg4=65792 flags=1 2025-06-07 01:00:31.734670*:A14B9E2F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B4687A8 2025-06-07 01:00:31.734670*:A14B9E30:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B466678 curCtx=0x000000002B52CC40 xsc->flg4=65792 flags=2 2025-06-07 01:00:31.734670*:A14B9E31:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B466678 2025-06-07 01:00:31.811873 :A14B9E32: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-06-07 01:00:31.845007 :A14B9E3A: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-06-07 01:00:31.845328 :A14B9E42: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-06-07 01:00:31.845597 :A14B9E4A: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-06-07 01:00:31.845613 :A14B9E4D: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-06-07 01:00:31.845682 :A14B9E5B: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-06-07 01:00:31.848367 :A14B9E67:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-07 06:00:02.050388 :A163A196:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-07 06:00:02.044 2025-06-07 06:00:02.050390 :A163A197:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15608 pso_num=29 pso_serial#=253 2025-06-07 06:00:02.050528 :A163A19D:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15608 osp_idx=27 osp_ver=12237 osp_pg=0 (spawn #260247) 2025-06-07 06:00:02.050528 :A163A19E:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 06:00:02.050529 :A163A19F:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=34 time=2251760704 2025-06-07 06:00:02.050529 :A163A1A0:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 06:00:02.050530 :A163A1A1:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 06:00:02.050532 :A163A1A2:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 06:00:02.050532 :A163A1A3:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 06:00:02.050532 :A163A1A4:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 06:00:02.051081 :A163A1A5: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-06-07 06:00:02.056107 :A163A1C4: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-06-07 06:00:02.056145 :A163A1C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-07 06:00:02.073880 :A163A3AC: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-06-07 06:00:02.074237 :A163A3B4: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-06-07 06:00:02.074426 :A163A3BC: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-06-07 06:00:02.074599 :A163A3C4: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-06-07 06:00:02.074759 :A163A3CC: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-06-07 06:00:02.074915 :A163A3D4: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-06-07 06:00:02.075076 :A163A3DC: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-06-07 06:00:02.075253 :A163A3E4: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-06-07 06:00:02.075414 :A163A3EC: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-06-07 06:00:02.075565 :A163A3F4: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-06-07 06:00:02.075721 :A163A3FC: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-06-07 06:00:02.075874 :A163A404: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-06-07 06:00:02.076025 :A163A40C: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-06-07 06:00:02.076179 :A163A414: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-06-07 06:00:02.076329 :A163A41C: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-06-07 06:00:02.076496 :A163A424: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-06-07 06:00:02.076661 :A163A42C: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-06-07 06:00:02.076854 :A163A434: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-06-07 06:00:02.077076 :A163A43C: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-06-07 06:00:02.077243 :A163A444: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-06-07 06:00:02.077398 :A163A44C: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-06-07 06:00:02.077558 :A163A454: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-06-07 06:00:02.077712 :A163A45C: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-06-07 06:00:02.077867 :A163A464: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-06-07 06:00:02.078024 :A163A46C: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-06-07 06:00:02.078678 :A163A474: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-06-07 06:00:02.078858 :A163A47C: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-06-07 06:00:02.105374 :A163A484: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-06-07 06:00:03.638807*:A163A731:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031633AD0 estart=1749247203 eid=19129949 ctx=0x0000000777968158 cwh=0x0000000042830158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-07 06:00:03.638807*:A163A732:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031633AD0 curCtx=0x0000000042731BF8 pubTabIdxCnt=0 2025-06-07 06:00:03.638807*:A163A733:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031633AD0 2025-06-07 06:00:03.638807*:A163A734:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031633AD0 2025-06-07 06:00:03.638807*:A163A735:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000316AB6F0 estart=1749247201 eid=16777371 ctx=0x000000077B54EAC8 cwh=0x00000000316A0068 cpu=1359375 buffg=67408 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-07 06:00:03.638807*:A163A736:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000316AB6F0 curCtx=0x0000000042727FD8 pubTabIdxCnt=0 2025-06-07 06:00:03.638807*:A163A737:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000316AB6F0 2025-06-07 06:00:03.638807*:A163A738:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000316AB6F0 2025-06-07 06:00:03.638807*:A163A739:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031633AD0 curCtx=0x0000000042731BF8 xsc->flg4=65792 flags=1 2025-06-07 06:00:03.638807*:A163A73A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031633AD0 2025-06-07 06:00:03.879528 :A163A7FE:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 0, nex 2 2025-06-07 06:00:03.879535 :A163A7FF:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00815e01 where: kdiwh22: kdifind 2025-06-07 06:00:03.882862 :A163A82C: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-06-07 06:00:03.888811*:A163A845:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000316AB6F0 curCtx=0x0000000042727FD8 xsc->flg4=65792 flags=2 2025-06-07 06:00:03.888811*:A163A846:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000316AB6F0 2025-06-07 06:00:03.890339 :A163A847: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-06-07 06:00:03.912425 :A163A851: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-06-07 06:00:03.914777 :A163A859:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-07 06:00:03.915028 :A163A85A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-07 06:00:03.916107 :A163A85B: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-06-07 06:00:03.916233 :A163A85E: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-06-07 06:00:03.917281 :A163A870: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-06-07 06:00:03.919042 :A163A878: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-06-07 06:00:03.919372 :A163A880: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-06-07 06:00:03.982581*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-07 06:00:03.982581*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-07 06:00:03.982581*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-07 06:00:04.013819*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-07 06:00:04.013819*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-07 06:00:04.013819*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-07 06:00:04.013819*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-07 06:00:04.085347 :A163A8A3: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-06-07 06:00:06.033860 :A163A97A: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-06-07 06:00:06.033874 :A163A97B:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.01d.0001cb8b ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 15608, J003)