Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250910220006\orcl_j002_23652_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250910220006\orcl_j002_23652_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:34867M/63366M, Ph+PgF:39588M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 23652, image: ORACLE.EXE (J002) *** 2025-09-10 22:00:06.502 *** SESSION ID:(225.29795) 2025-09-10 22:00:06.502 *** 2025-09-10 22:00:06.502 Process diagnostic dump for ORACLE.EXE (J002), OS id=23652, pid: 28, proc_ser: 220, sid: 225, sess_ser: 29795 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23652 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13059, hash value=4036766534 Current Wait Stack: Not in wait; last wait ended 2.669348 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.669374 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000171 sec, exc=0.000171 sec, total=0.000171 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.060755 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.765441 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.028490 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000003 sec, exc=0.000003 sec, total=0.000003 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012779 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000077 sec, exc=0.000077 sec, total=0.000077 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049514 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.006654 sec, exc=0.006654 sec, total=0.006654 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007953 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000077 sec, exc=0.000077 sec, total=0.000077 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.260695 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.004882 sec, exc=0.004882 sec, total=0.004882 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000178 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000126 sec, exc=0.000126 sec, total=0.000126 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008013 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000055 sec of elapsed time Sampled Session History of session 225 serial 29795 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00: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] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-10 22:00:06.502 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 23652, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-10 19:15:50.189427 :CBA70DE2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-10 19:15:50.189692 :CBA70DE3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-10 19:15:50.189707 :CBA70DE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-10 19:15:50.189926 :CBA70DE5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-10 19:15:50.189939 :CBA70DE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-10 19:15:50.190147 :CBA70DE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-10 19:15:50.190160 :CBA70DE8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-10 19:15:50.190365 :CBA70DE9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-10 19:15:50.190378 :CBA70DEA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-10 19:15:50.190587 :CBA70DEB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-10 19:15:50.190600 :CBA70DEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-10 19:15:50.193016 :CBA70DED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:30:51.953191 :CBA83A77:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:30:51.948 2025-09-10 19:30:51.953192 :CBA83A78:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23108 pso_num=28 pso_serial#=210 2025-09-10 19:30:51.953320 :CBA83A79:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23108 osp_idx=26 osp_ver=309573 osp_pg=0 (spawn #578819) 2025-09-10 19:30:51.953321 :CBA83A7A:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 19:30:51.953321 :CBA83A7B:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=145 time=1918303252 2025-09-10 19:30:51.953322 :CBA83A7C:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 19:30:51.953322 :CBA83A7D:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 19:30:51.953322 :CBA83A7E:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 19:30:51.953323 :CBA83A7F:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 19:30:51.953323 :CBA83A80:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 19:30:51.953793 :CBA83A84: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-09-10 19:30:51.953828 :CBA83A8A: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-09-10 19:30:51.973665 :CBA83AB4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:42:23.820132 :CBA92060:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:42:23.805 2025-09-10 19:42:23.820134 :CBA92061:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17500 pso_num=28 pso_serial#=211 2025-09-10 19:42:23.820278 :CBA92062:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17500 (legacy spawn) 2025-09-10 19:42:23.826773 :CBA92063: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-09-10 19:42:23.826938 :CBA92068: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-09-10 19:42:23.827731 :CBA9206D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:42:23.845183 :CBA92070:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:42:23.836 2025-09-10 19:42:23.845186 :CBA92071:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11772 pso_num=28 pso_serial#=212 2025-09-10 19:42:23.845325 :CBA92072:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11772 (legacy spawn) 2025-09-10 19:42:23.851795 :CBA92073: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-09-10 19:42:23.851961 :CBA9207C: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-09-10 19:42:23.852816 :CBA9207D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:42:26.922402 :CBA92165:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:42:26.909 2025-09-10 19:42:26.922404 :CBA92166:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23012 pso_num=28 pso_serial#=213 2025-09-10 19:42:26.922537 :CBA92167:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23012 (legacy spawn) 2025-09-10 19:42:26.929005 :CBA92168: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-09-10 19:42:26.929183 :CBA9216D: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-09-10 19:42:26.929973 :CBA92172:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:42:26.938738 :CBA92175:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:42:26.925 2025-09-10 19:42:26.938740 :CBA92176:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24260 pso_num=28 pso_serial#=214 2025-09-10 19:42:26.938874 :CBA92177:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24260 (legacy spawn) 2025-09-10 19:42:26.945445 :CBA92178: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-09-10 19:42:26.945620 :CBA92180: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-09-10 19:42:26.946393 :CBA92182:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:42:45.779609 :CBA927A7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:42:45.766 2025-09-10 19:42:45.779612 :CBA927A8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22084 pso_num=28 pso_serial#=215 2025-09-10 19:42:45.779757 :CBA927A9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22084 (legacy spawn) 2025-09-10 19:42:45.786664 :CBA927AA: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-09-10 19:42:45.786854 :CBA927AF: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-09-10 19:42:45.787802 :CBA927B4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 19:42:47.840758 :CBA9282D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 19:42:47.832 2025-09-10 19:42:47.840761 :CBA9282E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24280 pso_num=28 pso_serial#=216 2025-09-10 19:42:47.840893 :CBA9282F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24280 (legacy spawn) 2025-09-10 19:42:47.847285 :CBA92830: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-09-10 19:42:47.847466 :CBA92835: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-09-10 19:42:47.848242 :CBA9283A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 22:00:00.037172 :CBB3F85C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 22:00:00.026 2025-09-10 22:00:00.037174 :CBB3F85D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22732 pso_num=28 pso_serial#=217 2025-09-10 22:00:00.037329 :CBB3F85E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22732 osp_idx=26 osp_ver=309580 osp_pg=0 (spawn #579165) 2025-09-10 22:00:00.037329 :CBB3F85F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 22:00:00.037330 :CBB3F860:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=146 time=1927251330 2025-09-10 22:00:00.037330 :CBB3F861:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 22:00:00.037330 :CBB3F862:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 22:00:00.037331 :CBB3F863:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 22:00:00.037331 :CBB3F864:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 22:00:00.037331 :CBB3F865:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 22:00:00.037872 :CBB3F866: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-09-10 22:00:00.037918 :CBB3F86D: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-09-10 22:00:00.038002 :CBB3F881: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-09-10 22:00:00.041925 :CBB3F890: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-09-10 22:00:00.043187 :CBB3F898: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-09-10 22:00:00.043214 :CBB3F89B: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-09-10 22:00:00.043257 :CBB3F8A6: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-09-10 22:00:00.045132 :CBB3F8B1: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-09-10 22:00:00.045163 :CBB3F8B4: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-09-10 22:00:00.045209 :CBB3F8BF: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-09-10 22:00:00.045687 :CBB3F8CA: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-09-10 22:00:00.048369 :CBB3F8D2: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-09-10 22:00:00.053645 :CBB3F8DA: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-09-10 22:00:00.059238 :CBB3F8E2: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-09-10 22:00:00.060607 :CBB3F8EA: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-09-10 22:00:00.063886 :CBB3F8F2: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-09-10 22:00:00.065204 :CBB3F8FA: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-09-10 22:00:00.068364 :CBB3F902: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-09-10 22:00:00.071835 :CBB3F90A: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-09-10 22:00:00.072236 :CBB3F919: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-09-10 22:00:00.072310 :CBB3F91C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-10 22:00:00.072324 :CBB3F91D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-10 22:00:00.074531 :CBB3F923: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-09-10 22:00:00.074578 :CBB3F926:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-10 22:00:00.074590 :CBB3F927:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-10 22:00:00.076513 :CBB3F92D: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-09-10 22:00:00.076556 :CBB3F930:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:ktcc.c:879 2025-09-10 22:00:00.076567 :CBB3F931:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:ktcc.c:879 2025-09-10 22:00:00.076734 :CBB3F937: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-09-10 22:00:00.076750 :CBB3F93A: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-09-10 22:00:00.076793 :CBB3F945: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-09-10 22:00:00.078524 :CBB3F94F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 22:00:01.900306 :CBB3FA30:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 22:00:01.885 2025-09-10 22:00:01.900308 :CBB3FA31:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21120 pso_num=28 pso_serial#=218 2025-09-10 22:00:01.900452 :CBB3FA32:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21120 (legacy spawn) 2025-09-10 22:00:01.907032 :CBB3FA33: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-09-10 22:00:01.907200 :CBB3FA38: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-09-10 22:00:01.908003 :CBB3FA3D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 22:00:01.916770 :CBB3FA40:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 22:00:01.901 2025-09-10 22:00:01.916772 :CBB3FA41:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23968 pso_num=28 pso_serial#=219 2025-09-10 22:00:01.916931 :CBB3FA42:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23968 (legacy spawn) 2025-09-10 22:00:01.937260 :CBB3FA43: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-09-10 22:00:01.937435 :CBB3FA4C: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-09-10 22:00:01.938228 :CBB3FA4D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-10 22:00:02.032071 :CBB3FA71:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-10 22:00:02.026 2025-09-10 22:00:02.032073 :CBB3FA72:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23652 pso_num=28 pso_serial#=220 2025-09-10 22:00:02.032223 :CBB3FA73:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=23652 osp_idx=26 osp_ver=309583 osp_pg=0 (spawn #579166) 2025-09-10 22:00:02.032224 :CBB3FA74:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-10 22:00:02.032225 :CBB3FA75:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=147 time=1927253330 2025-09-10 22:00:02.032225 :CBB3FA76:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-10 22:00:02.032226 :CBB3FA77:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-10 22:00:02.032226 :CBB3FA78:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-10 22:00:02.032226 :CBB3FA79:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-10 22:00:02.032226 :CBB3FA7A:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-10 22:00:02.032744 :CBB3FA7F: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-09-10 22:00:02.037763 :CBB3FAA8: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-09-10 22:00:02.037809 :CBB3FAAB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:jslv.c:9203 2025-09-10 22:00:02.055220 :CBB3FC9C: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-09-10 22:00:02.055449 :CBB3FCA4: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-09-10 22:00:02.055619 :CBB3FCAC: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-09-10 22:00:02.055793 :CBB3FCB4: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-09-10 22:00:02.055963 :CBB3FCBC: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-09-10 22:00:02.056116 :CBB3FCD1: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-09-10 22:00:02.056270 :CBB3FCE3: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-09-10 22:00:02.056423 :CBB3FCEB: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-09-10 22:00:02.056583 :CBB3FCF5: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-09-10 22:00:02.056728 :CBB3FCFD: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-09-10 22:00:02.057198 :CBB3FD0E: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-09-10 22:00:02.057525 :CBB3FD16: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-09-10 22:00:02.057964 :CBB3FD1E: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-09-10 22:00:02.058136 :CBB3FD26: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-09-10 22:00:02.058297 :CBB3FD2E: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-09-10 22:00:02.058451 :CBB3FD38: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-09-10 22:00:02.058596 :CBB3FD40: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-09-10 22:00:02.058741 :CBB3FD48: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-09-10 22:00:02.058900 :CBB3FD68: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-09-10 22:00:02.059067 :CBB3FD70: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-09-10 22:00:02.059208 :CBB3FD78: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-09-10 22:00:02.059350 :CBB3FD80: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-09-10 22:00:02.059495 :CBB3FD88: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-09-10 22:00:02.059650 :CBB3FD90: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-09-10 22:00:02.059796 :CBB3FD98: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-09-10 22:00:02.059941 :CBB3FDA0: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-09-10 22:00:02.083616 :CBB3FDA8: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-09-10 22:00:03.738558 :CBB3FFF8: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-09-10 22:00:03.746901*:CBB40017:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002B53B6F0 estart=1757512801 eid=16777578 ctx=0x000000077B54EAC8 cwh=0x000000002B530068 cpu=1484375 buffg=79941 interb=196608 r=24 rb=196608 w=0 wb=0 2025-09-10 22:00:03.746901*:CBB40018:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002B53B6F0 curCtx=0x000000005F127FD8 pubTabIdxCnt=0 2025-09-10 22:00:03.746901*:CBB40019:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002B53B6F0 2025-09-10 22:00:03.746901*:CBB4001A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002B53B6F0 2025-09-10 22:00:03.746901*:CBB40039:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002B53B6F0 curCtx=0x000000005F127FD8 xsc->flg4=65792 flags=2 2025-09-10 22:00:03.746901*:CBB4003A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002B53B6F0 2025-09-10 22:00:03.755876 :CBB4003B: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-09-10 22:00:03.776194 :CBB40065: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-09-10 22:00:03.778181 :CBB40076:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:psdpgi.c:2235 2025-09-10 22:00:03.778375 :CBB40078:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:psdpgi.c:2235 2025-09-10 22:00:03.779241 :CBB4007A: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-09-10 22:00:03.779331 :CBB4007D: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-09-10 22:00:03.780163 :CBB40092: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-09-10 22:00:03.781696 :CBB4009C: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-09-10 22:00:03.781968 :CBB400A4: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-09-10 22:00:03.840658*: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-10 22:00:03.840658*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-09-10 22:00:03.840658*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-09-10 22:00:03.887539*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-09-10 22:00:03.887539*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-09-10 22:00:03.887539*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-09-10 22:00:03.887539*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-09-10 22:00:03.949954 :CBB4016A: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-09-10 22:00:05.874741 :CBB402CA: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-09-10 22:00:05.874754 :CBB402CB:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0007.00a.0000cc3f ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 28 (osid: 23652, J002)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de