Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250805220009\orcl_j002_15536_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250805220009\orcl_j002_15536_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:35685M/63366M, Ph+PgF:41205M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 15536, image: ORACLE.EXE (J002) *** 2025-08-05 22:00:09.470 *** SESSION ID:(225.13315) 2025-08-05 22:00:09.470 *** 2025-08-05 22:00:09.470 Process diagnostic dump for ORACLE.EXE (J002), OS id=15536, pid: 28, proc_ser: 93, sid: 225, sess_ser: 13315 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15536 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12871, hash value=1856163303 Current Wait Stack: Not in wait; last wait ended 0.643688 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.643715 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x5, fileno=0x0, filetype=0x3 wait_id=132 seq_num=133 snap_id=1 wait times: snap=0.000204 sec, exc=0.000204 sec, total=0.000204 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#=0x2991, block#=0x0, blocks=0x0 wait_id=131 seq_num=132 snap_id=1 wait times: snap=0.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.043539 sec of elapsed time 2: waited for 'log file sequential read' log#=0x2991, block#=0x0, blocks=0x0 wait_id=130 seq_num=131 snap_id=1 wait times: snap=0.000018 sec, exc=0.000018 sec, total=0.000018 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.159597 sec of elapsed time 3: waited for 'log file sequential read' log#=0x0, block#=0x6000, blocks=0x800 wait_id=129 seq_num=130 snap_id=1 wait times: snap=0.000290 sec, exc=0.000290 sec, total=0.000290 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000004 sec of elapsed time 4: waited for 'log file sequential read' log#=0x0, block#=0x5800, blocks=0x800 wait_id=128 seq_num=129 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.000005 sec of elapsed time 5: waited for 'log file sequential read' log#=0x0, block#=0x5000, blocks=0x800 wait_id=127 seq_num=128 snap_id=1 wait times: snap=0.000287 sec, exc=0.000287 sec, total=0.000287 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#=0x4800, blocks=0x800 wait_id=126 seq_num=127 snap_id=1 wait times: snap=0.000291 sec, exc=0.000291 sec, total=0.000291 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000033 sec of elapsed time 7: waited for 'log file sequential read' log#=0x0, block#=0x4000, blocks=0x800 wait_id=125 seq_num=126 snap_id=1 wait times: snap=0.000316 sec, exc=0.000316 sec, total=0.000316 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.419536 sec of elapsed time 8: waited for 'log file sequential read' log#=0x2991, block#=0x800, blocks=0x800 wait_id=124 seq_num=125 snap_id=1 wait times: snap=0.000207 sec, exc=0.000207 sec, total=0.000207 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.002673 sec of elapsed time 9: waited for 'log file sequential read' log#=0x2991, block#=0x0, blocks=0x0 wait_id=123 seq_num=124 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time Sampled Session History of session 225 serial 13315 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:08 - 22:00:09] not in wait at each sample [1 sample, 22:00:07] waited for 'log buffer space', seq_num: 42 p1: ''=0x0 p2: ''=0x0 p3: ''=0x0 time_waited: 0.986867 sec (sample interval: 0 sec) [5 samples, 22:00:02 - 22:00:06] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'log buffer space' [1 sample, 22:00:07 ] time_waited: 0.986867 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-05 22:00:09.470 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 15536, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-05 16:00:24.317605 :BBBB52FC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:00:27.471026 :BBBB54DF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:00:27.458 2025-08-05 16:00:27.471028 :BBBB54E0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18072 pso_num=28 pso_serial#=85 2025-08-05 16:00:27.471162 :BBBB54E1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18072 (legacy spawn) 2025-08-05 16:00:27.477718 :BBBB54E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 16:00:27.477889 :BBBB54EB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-05 16:00:27.478664 :BBBB54EC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:00:27.487292 :BBBB54EF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:00:27.473 2025-08-05 16:00:27.487294 :BBBB54F0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16212 pso_num=28 pso_serial#=86 2025-08-05 16:00:27.487433 :BBBB54F1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16212 (legacy spawn) 2025-08-05 16:00:27.493930 :BBBB54F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 16:00:27.494104 :BBBB54FB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-05 16:00:27.494904 :BBBB54FC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:14:33.523679 :BBBC7533:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:14:33.514 2025-08-05 16:14:33.523681 :BBBC7534:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17692 pso_num=28 pso_serial#=87 2025-08-05 16:14:33.523817 :BBBC7535:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17692 (legacy spawn) 2025-08-05 16:14:33.530299 :BBBC7536:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 16:14:33.633275 :BBBC753B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-05 16:14:33.634252 :BBBC7540:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:14:37.883381 :BBBC76D1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:14:37.878 2025-08-05 16:14:37.883382 :BBBC76D2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20032 pso_num=28 pso_serial#=88 2025-08-05 16:14:37.883517 :BBBC76D3:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20032 osp_idx=26 osp_ver=203116 osp_pg=0 (spawn #458361) 2025-08-05 16:14:37.883518 :BBBC76D4:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-05 16:14:37.883518 :BBBC76D5:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=112 time=3091147189 2025-08-05 16:14:37.883519 :BBBC76D6:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-05 16:14:37.883519 :BBBC76D7:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-05 16:14:37.883519 :BBBC76D8:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-05 16:14:37.883520 :BBBC76D9:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-05 16:14:37.883520 :BBBC76DA:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-05 16:14:37.884012 :BBBC76DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-05 16:14:37.884056 :BBBC76E1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-05 16:14:37.888251 :BBBC76F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-05 16:14:37.888294 :BBBC76F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-05 16:14:37.888545 :BBBC76F2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-05 16:14:37.888561 :BBBC76F3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-05 16:14:37.888775 :BBBC76F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-05 16:14:37.888789 :BBBC76F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-05 16:14:37.888993 :BBBC76F6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-05 16:14:37.889005 :BBBC76F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-05 16:14:37.889247 :BBBC76F8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-05 16:14:37.889267 :BBBC76F9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-05 16:14:37.889501 :BBBC76FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-05 16:14:37.889513 :BBBC76FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-05 16:14:37.891837 :BBBC76FC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:14:48.878341 :BBBC7A26:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:14:48.871 2025-08-05 16:14:48.878343 :BBBC7A27:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18956 pso_num=28 pso_serial#=89 2025-08-05 16:14:48.878484 :BBBC7A28:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18956 (legacy spawn) 2025-08-05 16:14:48.885229 :BBBC7A29:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 16:14:48.885411 :BBBC7A2E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-05 16:14:48.886313 :BBBC7A33:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:14:56.884471 :BBBC7CDE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:14:56.870 2025-08-05 16:14:56.884473 :BBBC7CDF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20220 pso_num=28 pso_serial#=90 2025-08-05 16:14:56.884607 :BBBC7CE0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20220 (legacy spawn) 2025-08-05 16:14:56.891127 :BBBC7CE1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 16:14:56.891297 :BBBC7CE7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-05 16:14:56.892072 :BBBC7CEB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 16:14:56.900775 :BBBC7CEE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 16:14:56.886 2025-08-05 16:14:56.900777 :BBBC7CEF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19868 pso_num=28 pso_serial#=91 2025-08-05 16:14:56.900936 :BBBC7CF0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19868 (legacy spawn) 2025-08-05 16:14:56.907310 :BBBC7CF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 16:14:56.907479 :BBBC7CFA:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-05 16:14:56.908305 :BBBC7CFB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 22:00:00.038840 :BBD7CB55:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 22:00:00.039 2025-08-05 22:00:00.038841 :BBD7CB56:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15292 pso_num=28 pso_serial#=92 2025-08-05 22:00:00.038985 :BBD7CB57:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15292 osp_idx=26 osp_ver=203120 osp_pg=0 (spawn #459151) 2025-08-05 22:00:00.038986 :BBD7CB58:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-05 22:00:00.038986 :BBD7CB59:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=113 time=3111869345 2025-08-05 22:00:00.038987 :BBD7CB5A:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-05 22:00:00.038987 :BBD7CB5B:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-05 22:00:00.038988 :BBD7CB5C:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-05 22:00:00.038988 :BBD7CB5D:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-05 22:00:00.038988 :BBD7CB5E:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-05 22:00:00.039496 :BBD7CB5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.039537 :BBD7CB66:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-05 22:00:00.039619 :BBD7CB7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.043541 :BBD7CB89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.044700 :BBD7CB91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.044725 :BBD7CB94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.044766 :BBD7CB9F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-05 22:00:00.046596 :BBD7CBAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.046624 :BBD7CBAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.046663 :BBD7CBB8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-05 22:00:00.047123 :BBD7CBC3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.049488 :BBD7CBCB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.054860 :BBD7CBD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.060399 :BBD7CBDB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.061736 :BBD7CBE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.062748 :BBD7CBEB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.065264 :BBD7CBF3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.066639 :BBD7CBFB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.069516 :BBD7CC03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.073036 :BBD7CC0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.073372 :BBD7CC1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.073415 :BBD7CC1D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-08-05 22:00:00.073429 :BBD7CC1E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-08-05 22:00:00.075709 :BBD7CC24:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.075757 :BBD7CC27:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-08-05 22:00:00.075769 :BBD7CC28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-08-05 22:00:00.077694 :BBD7CC2E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.077740 :BBD7CC31:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-08-05 22:00:00.077751 :BBD7CC32:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-08-05 22:00:00.077920 :BBD7CC38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.077935 :BBD7CC3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-05 22:00:00.077973 :BBD7CC46:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-05 22:00:00.079548 :BBD7CC51:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-05 22:00:02.044210 :BBD7CD94:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-05 22:00:02.039 2025-08-05 22:00:02.044211 :BBD7CD95:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15536 pso_num=28 pso_serial#=93 2025-08-05 22:00:02.044345 :BBD7CD96:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15536 osp_idx=26 osp_ver=203121 osp_pg=0 (spawn #459152) 2025-08-05 22:00:02.044345 :BBD7CD97:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-05 22:00:02.044346 :BBD7CD98:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=114 time=3111871345 2025-08-05 22:00:02.044346 :BBD7CD99:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-05 22:00:02.044347 :BBD7CD9A:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-05 22:00:02.044347 :BBD7CD9B:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-05 22:00:02.044347 :BBD7CD9C:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-05 22:00:02.044347 :BBD7CD9D:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-05 22:00:02.044829 :BBD7CDA5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.050001 :BBD7CDD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.050040 :BBD7CDDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-08-05 22:00:02.067504 :BBD7CFCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.067865 :BBD7CFD5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.068033 :BBD7CFDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.068197 :BBD7CFE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.068383 :BBD7CFED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.068648 :BBD7CFF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.068811 :BBD7D008:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.068986 :BBD7D01C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.069173 :BBD7D024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.069381 :BBD7D02E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.069534 :BBD7D036:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.069703 :BBD7D041:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.069874 :BBD7D053:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.070028 :BBD7D05C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.070178 :BBD7D064:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.070390 :BBD7D06C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.070566 :BBD7D074:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.070720 :BBD7D07C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.070870 :BBD7D084:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.071202 :BBD7D08C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.071370 :BBD7D094:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.071509 :BBD7D0A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.071532 :BBD7D0AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.071774 :BBD7D0BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.072018 :BBD7D0C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.072252 :BBD7D0CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.072857 :BBD7D0D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:02.099217 :BBD7D0DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.470324*:BBD7D335:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1754402403 eid=21108933 ctx=0x0000000777968158 cwh=0x0000000029320AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-05 22:00:03.470324*:BBD7D336:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x000000005426C4B8 pubTabIdxCnt=0 2025-08-05 22:00:03.470324*:BBD7D337:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-08-05 22:00:03.470324*:BBD7D338:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-08-05 22:00:03.470324*:BBD7D339:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301BAAF8 estart=1754402401 eid=16777502 ctx=0x000000077B54EAC8 cwh=0x0000000029320608 cpu=1187500 buffg=65570 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-05 22:00:03.470324*:BBD7D33A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301BAAF8 curCtx=0x0000000054081240 pubTabIdxCnt=0 2025-08-05 22:00:03.470324*:BBD7D33B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301BAAF8 2025-08-05 22:00:03.470324*:BBD7D33C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301BAAF8 2025-08-05 22:00:03.470324*:BBD7D33E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x000000005426C4B8 xsc->flg4=65792 flags=1 2025-08-05 22:00:03.470324*:BBD7D340:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-08-05 22:00:03.751580*:BBD7D361:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301BAAF8 curCtx=0x0000000054081240 xsc->flg4=65792 flags=2 2025-08-05 22:00:03.751580*:BBD7D362:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301BAAF8 2025-08-05 22:00:03.756446 :BBD7D363:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.776397 :BBD7D37B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.778195 :BBD7D383:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-08-05 22:00:03.778406 :BBD7D385:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-08-05 22:00:03.779445 :BBD7D38D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.779545 :BBD7D390:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.780418 :BBD7D3A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.781815 :BBD7D3B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.782086 :BBD7D3BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:03.829569*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-08-05 22:00:03.829569*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-05 22:00:03.829569*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-05 22:00:03.845192*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-05 22:00:03.845192*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-05 22:00:03.845192*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-05 22:00:03.845192*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-05 22:00:03.910491 :BBD7D487:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:05.791003 :BBD7D600:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:05.791015 :BBD7D601:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0008.01b.0000d0e0 2025-08-05 22:00:09.284195 :BBD7D7E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:09.285554 :BBD7D7EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:09.296286 :BBD7D7F7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-05 22:00:09.323757 :BBD7D7FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 15536, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de