Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817060009\orcl_j003_14192_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:35405M/63366M, Ph+PgF:40667M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 14192, image: ORACLE.EXE (J003) *** 2025-08-17 06:00:09.119 *** SESSION ID:(233.32197) 2025-08-17 06:00:09.119 *** 2025-08-17 06:00:09.119 Process diagnostic dump for ORACLE.EXE (J003), OS id=14192, pid: 29, proc_ser: 223, sid: 233, sess_ser: 32197 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14192 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12931, hash value=2639180690 Current Wait Stack: Not in wait; last wait ended 1.116164 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.116190 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.000222 sec, exc=0.000222 sec, total=0.000222 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'log file sequential read' log#=0x29d3, block#=0x0, blocks=0x0 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052401 sec of elapsed time 2: waited for 'log file sequential read' log#=0x29d3, block#=0x0, blocks=0x0 wait_id=125 seq_num=126 snap_id=1 wait times: snap=0.000027 sec, exc=0.000027 sec, total=0.000027 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.470713 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0xc800, blocks=0x800 wait_id=124 seq_num=125 snap_id=1 wait times: snap=0.000351 sec, exc=0.000351 sec, total=0.000351 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052527 sec of elapsed time 4: waited for 'log file sequential read' log#=0x29d3, block#=0x800, blocks=0x800 wait_id=123 seq_num=124 snap_id=1 wait times: snap=0.000212 sec, exc=0.000212 sec, total=0.000212 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.003225 sec of elapsed time 5: waited for 'log file sequential read' log#=0x29d3, block#=0x0, blocks=0x0 wait_id=122 seq_num=123 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.000126 sec of elapsed time 6: waited for 'log file sequential read' log#=0x29d3, block#=0x2, blocks=0x7fe wait_id=121 seq_num=122 snap_id=1 wait times: snap=0.000198 sec, exc=0.000198 sec, total=0.000198 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000165 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=120 seq_num=121 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=119 seq_num=120 snap_id=1 wait times: snap=0.000203 sec, exc=0.000203 sec, total=0.000203 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=118 seq_num=119 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time Sampled Session History of session 233 serial 32197 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 06:00:02 - 06:00:08] not in wait at each sample [1 sample, 06:00:01] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-17 06:00:09.119 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 14192, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 00:04:26.686717 :C0B4AE7A:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 00:04:26.686718 :C0B4AE7B:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 00:04:26.687209 :C0B4AE7C: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-17 00:04:56.747988 :C0B4B999:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 00:05:29.886087 :C0B4C5EE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 00:05:29.871 2025-08-17 00:05:29.886090 :C0B4C5EF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15216 pso_num=29 pso_serial#=208 2025-08-17 00:05:29.886225 :C0B4C5F0:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15216 (legacy spawn) 2025-08-17 00:05:29.892692 :C0B4C5F1: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 00:05:29.892856 :C0B4C5FA: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 00:05:29.893631 :C0B4C5FB:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 00:05:29.910855 :C0B4C5FE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 00:05:29.903 2025-08-17 00:05:29.910857 :C0B4C5FF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4492 pso_num=29 pso_serial#=209 2025-08-17 00:05:29.910989 :C0B4C600:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4492 (legacy spawn) 2025-08-17 00:05:29.917453 :C0B4C601: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 00:05:29.917626 :C0B4C60A: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 00:05:29.918448 :C0B4C60B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 00:05:33.372289 :C0B4C709:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 00:05:33.370 2025-08-17 00:05:33.372291 :C0B4C70A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18956 pso_num=29 pso_serial#=210 2025-08-17 00:05:33.372433 :C0B4C70B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=18956 (legacy spawn) 2025-08-17 00:05:33.378857 :C0B4C70C: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 00:05:33.379178 :C0B4C711: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 00:05:33.379985 :C0B4C716:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 00:05:33.388700 :C0B4C719:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 00:05:33.386 2025-08-17 00:05:33.388701 :C0B4C71A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22292 pso_num=29 pso_serial#=211 2025-08-17 00:05:33.388839 :C0B4C71B:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22292 (legacy spawn) 2025-08-17 00:05:33.395392 :C0B4C71C: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 00:05:33.395554 :C0B4C725: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 00:05:33.396337 :C0B4C726:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 00:05:35.001505 :C0B4C7B4:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 00:05:34.991 2025-08-17 00:05:35.001508 :C0B4C7B5:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21828 pso_num=29 pso_serial#=212 2025-08-17 00:05:35.001653 :C0B4C7B6:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21828 (legacy spawn) 2025-08-17 00:05:35.024503 :C0B4C7C4: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 00:05:35.024695 :C0B4C7C9: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 00:05:35.025665 :C0B4C7CE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 00:05:56.107684 :C0B4D3FD:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 00:05:56.099 2025-08-17 00:05:56.107687 :C0B4D3FE:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21272 pso_num=29 pso_serial#=213 2025-08-17 00:05:56.107830 :C0B4D3FF:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21272 (legacy spawn) 2025-08-17 00:05:56.114566 :C0B4D400: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 00:05:56.114785 :C0B4D405: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 00:05:56.115722 :C0B4D40A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:04.321343 :C0B90980:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:04.306 2025-08-17 01:00:04.321345 :C0B90981:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20392 pso_num=29 pso_serial#=214 2025-08-17 01:00:04.321485 :C0B90982:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20392 (legacy spawn) 2025-08-17 01:00:04.328029 :C0B90983: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 01:00:04.328203 :C0B90988: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 01:00:04.329026 :C0B9098D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:04.346524 :C0B90990:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:04.338 2025-08-17 01:00:04.346526 :C0B90991:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22340 pso_num=29 pso_serial#=215 2025-08-17 01:00:04.346663 :C0B90992:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22340 (legacy spawn) 2025-08-17 01:00:04.353223 :C0B90993: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 01:00:04.353387 :C0B9099C: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 01:00:04.354232 :C0B9099D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:07.423245 :C0B90A98:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:07.410 2025-08-17 01:00:07.423247 :C0B90A99:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20520 pso_num=29 pso_serial#=216 2025-08-17 01:00:07.423383 :C0B90A9A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20520 (legacy spawn) 2025-08-17 01:00:07.429882 :C0B90A9B: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 01:00:07.430050 :C0B90AA4: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 01:00:07.430855 :C0B90AA5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:07.439665 :C0B90AA8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:07.426 2025-08-17 01:00:07.439667 :C0B90AA9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19264 pso_num=29 pso_serial#=217 2025-08-17 01:00:07.439802 :C0B90AAA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19264 (legacy spawn) 2025-08-17 01:00:07.446339 :C0B90ACE: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 01:00:07.446509 :C0B90AD6: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 01:00:07.447327 :C0B90AD7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:10.447426 :C0B90BA3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:10.436 2025-08-17 01:00:10.447428 :C0B90BA4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19212 pso_num=29 pso_serial#=218 2025-08-17 01:00:10.447561 :C0B90BA5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19212 (legacy spawn) 2025-08-17 01:00:10.454977 :C0B90BC9: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 01:00:10.455150 :C0B90BD2: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 01:00:10.455961 :C0B90BD3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:20.922976 :C0B90F02:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:20.914 2025-08-17 01:00:20.922978 :C0B90F03:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21720 pso_num=29 pso_serial#=219 2025-08-17 01:00:20.923117 :C0B90F04:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21720 (legacy spawn) 2025-08-17 01:00:20.929587 :C0B90F05: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 01:00:20.929763 :C0B90F0A: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 01:00:20.930565 :C0B90F0F:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:40.609208 :C0B91696:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:40.594 2025-08-17 01:00:40.609210 :C0B91697:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20716 pso_num=29 pso_serial#=220 2025-08-17 01:00:40.609408 :C0B91698:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20716 (legacy spawn) 2025-08-17 01:00:40.616829 :C0B91699: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 01:00:40.617023 :C0B916A2: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 01:00:40.617827 :C0B916A3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:40.626874 :C0B916A8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:40.625 2025-08-17 01:00:40.626875 :C0B916A9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22356 pso_num=29 pso_serial#=221 2025-08-17 01:00:40.627022 :C0B916AA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22356 (legacy spawn) 2025-08-17 01:00:40.633639 :C0B916AB: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 01:00:40.633662 :C0B916AE: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 01:00:40.633838 :C0B916B5: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 01:00:40.634748 :C0B916C2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 01:00:41.876927 :C0B91748:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 01:00:41.875 2025-08-17 01:00:41.876929 :C0B91749:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21580 pso_num=29 pso_serial#=222 2025-08-17 01:00:41.877063 :C0B9174A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21580 osp_idx=27 osp_ver=16031 osp_pg=0 (spawn #496239) 2025-08-17 01:00:41.877063 :C0B9174B: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-17 01:00:41.877064 :C0B9174C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=14 time=4073111173 2025-08-17 01:00:41.877064 :C0B9174D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 01:00:41.877064 :C0B9174E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 01:00:41.877065 :C0B9174F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 01:00:41.877065 :C0B91750:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 01:00:41.877066 :C0B91751:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 01:00:41.877564 :C0B91752: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-17 01:00:41.877610 :C0B91759: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-17 01:00:41.885099 :C0B91766: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 01:00:41.886165 :C0B9176E: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 01:00:41.886717 :C0B91776: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 01:00:41.887175 :C0B9177E: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 01:00:41.887693 :C0B91786: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 01:00:41.890178 :C0B9178E: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 01:00:41.891275 :C0B91796: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 01:00:41.942650 :C0B917CC: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 01:00:41.943530 :C0B917D4: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 01:00:41.943783 :C0B917DC: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 01:00:41.944063 :C0B917E4: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 01:00:41.945839 :C0B917EC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-17 06:00:02.043385 :C0D092AE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-17 06:00:02.032 2025-08-17 06:00:02.043387 :C0D092AF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14192 pso_num=29 pso_serial#=223 2025-08-17 06:00:02.043522 :C0D092B5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14192 osp_idx=27 osp_ver=16032 osp_pg=0 (spawn #496931) 2025-08-17 06:00:02.043523 :C0D092B6: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-17 06:00:02.043523 :C0D092B7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=15 time=4091071345 2025-08-17 06:00:02.043524 :C0D092B8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 06:00:02.043524 :C0D092B9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 06:00:02.043525 :C0D092BA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 06:00:02.043525 :C0D092BB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 06:00:02.043526 :C0D092BC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 06:00:02.044066 :C0D092C6: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-17 06:00:02.048952 :C0D092DB: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 06:00:02.049003 :C0D092DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-17 06:00:02.066550 :C0D094BD: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 06:00:02.066753 :C0D094C5: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 06:00:02.066920 :C0D094CD: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 06:00:02.067084 :C0D094D5: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 06:00:02.067263 :C0D094DD: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 06:00:02.067427 :C0D094E5: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 06:00:02.067580 :C0D094ED: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 06:00:02.067726 :C0D094F5: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 06:00:02.067877 :C0D094FD: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 06:00:02.068040 :C0D09505: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 06:00:02.068187 :C0D0950D: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 06:00:02.068331 :C0D09515: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 06:00:02.068546 :C0D0951D: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 06:00:02.068717 :C0D09525: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 06:00:02.068872 :C0D0952D: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 06:00:02.069028 :C0D09535: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 06:00:02.069182 :C0D0953D: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 06:00:02.069329 :C0D09545: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 06:00:02.069470 :C0D0954E: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 06:00:02.069613 :C0D09556: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 06:00:02.069766 :C0D0955E: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 06:00:02.069926 :C0D09566: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 06:00:02.092669 :C0D0957D: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 06:00:03.306767*:C0D097C1:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DB83AD0 estart=1755381603 eid=21469861 ctx=0x0000000777968158 cwh=0x000000002DBB09C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-17 06:00:03.306767*:C0D097C2:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DB83AD0 curCtx=0x0000000058287918 pubTabIdxCnt=0 2025-08-17 06:00:03.306767*:C0D097C3:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DB83AD0 2025-08-17 06:00:03.306767*:C0D097C4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DB83AD0 2025-08-17 06:00:03.306767*:C0D097C5:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DBFB6F0 estart=1755381601 eid=16777526 ctx=0x000000077B54EAC8 cwh=0x000000002DBB0518 cpu=1015625 buffg=55852 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-17 06:00:03.306767*:C0D097C6:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DBFB6F0 curCtx=0x00000000581687D0 pubTabIdxCnt=0 2025-08-17 06:00:03.306767*:C0D097C7:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DBFB6F0 2025-08-17 06:00:03.306767*:C0D097C8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DBFB6F0 2025-08-17 06:00:03.306767*:C0D097C9:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DB83AD0 curCtx=0x0000000058287918 xsc->flg4=65792 flags=1 2025-08-17 06:00:03.306767*:C0D097CA:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DB83AD0 2025-08-17 06:00:03.791153*:C0D0992E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DBFB6F0 curCtx=0x00000000581687D0 xsc->flg4=65792 flags=2 2025-08-17 06:00:03.791153*:C0D0992F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DBFB6F0 2025-08-17 06:00:03.792594 :C0D09930: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 06:00:03.814848 :C0D09938: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 06:00:03.817212 :C0D09940:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-17 06:00:03.817449 :C0D09941:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-17 06:00:03.818442 :C0D09942: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 06:00:03.818545 :C0D09945: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 06:00:03.819503 :C0D09957: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 06:00:03.821304 :C0D0995F: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 06:00:03.821622 :C0D09967: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 06:00:03.884900*: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-17 06:00:03.884900*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-17 06:00:03.884900*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-17 06:00:03.884900*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-17 06:00:03.884900*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-17 06:00:03.884900*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-17 06:00:03.884900*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-17 06:00:03.958814 :C0D0996F: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 06:00:05.858704 :C0D09A18: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 06:00:05.858716 :C0D09A19:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.018.0001d963 2025-08-17 06:00:08.458374 :C0D09B88: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 06:00:08.459803 :C0D09B90: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 06:00:08.469695 :C0D09BBB: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 06:00:08.497854 :C0D09BC3: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: 14192, J003)