Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719220351\orcl_j000_16504_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250719220351\orcl_j000_16504_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:36188M/63366M, Ph+PgF:42070M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 16504, image: ORACLE.EXE (J000) *** 2025-07-19 22:03:51.713 *** SESSION ID:(177.25235) 2025-07-19 22:03:51.713 *** 2025-07-19 22:03:51.713 Process diagnostic dump for ORACLE.EXE (J000), OS id=16504, pid: 22, proc_ser: 107, sid: 177, sess_ser: 25235 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16504 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12781, hash value=326846118 Current Wait Stack: Not in wait; last wait ended 1.369180 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 1.369206 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=170 seq_num=171 snap_id=1 wait times: snap=0.000213 sec, exc=0.000213 sec, total=0.000213 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#=0x292f, block#=0x0, blocks=0x0 wait_id=169 seq_num=170 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.022546 sec of elapsed time 2: waited for 'log file sequential read' log#=0x292f, block#=0x0, blocks=0x0 wait_id=168 seq_num=169 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.513519 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x6000, blocks=0x800 wait_id=167 seq_num=168 snap_id=1 wait times: snap=0.000326 sec, exc=0.000326 sec, total=0.000326 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.018448 sec of elapsed time 4: waited for 'log file sequential read' log#=0x292f, block#=0x800, blocks=0x800 wait_id=166 seq_num=167 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.002421 sec of elapsed time 5: waited for 'log file sequential read' log#=0x292f, block#=0x0, blocks=0x0 wait_id=165 seq_num=166 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.000155 sec of elapsed time 6: waited for 'log file sequential read' log#=0x292f, block#=0x2, blocks=0x7fe wait_id=164 seq_num=165 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.000161 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x1, blocks=0x1 wait_id=163 seq_num=164 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 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=162 seq_num=163 snap_id=1 wait times: snap=0.000191 sec, exc=0.000191 sec, total=0.000191 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000118 sec of elapsed time 9: waited for 'control file sequential read' file#=0x0, block#=0x20, blocks=0x1 wait_id=161 seq_num=162 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.000006 sec of elapsed time Sampled Session History of session 177 serial 25235 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:03:45 - 22:03:51] not in wait at each sample [16 samples, 22:03:29 - 22:03:44] idle wait at each sample [session created at: 22:03:29] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-19 22:03:51.713 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 16504, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-19 22:01:29.311858 :B4488A55:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18528 osp_idx=0 osp_ver=423975 osp_pg=0 (spawn #402504) 2025-07-19 22:01:29.311859 :B4488A56:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 22:01:29.311859 :B4488A57:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=1643265204 2025-07-19 22:01:29.311860 :B4488A58:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 22:01:29.311860 :B4488A59:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 22:01:29.311861 :B4488A5A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 22:01:29.311861 :B4488A5B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 22:01:29.311862 :B4488A5C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 22:01:29.312365 :B4488A5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 22:01:29.313062 :B4488A72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:01:29.320772 :B4488A8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-19 22:01:29.321219 :B4488AA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:01:29.321260 :B4488AA6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-19 22:01:29.322203 :B4488AC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:01:29.322322 :B4488AC9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:01:59.378634 :B44894F7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:12.766799 :B4489911:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:12.758 2025-07-19 22:02:12.766801 :B4489912:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19008 pso_num=22 pso_serial#=94 2025-07-19 22:02:12.766938 :B4489913:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19008 (legacy spawn) 2025-07-19 22:02:12.773405 :B4489914:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:12.773584 :B4489919:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:12.774410 :B448991E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:17.462488 :B4489AC8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:17.453 2025-07-19 22:02:17.462490 :B4489AC9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18680 pso_num=22 pso_serial#=95 2025-07-19 22:02:17.462622 :B4489ACA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18680 (legacy spawn) 2025-07-19 22:02:17.469107 :B4489AD6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:17.469266 :B4489ADF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:17.470126 :B4489AE0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:25.169168 :B4489D2A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:25.155 2025-07-19 22:02:25.169169 :B4489D2B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18872 pso_num=22 pso_serial#=96 2025-07-19 22:02:25.169313 :B4489D2C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18872 (legacy spawn) 2025-07-19 22:02:25.189932 :B4489D2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:25.190110 :B4489D36:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:25.190913 :B4489D37:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:25.199588 :B4489D3A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:25.186 2025-07-19 22:02:25.199589 :B4489D3B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19316 pso_num=22 pso_serial#=97 2025-07-19 22:02:25.199724 :B4489D3C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19316 (legacy spawn) 2025-07-19 22:02:25.206260 :B4489D3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:25.206421 :B4489D46:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:25.207211 :B4489D47:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:28.390756 :B4489E64:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:28.377 2025-07-19 22:02:28.390759 :B4489E65:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13284 pso_num=22 pso_serial#=98 2025-07-19 22:02:28.390891 :B4489E66:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13284 (legacy spawn) 2025-07-19 22:02:28.397404 :B4489E67:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:28.397577 :B4489E70:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:28.398399 :B4489E71:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:28.407074 :B4489E74:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:28.392 2025-07-19 22:02:28.407077 :B4489E75:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6356 pso_num=22 pso_serial#=99 2025-07-19 22:02:28.407212 :B4489E76:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6356 (legacy spawn) 2025-07-19 22:02:28.413648 :B4489E77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:28.413810 :B4489E7F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:28.414640 :B4489E80:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:02:29.388020 :B4489ECA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:02:29.382 2025-07-19 22:02:29.388022 :B4489ECB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18832 pso_num=22 pso_serial#=100 2025-07-19 22:02:29.388162 :B4489ECC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18832 osp_idx=0 osp_ver=423982 osp_pg=0 (spawn #402506) 2025-07-19 22:02:29.388163 :B4489ECD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 22:02:29.388163 :B4489ECE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=196 time=1643325283 2025-07-19 22:02:29.388164 :B4489ECF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 22:02:29.388164 :B4489ED0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 22:02:29.388165 :B4489ED1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 22:02:29.388165 :B4489ED2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 22:02:29.388165 :B4489ED3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 22:02:29.388647 :B4489ED4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 22:02:29.389285 :B4489EE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:29.400311 :B4489F05:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-19 22:02:29.400783 :B4489F06:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-19 22:02:29.403136 :B4489F07:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-19 22:02:29.403493 :B4489F08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:29.403529 :B4489F0B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-19 22:02:29.404405 :B4489F11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:02:29.404527 :B4489F1A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:02:59.494631 :B448ABEA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:01.603768 :B448ACA7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:01.590 2025-07-19 22:03:01.603770 :B448ACA8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18920 pso_num=22 pso_serial#=101 2025-07-19 22:03:01.603922 :B448ACA9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18920 (legacy spawn) 2025-07-19 22:03:01.610368 :B448ACAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:01.610551 :B448ACB3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:01.611363 :B448ACB4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:01.620084 :B448ACB7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:01.605 2025-07-19 22:03:01.620086 :B448ACB8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19012 pso_num=22 pso_serial#=102 2025-07-19 22:03:01.620225 :B448ACB9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19012 (legacy spawn) 2025-07-19 22:03:01.626668 :B448ACBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:01.626840 :B448ACC3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:01.627651 :B448ACC4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:04.808724 :B448ADB6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:04.795 2025-07-19 22:03:04.808726 :B448ADB7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4152 pso_num=22 pso_serial#=103 2025-07-19 22:03:04.808871 :B448ADB8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4152 (legacy spawn) 2025-07-19 22:03:04.815380 :B448ADB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:04.815547 :B448ADC2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:04.816342 :B448ADC3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:05.168804 :B448ADC6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:05.154 2025-07-19 22:03:05.168807 :B448ADC7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4728 pso_num=22 pso_serial#=104 2025-07-19 22:03:05.168943 :B448ADC8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4728 (legacy spawn) 2025-07-19 22:03:05.175456 :B448ADC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:05.175628 :B448ADCE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:05.176423 :B448ADD3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:15.201047 :B448B115:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:15.192 2025-07-19 22:03:15.201049 :B448B116:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18956 pso_num=22 pso_serial#=105 2025-07-19 22:03:15.201187 :B448B117:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18956 (legacy spawn) 2025-07-19 22:03:15.207683 :B448B118:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:15.207874 :B448B11D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:15.208697 :B448B122:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:25.646352 :B448B489:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:25.638 2025-07-19 22:03:25.646354 :B448B48A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19208 pso_num=22 pso_serial#=106 2025-07-19 22:03:25.646530 :B448B48B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19208 (legacy spawn) 2025-07-19 22:03:25.652945 :B448B48C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:25.653143 :B448B495:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:25.653952 :B448B496:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-19 22:03:29.470836 :B448B5C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-19 22:03:29.465 2025-07-19 22:03:29.470838 :B448B5C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16504 pso_num=22 pso_serial#=107 2025-07-19 22:03:29.470989 :B448B5C4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16504 osp_idx=0 osp_ver=423989 osp_pg=0 (spawn #402508) 2025-07-19 22:03:29.470990 :B448B5C5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-19 22:03:29.470990 :B448B5C6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=1643385361 2025-07-19 22:03:29.470991 :B448B5C7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-19 22:03:29.470991 :B448B5C8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-19 22:03:29.470992 :B448B5C9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-19 22:03:29.470992 :B448B5CA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-19 22:03:29.470992 :B448B5CB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-19 22:03:29.471479 :B448B5CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-19 22:03:29.472107 :B448B5E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:29.479670 :B448B5FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-19 22:03:29.480026 :B448B5FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:29.480065 :B448B602:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-19 22:03:29.480910 :B448B608:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:29.481028 :B448B611:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-19 22:03:44.585051 :B448BC91:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-19 22:03:44.585051*:B448BCA2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1752933824 eid=16784493 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-19 22:03:44.585051*:B448BCA3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-07-19 22:03:44.585051*:B448BCA4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-07-19 22:03:44.585051*:B448BCA5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-07-19 22:03:44.585051*:B448BCA8:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-07-19 22:03:44.585051*:B448BCA9:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-07-19 22:03:44.590524 :B448BCB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.590567 :B448BCB5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-07-19 22:03:44.607782 :B448BCD6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.607989 :B448BCDE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.608148 :B448BCE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.608294 :B448BCEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.608455 :B448BCF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.608636 :B448BCFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.608818 :B448BD06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.608975 :B448BD0E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.609115 :B448BD16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.609435 :B448BD1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.609676 :B448BD26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:44.628847 :B448BD2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.104450*:B448BDF2:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B41FA8 estart=1752933826 eid=20551208 ctx=0x0000000777968158 cwh=0x000000000FD009C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-19 22:03:46.104450*:B448BDF3:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B41FA8 curCtx=0x0000000031CAE640 pubTabIdxCnt=0 2025-07-19 22:03:46.104450*:B448BDF4:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B41FA8 2025-07-19 22:03:46.104450*:B448BDF5:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B41FA8 2025-07-19 22:03:46.104450*:B448BDF6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BDB6F0 estart=1752933824 eid=16777465 ctx=0x000000077B54EAC8 cwh=0x000000000FD00518 cpu=1281250 buffg=70320 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-19 22:03:46.104450*:B448BDF7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BDB6F0 curCtx=0x0000000031A87FD8 pubTabIdxCnt=0 2025-07-19 22:03:46.104450*:B448BDF8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BDB6F0 2025-07-19 22:03:46.104450*:B448BDF9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BDB6F0 2025-07-19 22:03:46.104450*:B448BDFA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B41FA8 curCtx=0x0000000031CAE640 xsc->flg4=65792 flags=1 2025-07-19 22:03:46.104450*:B448BDFB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B41FA8 2025-07-19 22:03:46.291949*:B448BEA5:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BDB6F0 curCtx=0x0000000031A87FD8 xsc->flg4=65792 flags=2 2025-07-19 22:03:46.291949*:B448BEA6:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BDB6F0 2025-07-19 22:03:46.306541 :B448BEA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.329677 :B448BEBC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.331941 :B448BEC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-19 22:03:46.332167 :B448BEC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-19 22:03:46.333261 :B448BEC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.333381 :B448BEC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.334407 :B448BEDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.336123 :B448BEE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.336444 :B448BEEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:46.370081*: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-19 22:03:46.385702*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-19 22:03:46.385702*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-19 22:03:46.385702*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-19 22:03:46.385702*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-19 22:03:46.385702*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-19 22:03:46.385702*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-19 22:03:46.458200 :B448BEFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:48.350358 :B448BF9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:48.350372 :B448BF9F:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x0004.007.0000c5a4 2025-07-19 22:03:50.796669 :B448C16C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:50.800714 :B448C174:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:50.811798 :B448C17C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-19 22:03:50.839213 :B448C184:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] 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 22 (osid: 16504, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de