Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250710220004\orcl_j005_17472_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250710220004\orcl_j005_17472_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:36451M/63366M, Ph+PgF:42544M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 31 Windows thread id: 17472, image: ORACLE.EXE (J005) *** 2025-07-10 22:00:04.746 *** SESSION ID:(249.1335) 2025-07-10 22:00:04.746 *** 2025-07-10 22:00:04.746 Process diagnostic dump for ORACLE.EXE (J005), OS id=17472, pid: 31, proc_ser: 139, sid: 249, sess_ser: 1335 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17472 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12729, hash value=171824684 Current Wait Stack: Not in wait; last wait ended 2.072326 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.072352 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=41 seq_num=42 snap_id=1 wait times: snap=0.000150 sec, exc=0.000150 sec, total=0.000150 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.068158 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000005 sec, exc=0.000005 sec, total=0.000005 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.749718 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.025187 sec of elapsed time 3: 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.000002 sec, exc=0.000002 sec, total=0.000002 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012933 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.000097 sec, exc=0.000097 sec, total=0.000097 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049341 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.004897 sec, exc=0.004897 sec, total=0.004897 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008600 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000075 sec, exc=0.000075 sec, total=0.000075 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.263018 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.004231 sec, exc=0.004231 sec, total=0.004231 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000176 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000128 sec, exc=0.000128 sec, total=0.000128 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007861 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=32 seq_num=33 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.000054 sec of elapsed time Sampled Session History of session 249 serial 1335 --------------------------------------------------- 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 --------------------------------------------------- [4 samples, 22:00:01 - 22:00:04] not in wait at each sample [1 sample, 22:00:00] 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-07-10 22:00:04.746 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 31 (osid: 17472, J005) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-07 23:59:54.890154 :AEF8EEA9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-07 23:59:54.897154 :AEF8EEAC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-07 23:59:54.884 2025-07-07 23:59:54.897156 :AEF8EEAD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8884 pso_num=31 pso_serial#=126 2025-07-07 23:59:54.897270 :AEF8EEAE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8884 (legacy spawn) 2025-07-07 23:59:54.923198 :AEF8EEAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 23:59:58.218025 :AEF8EFCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-07 23:59:58.218215 :AEF8EFCF:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-07 23:59:58.219430 :AEF8EFD4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-07 23:59:58.478887 :AEF8F000:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-07 23:59:58.467 2025-07-07 23:59:58.478889 :AEF8F001:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16756 pso_num=31 pso_serial#=127 2025-07-07 23:59:58.479040 :AEF8F002:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16756 (legacy spawn) 2025-07-07 23:59:58.494285 :AEF8F003:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 00:06:18.453135 :AEF97D77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 00:06:18.453296 :AEF97D80:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-08 00:06:18.454476 :AEF97D81:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-08 22:00:00.213794 :AF625EBF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-08 22:00:00.206 2025-07-08 22:00:00.213795 :AF625EC0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18024 pso_num=31 pso_serial#=128 2025-07-08 22:00:00.213917 :AF625EC1:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18024 osp_idx=29 osp_ver=637 osp_pg=0 (spawn #365841) 2025-07-08 22:00:00.213918 :AF625EC2:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-08 22:00:00.213918 :AF625EC3:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=196 time=692776111 2025-07-08 22:00:00.213919 :AF625EC4:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-07-08 22:00:00.213919 :AF625EC5:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-07-08 22:00:00.213919 :AF625EC6:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-07-08 22:00:00.213920 :AF625EC7:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-08 22:00:00.213920 :AF625EC8:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-08 22:00:00.214424 :AF625EC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-08 22:00:00.316103 :AF6261D6:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-08 22:00:30.427812 :AF627290:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-08 23:59:09.958143 :AF6BE718:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-08 23:59:09.954 2025-07-08 23:59:09.958145 :AF6BE719:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18068 pso_num=31 pso_serial#=129 2025-07-08 23:59:09.958289 :AF6BE71A:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=18068 (legacy spawn) 2025-07-08 23:59:09.973035 :AF6BE71B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 23:59:43.117027 :AF6BF3B3:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-08 23:59:45.550822 :AF6BF4EC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-08 23:59:45.536 2025-07-08 23:59:45.550824 :AF6BF4ED:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9372 pso_num=31 pso_serial#=130 2025-07-08 23:59:45.550979 :AF6BF4EE:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9372 (legacy spawn) 2025-07-08 23:59:45.564960 :AF6BF4EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 23:59:48.790942 :AF6BF60E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-08 23:59:48.791115 :AF6BF617:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-08 23:59:48.792405 :AF6BF618:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-08 23:59:50.855278 :AF6BF69B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-08 23:59:50.849 2025-07-08 23:59:50.855280 :AF6BF69C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=8156 pso_num=31 pso_serial#=131 2025-07-08 23:59:50.855418 :AF6BF69D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=8156 (legacy spawn) 2025-07-08 23:59:50.869629 :AF6BF69E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 00:00:24.027923 :AF6C034A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 22:00:00.733826 :AFD50661:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 22:00:00.724 2025-07-09 22:00:00.733828 :AFD50662:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17956 pso_num=31 pso_serial#=132 2025-07-09 22:00:00.733960 :AFD50663:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17956 (legacy spawn) 2025-07-09 22:00:00.740628 :AFD50664:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:00.740806 :AFD5066D:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-09 22:00:00.741716 :AFD5066E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 22:00:14.309787 :AFD51125:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 22:00:14.299 2025-07-09 22:00:14.309789 :AFD51126:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13380 pso_num=31 pso_serial#=133 2025-07-09 22:00:14.309924 :AFD51127:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=13380 osp_idx=29 osp_ver=642 osp_pg=0 (spawn #369155) 2025-07-09 22:00:14.309925 :AFD51128:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-09 22:00:14.309925 :AFD51129:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=197 time=779190204 2025-07-09 22:00:14.309925 :AFD5112A:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-07-09 22:00:14.309926 :AFD5112B:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-07-09 22:00:14.309926 :AFD5112C:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-07-09 22:00:14.309927 :AFD5112D:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-09 22:00:14.309927 :AFD5112E:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-09 22:00:14.310425 :AFD5112F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.310468 :AFD51135:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-07-09 22:00:14.317689 :AFD51143:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.318749 :AFD5114B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.319248 :AFD51153:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.319685 :AFD5115B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.320111 :AFD51163:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.322614 :AFD5116B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.323731 :AFD51173:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.373485 :AFD51187:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.374445 :AFD5118F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.374686 :AFD51197:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.374902 :AFD5119F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 22:00:14.376693 :AFD511A7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 23:59:06.220055 :AFDE81EF:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 23:59:06.212 2025-07-09 23:59:06.220057 :AFDE81F0:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17764 pso_num=31 pso_serial#=134 2025-07-09 23:59:06.220199 :AFDE81F1:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17764 (legacy spawn) 2025-07-09 23:59:06.226859 :AFDE81F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:06.226888 :AFDE81F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:06.227049 :AFDE81F8:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-09 23:59:06.227886 :AFDE8209:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 23:59:09.829949 :AFDE833B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 23:59:09.820 2025-07-09 23:59:09.829950 :AFDE833C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16452 pso_num=31 pso_serial#=135 2025-07-09 23:59:09.830098 :AFDE833D:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16452 (legacy spawn) 2025-07-09 23:59:09.844486 :AFDE833E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:13.116291 :AFDE8444:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:13.116469 :AFDE8449:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-09 23:59:13.117683 :AFDE844E:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 23:59:13.368644 :AFDE8474:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 23:59:13.354 2025-07-09 23:59:13.368646 :AFDE8475:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=6984 pso_num=31 pso_serial#=136 2025-07-09 23:59:13.368792 :AFDE8476:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=6984 (legacy spawn) 2025-07-09 23:59:13.383386 :AFDE8477:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:46.510105 :AFDE908A:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 23:59:46.630370 :AFDE908D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 23:59:46.616 2025-07-09 23:59:46.630372 :AFDE908E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=16372 pso_num=31 pso_serial#=137 2025-07-09 23:59:46.630513 :AFDE908F:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=16372 (legacy spawn) 2025-07-09 23:59:46.644406 :AFDE9090:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:49.901083 :AFDE91AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-09 23:59:49.901268 :AFDE91B2:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-09 23:59:49.902376 :AFDE91B7:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-09 23:59:50.163505 :AFDE91C6:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-09 23:59:50.155 2025-07-09 23:59:50.163507 :AFDE91C7:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17348 pso_num=31 pso_serial#=138 2025-07-09 23:59:50.163648 :AFDE91C8:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17348 (legacy spawn) 2025-07-09 23:59:50.178803 :AFDE91C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 00:00:23.334364 :AFDE9DAA:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted 2025-07-10 22:00:00.876872 :B045C5D7:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-10 22:00:00.870 2025-07-10 22:00:00.876873 :B045C5D8:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17472 pso_num=31 pso_serial#=139 2025-07-10 22:00:00.877018 :B045C5D9:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17472 osp_idx=29 osp_ver=648 osp_pg=0 (spawn #372488) 2025-07-10 22:00:00.877018 :B045C5DA:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-10 22:00:00.877019 :B045C5DB:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=198 time=865576767 2025-07-10 22:00:00.877019 :B045C5DC:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs 2025-07-10 22:00:00.877020 :B045C5DD:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs 2025-07-10 22:00:00.877020 :B045C5DE:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs 2025-07-10 22:00:00.877021 :B045C5DF:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-10 22:00:00.877021 :B045C5E0:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-10 22:00:00.877654 :B045C60D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.882737 :B045C74D:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] KSL POST RCVD poster=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-10 22:00:00.883367 :B045C756:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.883395 :B045C759:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:jslv.c:9203 2025-07-10 22:00:00.901875 :B045C7F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:00.901922 :B045C7F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.902270 :B045C816:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:00.902295 :B045C818:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.902431 :B045C829:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:00.902455 :B045C82B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.902599 :B045C843:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:00.902622 :B045C845:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.902799 :B045C857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.902968 :B045C85F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.903120 :B045C867:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.903259 :B045C873:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:00.903283 :B045C876:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.903438 :B045C888:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.903592 :B045C890:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.903744 :B045C898:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.903918 :B045C8A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.904073 :B045C8A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.904224 :B045C8B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.904415 :B045C8B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.904578 :B045C8C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.904846 :B045C8CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.905012 :B045C8D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.905170 :B045C8DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.905533 :B045C8E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.905719 :B045C8EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.905885 :B045C8F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.906149 :B045C8FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.906411 :B045C906:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.906652 :B045C90E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.907261 :B045C916:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:00.910266 :B045C933:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:00.934656 :B045C93C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.541057*:B045CB8D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000004CF83AD0 estart=1752156002 eid=20235512 ctx=0x0000000777968158 cwh=0x0000000034540AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-07-10 22:00:02.541057*:B045CB8E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000004CF83AD0 curCtx=0x000000002DD288B0 pubTabIdxCnt=0 2025-07-10 22:00:02.541057*:B045CB8F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000004CF83AD0 2025-07-10 22:00:02.541057*:B045CB90:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000004CF83AD0 2025-07-10 22:00:02.541057*:B045CB91:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000004CFBAAF8 estart=1752156000 eid=16777444 ctx=0x000000077B54EAC8 cwh=0x0000000034540608 cpu=1437500 buffg=77304 interb=196608 r=24 rb=196608 w=0 wb=0 2025-07-10 22:00:02.541057*:B045CB92:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000004CFBAAF8 curCtx=0x000000002DC91240 pubTabIdxCnt=0 2025-07-10 22:00:02.541057*:B045CB93:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000004CFBAAF8 2025-07-10 22:00:02.541057*:B045CB94:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000004CFBAAF8 2025-07-10 22:00:02.541057*:B045CB95:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000004CF83AD0 curCtx=0x000000002DD288B0 xsc->flg4=65792 flags=1 2025-07-10 22:00:02.541057*:B045CB96:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000004CF83AD0 2025-07-10 22:00:02.567642 :B045CBAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:02.575846 :B045CBCC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] 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-07-10 22:00:02.587943*:B045CBDB:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000004CFBAAF8 curCtx=0x000000002DC91240 xsc->flg4=65792 flags=2 2025-07-10 22:00:02.587943*:B045CBDC:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000004CFBAAF8 2025-07-10 22:00:02.588080 :B045CBDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.608269 :B045CC15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.610191 :B045CC1E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235 2025-07-10 22:00:02.610385 :B045CC1F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235 2025-07-10 22:00:02.611309 :B045CC20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.611391 :B045CC23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.612226 :B045CC35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.613769 :B045CC3D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.614051 :B045CC45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-07-10 22:00:02.682800*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-07-10 22:00:02.761872 :B045CCDD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:04.630980 :B045CE6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-10 22:00:04.630991 :B045CE6C:db_trace:ktur.c@3079:ktuabt(): [10444:31:249] ABORT TRANSACTION - xid: 0x0008.004.0000ce91 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 31 (osid: 17472, J005)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de