Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903220006\orcl_j000_23828_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250903220006\orcl_j000_23828_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:35043M/63366M, Ph+PgF:39892M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 23828, image: ORACLE.EXE (J000) *** 2025-09-03 22:00:06.986 *** SESSION ID:(177.44239) 2025-09-03 22:00:06.986 *** 2025-09-03 22:00:06.986 Process diagnostic dump for ORACLE.EXE (J000), OS id=23828, pid: 22, proc_ser: 43, sid: 177, sess_ser: 44239 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23828 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13022, hash value=254493430 Current Wait Stack: Not in wait; last wait ended 0.586946 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.586971 sec since last wait 0: waited for 'db file sequential read' file#=0x2, block#=0x1bad6, blocks=0x1 wait_id=144 seq_num=145 snap_id=1 wait times: snap=0.000137 sec, exc=0.000137 sec, total=0.000137 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.469253 sec of elapsed time 1: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=143 seq_num=144 snap_id=1 wait times: snap=0.000190 sec, exc=0.000190 sec, total=0.000190 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000002 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2a37, block#=0x0, blocks=0x0 wait_id=142 seq_num=143 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.000344 sec of elapsed time 3: waited for 'log file sequential read' log#=0x2a37, block#=0x0, blocks=0x0 wait_id=141 seq_num=142 snap_id=1 wait times: snap=0.000014 sec, exc=0.000014 sec, total=0.000014 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000009 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0xc000, blocks=0x800 wait_id=140 seq_num=141 snap_id=1 wait times: snap=0.000309 sec, exc=0.000309 sec, total=0.000309 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0xb800, blocks=0x800 wait_id=139 seq_num=140 snap_id=1 wait times: snap=0.000308 sec, exc=0.000308 sec, total=0.000308 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 6: waited for 'log file sequential read' log#=0x0, block#=0xb000, blocks=0x800 wait_id=138 seq_num=139 snap_id=1 wait times: snap=0.000309 sec, exc=0.000309 sec, total=0.000309 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0xa800, blocks=0x800 wait_id=137 seq_num=138 snap_id=1 wait times: snap=0.000299 sec, exc=0.000299 sec, total=0.000299 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 8: waited for 'log file sequential read' log#=0x0, block#=0xa000, blocks=0x800 wait_id=136 seq_num=137 snap_id=1 wait times: snap=0.000339 sec, exc=0.000339 sec, total=0.000339 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000046 sec of elapsed time 9: waited for 'log file sequential read' log#=0x0, block#=0x9800, blocks=0x800 wait_id=135 seq_num=136 snap_id=1 wait times: snap=0.000424 sec, exc=0.000424 sec, total=0.000424 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.579316 sec of elapsed time Sampled Session History of session 177 serial 44239 --------------------------------------------------- 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:00:00 - 22:00:06] not in wait at each sample [1 sample, 21:59:59] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-03 22:00:06.986 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 23828, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-03 21:57:09.023478 :C8996AB1: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-09-03 21:57:09.023643 :C8996ABA: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-09-03 21:57:09.024435 :C8996ABB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:57:16.839527 :C8996D0E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:57:16.830 2025-09-03 21:57:16.839530 :C8996D0F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22920 pso_num=22 pso_serial#=33 2025-09-03 21:57:16.839679 :C8996D10:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22920 (legacy spawn) 2025-09-03 21:57:16.846383 :C8996D11: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-09-03 21:57:16.846586 :C8996D16: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-09-03 21:57:16.847501 :C8996D1B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:57:29.841613 :C8997157:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:57:29.836 2025-09-03 21:57:29.841615 :C8997158:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18376 pso_num=22 pso_serial#=34 2025-09-03 21:57:29.841764 :C8997159:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18376 osp_idx=0 osp_ver=571051 osp_pg=0 (spawn #555809) 2025-09-03 21:57:29.841765 :C899715A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:57:29.841765 :C899715B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=231 time=1322301143 2025-09-03 21:57:29.841765 :C899715C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:57:29.841766 :C899715D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:57:29.841766 :C899715E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:57:29.841767 :C899715F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:57:29.841767 :C8997160:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:57:29.842284 :C8997161: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-09-03 21:57:29.842958 :C8997176: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-09-03 21:57:29.850659 :C8997192:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-03 21:57:29.851094 :C8997193: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-09-03 21:57:29.851134 :C8997196:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-03 21:57:29.852002 :C899719C: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-09-03 21:57:29.852141 :C89971A5: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-09-03 21:57:59.936613 :C8997BE1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:02.166796 :C8997C8A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:02.158 2025-09-03 21:58:02.166799 :C8997C8B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23780 pso_num=22 pso_serial#=35 2025-09-03 21:58:02.167005 :C8997C8C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23780 (legacy spawn) 2025-09-03 21:58:02.174005 :C8997C8D: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-09-03 21:58:02.174183 :C8997C96: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-09-03 21:58:02.175089 :C8997C97:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:18.378778 :C89981BF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:18.364 2025-09-03 21:58:18.378780 :C89981C0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23040 pso_num=22 pso_serial#=36 2025-09-03 21:58:18.378913 :C89981C1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23040 (legacy spawn) 2025-09-03 21:58:18.385882 :C89981C2: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-09-03 21:58:18.386067 :C89981C7: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-09-03 21:58:18.386887 :C89981CC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:18.395949 :C89981CF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:18.395 2025-09-03 21:58:18.395951 :C89981D0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17460 pso_num=22 pso_serial#=37 2025-09-03 21:58:18.396097 :C89981D1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17460 (legacy spawn) 2025-09-03 21:58:18.415727 :C89981D2: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-09-03 21:58:18.415900 :C89981DB: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-09-03 21:58:18.416789 :C89981DC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:21.591673 :C89982D1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:21.577 2025-09-03 21:58:21.591676 :C89982D2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23100 pso_num=22 pso_serial#=38 2025-09-03 21:58:21.591812 :C89982D3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23100 (legacy spawn) 2025-09-03 21:58:21.599072 :C89982D4: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-09-03 21:58:21.599256 :C89982DD: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-09-03 21:58:21.600207 :C89982DE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:21.609550 :C89982E1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:21.609 2025-09-03 21:58:21.609552 :C89982E2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22972 pso_num=22 pso_serial#=39 2025-09-03 21:58:21.609702 :C89982E3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22972 (legacy spawn) 2025-09-03 21:58:21.616698 :C89982E4: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-09-03 21:58:21.616861 :C89982EB: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-09-03 21:58:21.617769 :C89982EE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:24.834722 :C89983F1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:24.826 2025-09-03 21:58:24.834724 :C89983F2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23816 pso_num=22 pso_serial#=40 2025-09-03 21:58:24.834864 :C89983F3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23816 (legacy spawn) 2025-09-03 21:58:24.841282 :C89983F4: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-09-03 21:58:24.841453 :C89983F9: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-09-03 21:58:24.842291 :C89983FE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:58:29.929076 :C89985B0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:58:29.923 2025-09-03 21:58:29.929078 :C89985B1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23792 pso_num=22 pso_serial#=41 2025-09-03 21:58:29.929216 :C89985B2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23792 osp_idx=0 osp_ver=571058 osp_pg=0 (spawn #555811) 2025-09-03 21:58:29.929217 :C89985B3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:58:29.929218 :C89985B4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=232 time=1322361237 2025-09-03 21:58:29.929218 :C89985B5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:58:29.929218 :C89985B6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:58:29.929219 :C89985B7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:58:29.929219 :C89985B8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:58:29.929220 :C89985B9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:58:29.929767 :C89985BA: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-09-03 21:58:29.930433 :C89985CF: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-09-03 21:58:29.941686 :C89985EB:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-03 21:58:29.942168 :C89985EC:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-03 21:58:29.944654 :C89985ED:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-03 21:58:29.945010 :C89985EE: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-09-03 21:58:29.945049 :C89985F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-03 21:58:29.945978 :C89985F7: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-09-03 21:58:29.946106 :C89985FF: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-09-03 21:59:00.051533 :C8998FE3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:59:04.342247 :C899911D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:59:04.334 2025-09-03 21:59:04.342250 :C899911E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21076 pso_num=22 pso_serial#=42 2025-09-03 21:59:04.342396 :C899911F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21076 (legacy spawn) 2025-09-03 21:59:04.349016 :C8999120: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-09-03 21:59:04.349193 :C8999125: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-09-03 21:59:04.350060 :C899912A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-03 21:59:30.044012 :C8999969:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-03 21:59:30.038 2025-09-03 21:59:30.044015 :C899996A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23828 pso_num=22 pso_serial#=43 2025-09-03 21:59:30.044171 :C899996B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23828 osp_idx=0 osp_ver=571060 osp_pg=0 (spawn #555814) 2025-09-03 21:59:30.044172 :C899996C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-03 21:59:30.044172 :C899996D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=233 time=1322421346 2025-09-03 21:59:30.044173 :C899996E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-03 21:59:30.044173 :C899996F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-03 21:59:30.044173 :C8999970:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-03 21:59:30.044174 :C8999971:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-03 21:59:30.044174 :C8999972:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-03 21:59:30.044698 :C8999973: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-09-03 21:59:30.045379 :C8999988: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-09-03 21:59:30.053244 :C89999A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-03 21:59:30.053628 :C89999A5: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-09-03 21:59:30.053665 :C89999A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-03 21:59:30.054552 :C89999AE: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-09-03 21:59:30.054676 :C89999B7: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-09-03 22:00:00.071749 :C899A455: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-09-03 22:00:00.071749*:C899A492:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1756907999 eid=16787318 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-03 22:00:00.071749*:C899A493:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-09-03 22:00:00.071749*:C899A494:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-09-03 22:00:00.071749*:C899A495:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-09-03 22:00:00.071749*:C899A4AB:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-09-03 22:00:00.071749*:C899A4AC:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-09-03 22:00:00.078607 :C899A4AD: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-09-03 22:00:00.078670 :C899A4B0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-09-03 22:00:00.090127 :C899A63D: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-09-03 22:00:00.096151 :C899A648: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-09-03 22:00:00.096997 :C899A650: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-09-03 22:00:00.103018 :C899A66A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.103175 :C899A679:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.103947 :C899A681: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-09-03 22:00:00.104148 :C899A689:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-09-03 22:00:00.105558 :C899A68A: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-09-03 22:00:00.105598 :C899A68D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-03 22:00:00.105695 :C899A69A: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-09-03 22:00:00.181011 :C899A6A6: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-09-03 22:00:00.183034 :C899A6B9: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-09-03 22:00:00.183073 :C899A6BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-09-03 22:00:00.200547 :C899A722: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-09-03 22:00:00.200781 :C899A72A: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-09-03 22:00:00.200961 :C899A732: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-09-03 22:00:00.201144 :C899A73B: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-09-03 22:00:00.201308 :C899A743: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-09-03 22:00:00.201508 :C899A74B: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-09-03 22:00:00.201744 :C899A753: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-09-03 22:00:00.201923 :C899A75B: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-09-03 22:00:00.202074 :C899A763: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-09-03 22:00:00.202236 :C899A76B: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-09-03 22:00:00.202391 :C899A773: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-09-03 22:00:00.202554 :C899A77B: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-09-03 22:00:00.202701 :C899A783: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-09-03 22:00:00.202850 :C899A78B: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-09-03 22:00:00.203007 :C899A793: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-09-03 22:00:00.203163 :C899A79D: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-09-03 22:00:00.203793 :C899A7A5: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-09-03 22:00:00.231847 :C899A7BC: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-09-03 22:00:01.877148 :C899AA09: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-09-03 22:00:01.897424 :C899AA31: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-09-03 22:00:01.899397 :C899AA41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-03 22:00:01.899592 :C899AA42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-03 22:00:01.900426 :C899AA43: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-09-03 22:00:01.900506 :C899AA46: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-09-03 22:00:01.901354 :C899AA60: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-09-03 22:00:01.902829 :C899AA70: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-09-03 22:00:01.903097 :C899AA78: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-09-03 22:00:01.945644*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-09-03 22:00:01.945644*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-03 22:00:01.945644*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-03 22:00:01.945644*:C899AABD:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000005C8E9EB0 estart=1756908001 eid=16777331 ctx=0x000000077CEF05F0 cwh=0x000000005C5B2138 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-09-03 22:00:01.945644*:C899AABE:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000005C8E9EB0 curCtx=0x000000005C8DA408 pubTabIdxCnt=0 2025-09-03 22:00:01.945644*:C899AABF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000005C8E9EB0 2025-09-03 22:00:01.945644*:C899AAC0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000005C8E9EB0 2025-09-03 22:00:01.976889*:C899AADD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000005C8E9EB0 curCtx=0x000000005C8DA408 xsc->flg4=65792 flags=1 2025-09-03 22:00:01.976889*:C899AADE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000005C8E9EB0 2025-09-03 22:00:01.976889*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-03 22:00:01.976889*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-03 22:00:01.976889*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-03 22:00:01.976889*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-03 22:00:02.044467 :C899AB13: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-09-03 22:00:03.968523 :C899AC7B: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-09-03 22:00:03.968535 :C899AC7C:db_trace:ktur.c@3079:ktuabt(): [10444:22:177] ABORT TRANSACTION - xid: 0x0008.021.0000d37c 2025-09-03 22:00:06.400443*:C899AF67:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEB718 estart=1756908005 eid=16777910 ctx=0x000000077F58CBF8 cwh=0x0000000026B90338 cpu=0 buffg=3 interb=0 r=0 rb=0 w=0 wb=0 2025-09-03 22:00:06.400443*:C899AF68:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEB718 curCtx=0x000000005C9AAEA0 pubTabIdxCnt=0 2025-09-03 22:00:06.400443*:C899AF69:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEB718 2025-09-03 22:00:06.400443*:C899AF6A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEB718 2025-09-03 22:00:06.400443*:C899AF6B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEB718 curCtx=0x000000005C9AAEA0 xsc->flg4=65792 flags=1 2025-09-03 22:00:06.400443*:C899AF6C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEB718 2025-09-03 22:00:06.407339 :C899AF6D: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-09-03 22:00:06.408947 :C899AF75: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-09-03 22:00:06.419696 :C899AF7D: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-09-03 22:00:06.449127 :C899AF85: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: 23828, J000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de