Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250720060006\orcl_j003_19172_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:36169M/63366M, Ph+PgF:42074M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 19172, image: ORACLE.EXE (J003) *** 2025-07-20 06:00:06.569 *** SESSION ID:(233.29585) 2025-07-20 06:00:06.569 *** 2025-07-20 06:00:06.569 Process diagnostic dump for ORACLE.EXE (J003), OS id=19172, pid: 29, proc_ser: 206, sid: 233, sess_ser: 29585 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19172 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12783, hash value=353815305 Current Wait Stack: Not in wait; last wait ended 2.734164 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.734190 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.000169 sec, exc=0.000169 sec, total=0.000169 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.067189 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.000009 sec, exc=0.000009 sec, total=0.000009 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.028687 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.000170 sec, exc=0.000170 sec, total=0.000170 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.734756 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.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.026775 sec of elapsed time 4: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012885 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.000065 sec, exc=0.000065 sec, total=0.000065 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049615 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.009953 sec, exc=0.009953 sec, total=0.009953 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007849 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.000065 sec, exc=0.000065 sec, total=0.000065 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.260747 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.003346 sec, exc=0.003346 sec, total=0.003346 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000178 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.000128 sec, exc=0.000128 sec, total=0.000128 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007944 sec of elapsed time Sampled Session History of session 233 serial 29585 --------------------------------------------------- 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-07-20 06:00:06.569 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 19172, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-20 00:04:54.839394 :B4527AF9: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-07-20 00:04:54.840210 :B4527AFA:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:03.514694 :B4527DDC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:03.507 2025-07-20 00:05:03.514696 :B4527DDD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18880 pso_num=29 pso_serial#=187 2025-07-20 00:05:03.514833 :B4527DDE:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18880 (legacy spawn) 2025-07-20 00:05:03.521273 :B4527DDF: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-07-20 00:05:03.521449 :B4527DE4: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-07-20 00:05:03.522255 :B4527DE9:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:07.541519 :B4527F3E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:07.532 2025-07-20 00:05:07.541520 :B4527F3F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15556 pso_num=29 pso_serial#=188 2025-07-20 00:05:07.541662 :B4527F40:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15556 (legacy spawn) 2025-07-20 00:05:07.548127 :B4527F41: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-07-20 00:05:07.548314 :B4527F49: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-07-20 00:05:07.549136 :B4527F57:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:27.991255 :B45285B2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:27.977 2025-07-20 00:05:27.991257 :B45285B3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18160 pso_num=29 pso_serial#=189 2025-07-20 00:05:27.991401 :B45285B4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18160 (legacy spawn) 2025-07-20 00:05:27.997804 :B45285B5: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-07-20 00:05:27.997976 :B45285BA: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-07-20 00:05:27.998814 :B45285BF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:28.007438 :B45285C2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:27.992 2025-07-20 00:05:28.007440 :B45285C3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18064 pso_num=29 pso_serial#=190 2025-07-20 00:05:28.007580 :B45285C4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18064 (legacy spawn) 2025-07-20 00:05:28.014025 :B45285C5: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-07-20 00:05:28.014189 :B45285CE: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-07-20 00:05:28.014984 :B45285CF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:31.301811 :B45286F7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:31.288 2025-07-20 00:05:31.301813 :B45286F8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10744 pso_num=29 pso_serial#=191 2025-07-20 00:05:31.301954 :B45286F9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10744 (legacy spawn) 2025-07-20 00:05:31.308438 :B45286FA: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-07-20 00:05:31.308630 :B4528703: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-07-20 00:05:31.309450 :B4528704:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:31.318190 :B4528707:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:31.304 2025-07-20 00:05:31.318192 :B4528708:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18652 pso_num=29 pso_serial#=192 2025-07-20 00:05:31.318327 :B4528709:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18652 (legacy spawn) 2025-07-20 00:05:31.324846 :B452870A: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-07-20 00:05:31.325012 :B4528713: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-07-20 00:05:31.325825 :B4528714:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:34.603737 :B4528802:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:34.595 2025-07-20 00:05:34.603739 :B4528803:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18496 pso_num=29 pso_serial#=193 2025-07-20 00:05:34.603874 :B4528804:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18496 (legacy spawn) 2025-07-20 00:05:34.610417 :B4528805: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-07-20 00:05:34.610590 :B452880A: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-07-20 00:05:34.611474 :B452881B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:05:41.750444 :B4528EE9:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:05:41.742 2025-07-20 00:05:41.750446 :B4528EEA:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13660 pso_num=29 pso_serial#=194 2025-07-20 00:05:41.750586 :B4528EEB:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13660 (legacy spawn) 2025-07-20 00:05:41.757038 :B4528EEC: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-07-20 00:05:41.757243 :B4528EF5: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-07-20 00:05:41.758062 :B4528EF6:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:06:04.423007 :B452984D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:06:04.420 2025-07-20 00:06:04.423009 :B452984E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18072 pso_num=29 pso_serial#=195 2025-07-20 00:06:04.423146 :B452984F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18072 (legacy spawn) 2025-07-20 00:06:04.429583 :B4529850: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-07-20 00:06:04.429757 :B4529859: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-07-20 00:06:04.430535 :B452985A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:06:04.439285 :B452985D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:06:04.436 2025-07-20 00:06:04.439287 :B452985E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13376 pso_num=29 pso_serial#=196 2025-07-20 00:06:04.439428 :B452985F:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13376 (legacy spawn) 2025-07-20 00:06:04.445849 :B4529860: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-07-20 00:06:04.446023 :B4529869: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-07-20 00:06:04.446822 :B452986A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:06:05.688499 :B4529893:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:06:05.680 2025-07-20 00:06:05.688502 :B4529894:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12540 pso_num=29 pso_serial#=197 2025-07-20 00:06:05.688642 :B4529895:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12540 (legacy spawn) 2025-07-20 00:06:05.695082 :B4529896: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-07-20 00:06:05.695249 :B452989B: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-07-20 00:06:05.696068 :B45298AC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:06:07.563735 :B452996E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:06:07.555 2025-07-20 00:06:07.563738 :B452996F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8928 pso_num=29 pso_serial#=198 2025-07-20 00:06:07.563877 :B4529970:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8928 (legacy spawn) 2025-07-20 00:06:07.570445 :B4529971: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-07-20 00:06:07.570642 :B4529978: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-07-20 00:06:07.571473 :B452997B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 00:06:07.580216 :B452997E:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 00:06:07.571 2025-07-20 00:06:07.580218 :B452997F:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18940 pso_num=29 pso_serial#=199 2025-07-20 00:06:07.580358 :B4529980:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18940 (legacy spawn) 2025-07-20 00:06:07.586904 :B4529981: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-07-20 00:06:07.587061 :B452998A: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-07-20 00:06:07.587883 :B452998B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 01:00:01.908219 :B456E02C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 01:00:01.894 2025-07-20 01:00:01.908221 :B456E02D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18928 pso_num=29 pso_serial#=200 2025-07-20 01:00:01.908352 :B456E02E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18928 (legacy spawn) 2025-07-20 01:00:01.914788 :B456E02F: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-07-20 01:00:01.914951 :B456E034: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-07-20 01:00:01.915743 :B456E039:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 01:00:01.924466 :B456E03C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 01:00:01.909 2025-07-20 01:00:01.924468 :B456E03D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18704 pso_num=29 pso_serial#=201 2025-07-20 01:00:01.924604 :B456E03E:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18704 (legacy spawn) 2025-07-20 01:00:01.931147 :B456E03F: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-07-20 01:00:01.931325 :B456E048: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-07-20 01:00:01.932142 :B456E049:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 01:00:05.000333 :B456E13A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 01:00:04.987 2025-07-20 01:00:05.000335 :B456E13B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16084 pso_num=29 pso_serial#=202 2025-07-20 01:00:05.000477 :B456E13C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16084 (legacy spawn) 2025-07-20 01:00:05.007017 :B456E13D: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-07-20 01:00:05.007187 :B456E142: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-07-20 01:00:05.007965 :B456E147:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 01:00:05.016568 :B456E14A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 01:00:05.003 2025-07-20 01:00:05.016570 :B456E14B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17564 pso_num=29 pso_serial#=203 2025-07-20 01:00:05.016706 :B456E14C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17564 (legacy spawn) 2025-07-20 01:00:05.023173 :B456E14D: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-07-20 01:00:05.023348 :B456E156: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-07-20 01:00:05.024133 :B456E157:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 01:00:17.239028 :B456E54B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 01:00:17.225 2025-07-20 01:00:17.239030 :B456E54C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19276 pso_num=29 pso_serial#=204 2025-07-20 01:00:17.239163 :B456E54D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19276 (legacy spawn) 2025-07-20 01:00:17.245553 :B456E54E: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-07-20 01:00:17.245730 :B456E553: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-07-20 01:00:17.246513 :B456E558:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 01:00:31.187245 :B456E9A2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 01:00:31.178 2025-07-20 01:00:31.187247 :B456E9A3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=2696 pso_num=29 pso_serial#=205 2025-07-20 01:00:31.187389 :B456E9A4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=2696 (legacy spawn) 2025-07-20 01:00:31.193838 :B456E9A5: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-07-20 01:00:31.193870 :B456E9A8: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-07-20 01:00:31.194032 :B456E9AB: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-07-20 01:00:31.194863 :B456E9BC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-07-20 06:00:02.041849 :B46EB539:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 07-20 06:00:02.035 2025-07-20 06:00:02.041850 :B46EB53A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19172 pso_num=29 pso_serial#=206 2025-07-20 06:00:02.041978 :B46EB53B:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19172 osp_idx=27 osp_ver=14740 osp_pg=0 (spawn #403610) 2025-07-20 06:00:02.041979 :B46EB53C:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 06:00:02.041979 :B46EB53D:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=239 time=1671977939 2025-07-20 06:00:02.041979 :B46EB53E:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 06:00:02.041980 :B46EB53F:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 06:00:02.041980 :B46EB540:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 06:00:02.041980 :B46EB541:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 06:00:02.041981 :B46EB542:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 06:00:02.042449 :B46EB545: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-07-20 06:00:02.047666 :B46EB572: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-07-20 06:00:02.047712 :B46EB576:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-07-20 06:00:02.065323 :B46EB770: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-07-20 06:00:02.065597 :B46EB778: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-07-20 06:00:02.065791 :B46EB780: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-07-20 06:00:02.065949 :B46EB788: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-07-20 06:00:02.066110 :B46EB790: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-07-20 06:00:02.066271 :B46EB798: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-07-20 06:00:02.066450 :B46EB7A0: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-07-20 06:00:02.066609 :B46EB7A8: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-07-20 06:00:02.066761 :B46EB7B0: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-07-20 06:00:02.066907 :B46EB7BE: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-07-20 06:00:02.067057 :B46EB7D7: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-07-20 06:00:02.067207 :B46EB7DF: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-07-20 06:00:02.067347 :B46EB7E7: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-07-20 06:00:02.067541 :B46EB7EF: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-07-20 06:00:02.067779 :B46EB7F7: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-07-20 06:00:02.067923 :B46EB7FF: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-07-20 06:00:02.068066 :B46EB807: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-07-20 06:00:02.068212 :B46EB80F: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-07-20 06:00:02.068352 :B46EB817: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-07-20 06:00:02.068492 :B46EB81F: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-07-20 06:00:02.068637 :B46EB827: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-07-20 06:00:02.068784 :B46EB82F: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-07-20 06:00:02.068920 :B46EB837: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-07-20 06:00:02.088841 :B46EB83F: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-07-20 06:00:03.747223 :B46EBBB5:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 9, ext 1, nex 3 2025-07-20 06:00:03.747231 :B46EBBB6:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00815e05 where: kdiwh22: kdifind 2025-07-20 06:00:03.755768 :B46EBBB7: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-07-20 06:00:03.778155 :B46EBBBF: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-07-20 06:00:03.780432 :B46EBBC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-07-20 06:00:03.780657 :B46EBBC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-07-20 06:00:03.781645 :B46EBBC9: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-07-20 06:00:03.781854 :B46EBBD1: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-07-20 06:00:03.782846 :B46EBBD9: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-07-20 06:00:03.784590 :B46EBBE1: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-07-20 06:00:03.784902 :B46EBBE9: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-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-20 06:00:03.847903*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-20 06:00:03.894774*:B46EBBF9:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000500D0B90 estart=1752962403 eid=16778136 ctx=0x000000079F4EAD08 cwh=0x0000000030150338 cpu=0 buffg=0 interb=0 r=0 rb=0 w=0 wb=0 2025-07-20 06:00:03.894774*:B46EBBFA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000500D0B90 curCtx=0x000000004FE893F8 pubTabIdxCnt=0 2025-07-20 06:00:03.894774*:B46EBBFB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000500D0B90 2025-07-20 06:00:03.894774*:B46EBBFC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000500D0B90 2025-07-20 06:00:03.910400*:B46EBBFF:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000500D0B90 curCtx=0x000000004FE893F8 xsc->flg4=65792 flags=1 2025-07-20 06:00:03.910400*:B46EBC00:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000500D0B90 2025-07-20 06:00:03.922505 :B46EBC01: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-07-20 06:00:05.806809 :B46EBD56: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-07-20 06:00:05.806821 :B46EBD57:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.002.0001d3fb ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 19172, J003)