Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250623220008\orcl_j003_18420_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250623220008\orcl_j003_18420_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:36315M/63366M, Ph+PgF:42504M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 18420, image: ORACLE.EXE (J003) *** 2025-06-23 22:00:08.325 *** SESSION ID:(233.26733) 2025-06-23 22:00:08.325 *** 2025-06-23 22:00:08.325 Process diagnostic dump for ORACLE.EXE (J003), OS id=18420, pid: 29, proc_ser: 67, sid: 233, sess_ser: 26733 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18420 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12646, hash value=2643442368 Current Wait Stack: Not in wait; last wait ended 0.592226 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.592252 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000303 sec, exc=0.000303 sec, total=0.000303 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 1: waited for 'log file sequential read' log#=0x2897, block#=0x0, blocks=0x0 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.043065 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2897, block#=0x0, blocks=0x0 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000019 sec, exc=0.000019 sec, total=0.000019 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.495063 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x3800, blocks=0x800 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000303 sec, exc=0.000303 sec, total=0.000303 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.004298 sec of elapsed time 4: waited for 'log file sequential read' log#=0x2897, block#=0x800, blocks=0x800 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000211 sec, exc=0.000211 sec, total=0.000211 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002096 sec of elapsed time 5: waited for 'log file sequential read' log#=0x2897, block#=0x0, blocks=0x0 wait_id=134 seq_num=135 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000129 sec of elapsed time 6: waited for 'log file sequential read' log#=0x2897, block#=0x2, blocks=0x7fe wait_id=133 seq_num=134 snap_id=1 wait times: snap=0.000206 sec, exc=0.000206 sec, total=0.000206 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000174 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=132 seq_num=133 snap_id=1 wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000003 sec of elapsed time 8: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x0, filetype=0x3 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000196 sec, exc=0.000196 sec, total=0.000196 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000120 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 233 serial 26733 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:03 - 22:00:07] not in wait at each sample [1 sample, 22:00:02] waited for 'db file sequential read', seq_num: 36 p1: 'file#'=0x2 p2: 'block#'=0x1e5a p3: 'blocks'=0x1 time_waited: 0.008552 sec (sample interval: 0 sec) [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 22:00:02 ] time_waited: 0.008552 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-23 22:00:08.325 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 18420, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-23 01:40:32.299696 :A8586755:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-06-23 01:40:32.302078 :A8586756:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 02:00:34.724020 :A859FED0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 02:00:34.715 2025-06-23 02:00:34.724021 :A859FED1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18364 pso_num=29 pso_serial#=59 2025-06-23 02:00:34.724155 :A859FED2:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18364 osp_idx=27 osp_ver=13318 osp_pg=0 (spawn #313065) 2025-06-23 02:00:34.724156 :A859FED3: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-23 02:00:34.724157 :A859FED4:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=143 time=3619777907 2025-06-23 02:00:34.724157 :A859FED5:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 02:00:34.724157 :A859FED6:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 02:00:34.724158 :A859FED7:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 02:00:34.724158 :A859FED8:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 02:00:34.724160 :A859FED9:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 02:00:34.724665 :A859FEDA: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-23 02:00:34.724708 :A859FEE0: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-23 02:00:34.733129 :A859FF02:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 03:00:42.116780 :A85EBFA5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 03:00:42.114 2025-06-23 03:00:42.116781 :A85EBFA6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17680 pso_num=29 pso_serial#=60 2025-06-23 03:00:42.116910 :A85EBFA7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17680 osp_idx=27 osp_ver=13319 osp_pg=0 (spawn #313204) 2025-06-23 03:00:42.116911 :A85EBFA8: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-23 03:00:42.116912 :A85EBFA9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=144 time=3623385297 2025-06-23 03:00:42.116912 :A85EBFAA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 03:00:42.116913 :A85EBFAB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 03:00:42.116913 :A85EBFAC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 03:00:42.116914 :A85EBFAD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 03:00:42.116914 :A85EBFAE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 03:00:42.117394 :A85EBFAF: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-23 03:00:42.117439 :A85EBFB6: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-23 03:00:42.125499 :A85EBFD7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 04:00:49.740443 :A8636CB5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 04:00:49.738 2025-06-23 04:00:49.740444 :A8636CB6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17360 pso_num=29 pso_serial#=61 2025-06-23 04:00:49.740576 :A8636CB7:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17360 osp_idx=27 osp_ver=13320 osp_pg=0 (spawn #313344) 2025-06-23 04:00:49.740577 :A8636CB8: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-23 04:00:49.740578 :A8636CB9:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=145 time=3626992922 2025-06-23 04:00:49.740578 :A8636CBA:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 04:00:49.740579 :A8636CBB:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 04:00:49.740579 :A8636CBC:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 04:00:49.740579 :A8636CBD:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 04:00:49.740579 :A8636CBE:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 04:00:49.741150 :A8636CBF: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-23 04:00:49.741189 :A8636CC5: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-23 04:00:49.749373 :A8636CD7:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 05:00:57.859124 :A8682967:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 05:00:57.855 2025-06-23 05:00:57.859125 :A8682968:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15256 pso_num=29 pso_serial#=62 2025-06-23 05:00:57.859271 :A8682969:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15256 osp_idx=27 osp_ver=13321 osp_pg=0 (spawn #313486) 2025-06-23 05:00:57.859272 :A868296A: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-23 05:00:57.859272 :A868296B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=146 time=3630601032 2025-06-23 05:00:57.859272 :A868296C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 05:00:57.859273 :A868296D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 05:00:57.859273 :A868296E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 05:00:57.859273 :A868296F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 05:00:57.859274 :A8682970:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 05:00:57.859814 :A8682971: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-23 05:00:57.859859 :A8682977: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-23 05:00:57.868197 :A8682989:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 08:00:18.359234 :A8769000:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 08:00:18.352 2025-06-23 08:00:18.359236 :A8769001:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14632 pso_num=29 pso_serial#=63 2025-06-23 08:00:18.360791 :A8769002:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14632 osp_idx=27 osp_ver=13322 osp_pg=0 (spawn #313900) 2025-06-23 08:00:18.360792 :A8769003: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-23 08:00:18.360793 :A8769004:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=147 time=3641361516 2025-06-23 08:00:18.360793 :A8769005:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 08:00:18.360794 :A8769006:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 08:00:18.360794 :A8769007:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 08:00:18.360794 :A8769008:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 08:00:18.360795 :A8769009:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 08:00:18.363096 :A876900A: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-23 08:00:18.363145 :A8769010: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-23 08:00:18.363557 :A8769011:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 08:00:20.621382 :A8769157:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 08:00:20.607 2025-06-23 08:00:20.621384 :A8769158:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12000 pso_num=29 pso_serial#=64 2025-06-23 08:00:20.621523 :A8769159:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12000 osp_idx=27 osp_ver=13323 osp_pg=0 (spawn #313902) 2025-06-23 08:00:20.621524 :A876915A: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-23 08:00:20.621524 :A876915B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=148 time=3641363797 2025-06-23 08:00:20.621525 :A876915C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 08:00:20.621525 :A876915D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 08:00:20.621525 :A876915E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 08:00:20.621526 :A876915F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 08:00:20.621526 :A8769160:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 08:00:20.622038 :A8769161: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-23 08:00:20.622085 :A8769167: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-23 08:00:22.031303 :A8769879:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 16:00:22.244346 :A89C6D7F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 16:00:22.235 2025-06-23 16:00:22.244348 :A89C6D80:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17448 pso_num=29 pso_serial#=65 2025-06-23 16:00:22.244486 :A89C6D81:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17448 (legacy spawn) 2025-06-23 16:00:22.251085 :A89C6D82: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-23 16:00:22.251560 :A89C6D8B: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-23 16:00:22.252428 :A89C6D8C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 16:00:22.915562 :A89C6DDC:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 16:00:22.908 2025-06-23 16:00:22.915564 :A89C6DDD:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17104 pso_num=29 pso_serial#=66 2025-06-23 16:00:22.915700 :A89C6DDE:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=17104 osp_idx=27 osp_ver=13325 osp_pg=0 (spawn #315012) 2025-06-23 16:00:22.915701 :A89C6DDF: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-23 16:00:22.915702 :A89C6DE0:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=149 time=3670166110 2025-06-23 16:00:22.915702 :A89C6DE1:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 16:00:22.915702 :A89C6DE2:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 16:00:22.915702 :A89C6DE3:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 16:00:22.915703 :A89C6DE4:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 16:00:22.915703 :A89C6DE5:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 16:00:22.916186 :A89C6DE6: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-23 16:00:22.916232 :A89C6DEC: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-23 16:00:22.923950 :A89C6DFA: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-23 16:00:22.925113 :A89C6E02: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-23 16:00:22.925710 :A89C6E0A: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-23 16:00:22.926184 :A89C6E12: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-23 16:00:22.926620 :A89C6E1A: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-23 16:00:22.929078 :A89C6E22: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-23 16:00:22.930163 :A89C6E2A: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-23 16:00:22.978297 :A89C6E32: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-23 16:00:22.979511 :A89C6E3A: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-23 16:00:22.980365 :A89C6E42: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-23 16:00:22.980596 :A89C6E4A: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-23 16:00:22.980837 :A89C6E52: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-23 16:00:22.982536 :A89C6E5A:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-23 22:00:02.068114 :A8B8BC9F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-23 22:00:02.060 2025-06-23 22:00:02.068115 :A8B8BCA1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=18420 pso_num=29 pso_serial#=67 2025-06-23 22:00:02.068258 :A8B8BCA6:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=18420 osp_idx=27 osp_ver=13326 osp_pg=0 (spawn #315845) 2025-06-23 22:00:02.068259 :A8B8BCA7: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-23 22:00:02.068259 :A8B8BCA8:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=150 time=3691745250 2025-06-23 22:00:02.068260 :A8B8BCA9:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-23 22:00:02.068260 :A8B8BCAA:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-23 22:00:02.068260 :A8B8BCAB:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-23 22:00:02.068261 :A8B8BCAC:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-23 22:00:02.068261 :A8B8BCAD:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-23 22:00:02.068788 :A8B8BCB6: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-23 22:00:02.073929 :A8B8BCCE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-23 22:00:02.092082 :A8B8BEB4: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-23 22:00:02.092383 :A8B8BEBC: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-23 22:00:02.092636 :A8B8BEC4: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-23 22:00:02.093133 :A8B8BECC: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-23 22:00:02.093404 :A8B8BED4: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-23 22:00:02.093674 :A8B8BEDC: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-23 22:00:02.093837 :A8B8BEE4: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-23 22:00:02.093994 :A8B8BEEC: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-23 22:00:02.094145 :A8B8BEF4: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-23 22:00:02.094306 :A8B8BEFC: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-23 22:00:02.094453 :A8B8BF04: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-23 22:00:02.094627 :A8B8BF0C: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-23 22:00:02.094789 :A8B8BF14: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-23 22:00:02.094937 :A8B8BF1C: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-23 22:00:02.095087 :A8B8BF24: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-23 22:00:02.095246 :A8B8BF2C: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-23 22:00:02.095400 :A8B8BF34: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-23 22:00:02.095548 :A8B8BF3C: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-23 22:00:02.095692 :A8B8BF44: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-23 22:00:02.095859 :A8B8BF4C: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-23 22:00:02.096019 :A8B8BF55: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-23 22:00:02.096171 :A8B8BF5D: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-23 22:00:02.096325 :A8B8BF65: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-23 22:00:02.096738 :A8B8BF6D: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-23 22:00:02.096915 :A8B8BF75: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-23 22:00:02.097082 :A8B8BF7D: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-23 22:00:02.124547 :A8B8BF94: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-23 22:00:03.122508*:A8B8C0FA:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC03AD0 estart=1750687202 eid=19729077 ctx=0x0000000777968158 cwh=0x0000000029A90AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-23 22:00:03.122508*:A8B8C0FB:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC03AD0 curCtx=0x0000000047C6C4B8 pubTabIdxCnt=0 2025-06-23 22:00:03.122508*:A8B8C0FC:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC03AD0 2025-06-23 22:00:03.122508*:A8B8C0FD:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC03AD0 2025-06-23 22:00:03.122508*:A8B8C0FE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC3AAF8 estart=1750687202 eid=16777411 ctx=0x000000077B54EAC8 cwh=0x0000000029A90608 cpu=796875 buffg=46490 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-23 22:00:03.122508*:A8B8C0FF:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC3AAF8 curCtx=0x0000000047A81240 pubTabIdxCnt=0 2025-06-23 22:00:03.122508*:A8B8C100:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC3AAF8 2025-06-23 22:00:03.122508*:A8B8C101:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC3AAF8 2025-06-23 22:00:03.122508*:A8B8C102:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC03AD0 curCtx=0x0000000047C6C4B8 xsc->flg4=65792 flags=1 2025-06-23 22:00:03.122508*:A8B8C103:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC03AD0 2025-06-23 22:00:03.778776*:A8B8C236:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC3AAF8 curCtx=0x0000000047A81240 xsc->flg4=65792 flags=2 2025-06-23 22:00:03.778776*:A8B8C237:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC3AAF8 2025-06-23 22:00:03.779314 :A8B8C238: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-23 22:00:03.799632 :A8B8C278:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=26 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-06-23 22:00:03.799764 :A8B8C27A: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-23 22:00:03.801878 :A8B8C282:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-23 22:00:03.802088 :A8B8C283:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-23 22:00:03.803077 :A8B8C284: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-23 22:00:03.803192 :A8B8C287: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-23 22:00:03.804075 :A8B8C299: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-23 22:00:03.805473 :A8B8C2A1: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-23 22:00:03.805762 :A8B8C2A9: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-23 22:00:03.856894*: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-23 22:00:03.856894*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-23 22:00:03.856894*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-23 22:00:03.872519*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-23 22:00:03.872519*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-23 22:00:03.872519*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-23 22:00:03.872519*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-23 22:00:03.944981 :A8B8C357: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-23 22:00:05.810781 :A8B8C4D6: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-23 22:00:05.810792 :A8B8C4D7:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0006.009.0000c49c 2025-06-23 22:00:08.192885 :A8B8C707: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-23 22:00:08.194198 :A8B8C70F: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-23 22:00:08.202793 :A8B8C717:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 9, ext 2, nex 3 2025-06-23 22:00:08.202801 :A8B8C718:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008127bd where: kduwh01: kdusru 2025-06-23 22:00:08.205101 :A8B8C719: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-23 22:00:08.230153 :A8B8C729: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: 18420, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de