Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831060005\orcl_j003_21328_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831060005\orcl_j003_21328_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:35081M/63366M, Ph+PgF:40058M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 21328, image: ORACLE.EXE (J003) *** 2025-08-31 06:00:05.176 *** SESSION ID:(233.33645) 2025-08-31 06:00:05.176 *** 2025-08-31 06:00:05.176 Process diagnostic dump for ORACLE.EXE (J003), OS id=21328, pid: 29, proc_ser: 176, sid: 233, sess_ser: 33645 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21328 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_13005, hash value=1938327888 Current Wait Stack: Not in wait; last wait ended 3.037423 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 3.037449 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=40 seq_num=41 snap_id=1 wait times: snap=0.000170 sec, exc=0.000170 sec, total=0.000170 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.066216 sec of elapsed time 1: 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.000007 sec, exc=0.000007 sec, total=0.000007 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.040711 sec of elapsed time 2: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x3, filetype=0x2 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000188 sec, exc=0.000188 sec, total=0.000188 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.721391 sec of elapsed time 3: 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.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.026777 sec of elapsed time 4: 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.013239 sec of elapsed time 5: 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.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.052910 sec of elapsed time 6: 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.007550 sec, exc=0.007550 sec, total=0.007550 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008027 sec of elapsed time 7: 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.000066 sec, exc=0.000066 sec, total=0.000066 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.261775 sec of elapsed time 8: 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.005718 sec, exc=0.005718 sec, total=0.005718 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000174 sec of elapsed time 9: 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.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007904 sec of elapsed time Sampled Session History of session 233 serial 33645 --------------------------------------------------- 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, 06:00:01 - 06:00:04] not in wait at each sample [1 sample, 06:00:00] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 06:00:05.176 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 21328, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 01:04:58.188436 :C6E0EDAF:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14024 osp_idx=27 osp_ver=16743 osp_pg=0 (spawn #542921) 2025-08-31 01:04:58.188436 :C6E0EDB0:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 01:04:58.188437 :C6E0EDB1:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=65 time=987949487 2025-08-31 01:04:58.188437 :C6E0EDB2:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 01:04:58.188438 :C6E0EDB3:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 01:04:58.188438 :C6E0EDB4:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 01:04:58.188438 :C6E0EDB5:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 01:04:58.188439 :C6E0EDB6:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 01:04:58.189014 :C6E0EDB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 01:04:58.189063 :C6E0EDBD:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 01:04:58.197684 :C6E0EDE2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 01:14:59.428650 :C6E1BE31:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 01:14:59.416 2025-08-31 01:14:59.428652 :C6E1BE32:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19228 pso_num=29 pso_serial#=170 2025-08-31 01:14:59.428781 :C6E1BE33:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19228 osp_idx=27 osp_ver=16744 osp_pg=0 (spawn #542944) 2025-08-31 01:14:59.428781 :C6E1BE34:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 01:14:59.428782 :C6E1BE35:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=66 time=988550721 2025-08-31 01:14:59.428782 :C6E1BE36:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 01:14:59.428783 :C6E1BE37:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 01:14:59.428783 :C6E1BE38:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 01:14:59.428783 :C6E1BE39:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 01:14:59.428784 :C6E1BE3A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 01:14:59.429290 :C6E1BE3B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 01:14:59.429336 :C6E1BE41:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 01:14:59.433439 :C6E1BE5A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:14:59.433481 :C6E1BE5B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:14:59.433732 :C6E1BE5C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:14:59.433752 :C6E1BE5D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:14:59.434053 :C6E1BE5E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:14:59.434067 :C6E1BE5F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:14:59.434285 :C6E1BE60:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:14:59.434299 :C6E1BE61:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:14:59.434516 :C6E1BE62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:14:59.434529 :C6E1BE63:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:14:59.434743 :C6E1BE64:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:14:59.434756 :C6E1BE65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:14:59.437031 :C6E1BE66:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 01:45:03.089663 :C6E412A3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 01:45:03.077 2025-08-31 01:45:03.089666 :C6E412A4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23024 pso_num=29 pso_serial#=171 2025-08-31 01:45:03.089803 :C6E412A5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23024 osp_idx=27 osp_ver=16745 osp_pg=0 (spawn #543011) 2025-08-31 01:45:03.089804 :C6E412A6:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 01:45:03.089805 :C6E412A7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=67 time=990354393 2025-08-31 01:45:03.089805 :C6E412A8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 01:45:03.089805 :C6E412A9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 01:45:03.089806 :C6E412AA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 01:45:03.089806 :C6E412AB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 01:45:03.089806 :C6E412AC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 01:45:03.090317 :C6E412AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 01:45:03.090357 :C6E412B3:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 01:45:03.094555 :C6E412DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:45:03.094599 :C6E412DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:45:03.094853 :C6E412DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:45:03.094868 :C6E412DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:45:03.095090 :C6E412E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:45:03.095103 :C6E412E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:45:03.095310 :C6E412E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:45:03.095323 :C6E412E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:45:03.095526 :C6E412E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:45:03.095538 :C6E412E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:45:03.095745 :C6E412E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517 2025-08-31 01:45:03.095758 :C6E412E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517 2025-08-31 01:45:03.098264 :C6E412E8:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 02:00:04.994650 :C6E541CE:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 02:00:04.991 2025-08-31 02:00:04.994651 :C6E541CF:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22444 pso_num=29 pso_serial#=172 2025-08-31 02:00:04.994780 :C6E541D0:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22444 osp_idx=27 osp_ver=16746 osp_pg=0 (spawn #543048) 2025-08-31 02:00:04.994780 :C6E541D1:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 02:00:04.994781 :C6E541D2:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=68 time=991256283 2025-08-31 02:00:04.994781 :C6E541D3:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 02:00:04.994782 :C6E541D4:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 02:00:04.994782 :C6E541D5:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 02:00:04.994783 :C6E541D6:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 02:00:04.994783 :C6E541D7:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 02:00:04.995320 :C6E541D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 02:00:04.995368 :C6E541DE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 02:00:05.004105 :C6E541F3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 03:00:12.282534 :C6E9F6D3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 03:00:12.280 2025-08-31 03:00:12.282535 :C6E9F6D4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22900 pso_num=29 pso_serial#=173 2025-08-31 03:00:12.282667 :C6E9F6D5:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=22900 osp_idx=27 osp_ver=16747 osp_pg=0 (spawn #543187) 2025-08-31 03:00:12.282668 :C6E9F6D6:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 03:00:12.282668 :C6E9F6D7:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=69 time=994863580 2025-08-31 03:00:12.282669 :C6E9F6D8:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 03:00:12.282669 :C6E9F6D9:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 03:00:12.282669 :C6E9F6DA:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 03:00:12.282670 :C6E9F6DB:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 03:00:12.282670 :C6E9F6DC:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 03:00:12.283186 :C6E9F6DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 03:00:12.283228 :C6E9F6E3:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 03:00:12.291716 :C6E9F708:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 04:00:19.652437 :C6EEA968:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 04:00:19.650 2025-08-31 04:00:19.652438 :C6EEA969:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=23296 pso_num=29 pso_serial#=174 2025-08-31 04:00:19.652578 :C6EEA96A:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=23296 osp_idx=27 osp_ver=16748 osp_pg=0 (spawn #543325) 2025-08-31 04:00:19.652578 :C6EEA96B:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 04:00:19.652578 :C6EEA96C:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=70 time=998470940 2025-08-31 04:00:19.652579 :C6EEA96D:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 04:00:19.652579 :C6EEA96E:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 04:00:19.652580 :C6EEA96F:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 04:00:19.652580 :C6EEA970:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 04:00:19.652580 :C6EEA971:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 04:00:19.653080 :C6EEA972:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 04:00:19.653128 :C6EEA978:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 04:00:19.661388 :C6EEA99B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 05:00:26.659471 :C6F35179:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 05:00:26.657 2025-08-31 05:00:26.659472 :C6F3517A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20940 pso_num=29 pso_serial#=175 2025-08-31 05:00:26.659614 :C6F3517B:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=20940 osp_idx=27 osp_ver=16749 osp_pg=0 (spawn #543464) 2025-08-31 05:00:26.659615 :C6F3517C:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 05:00:26.659615 :C6F3517D:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=71 time=1002077955 2025-08-31 05:00:26.659616 :C6F3517E:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 05:00:26.659616 :C6F3517F:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 05:00:26.659617 :C6F35180:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 05:00:26.659617 :C6F35181:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 05:00:26.659617 :C6F35182:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 05:00:26.660136 :C6F35183:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 05:00:26.660181 :C6F35189:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-31 05:00:26.668537 :C6F3519E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-31 06:00:00.343621 :C6F7E8D3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-31 06:00:00.337 2025-08-31 06:00:00.343622 :C6F7E8D4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21328 pso_num=29 pso_serial#=176 2025-08-31 06:00:00.343747 :C6F7E8D8:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21328 osp_idx=27 osp_ver=16750 osp_pg=0 (spawn #543603) 2025-08-31 06:00:00.343748 :C6F7E8D9:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 06:00:00.343749 :C6F7E8DA:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=72 time=1005651643 2025-08-31 06:00:00.343749 :C6F7E8DB:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 06:00:00.343750 :C6F7E8DC:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 06:00:00.343751 :C6F7E8DD:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 06:00:00.343751 :C6F7E8DE:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 06:00:00.343751 :C6F7E8DF:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 06:00:00.344265 :C6F7E8E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.349102 :C6F7E902:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.349139 :C6F7E905:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-31 06:00:00.367104 :C6F7EB22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.367300 :C6F7EB2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.367466 :C6F7EB32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.367618 :C6F7EB3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.367795 :C6F7EB42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.367960 :C6F7EB4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.368111 :C6F7EB52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.368269 :C6F7EB5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.368426 :C6F7EB62:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.368577 :C6F7EB6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.368727 :C6F7EB72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.368867 :C6F7EB7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369090 :C6F7EB82:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369246 :C6F7EB8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369386 :C6F7EB92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369545 :C6F7EB9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369688 :C6F7EBA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369826 :C6F7EBAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.369969 :C6F7EBB2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.370174 :C6F7EBBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.370352 :C6F7EBC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.370501 :C6F7EBCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:00.375449 :C6F7EBD6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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-31 06:00:00.390053 :C6F7EBE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:01.712542*:C6F7EE2A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1756591201 eid=21924936 ctx=0x0000000777968158 cwh=0x00000000301B09C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-31 06:00:01.712542*:C6F7EE2B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x000000005C0A7918 pubTabIdxCnt=0 2025-08-31 06:00:01.712542*:C6F7EE2D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0 2025-08-31 06:00:01.712542*:C6F7EE2F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0 2025-08-31 06:00:01.712542*:C6F7EE30:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301FB6F0 estart=1756591199 eid=16777556 ctx=0x000000077B54EAC8 cwh=0x00000000301B0518 cpu=1140625 buffg=63218 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-31 06:00:01.712542*:C6F7EE32:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301FB6F0 curCtx=0x000000005BF887D0 pubTabIdxCnt=0 2025-08-31 06:00:01.712542*:C6F7EE33:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301FB6F0 2025-08-31 06:00:01.712542*:C6F7EE34:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301FB6F0 2025-08-31 06:00:01.712542*:C6F7EE38:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x000000005C0A7918 xsc->flg4=65792 flags=1 2025-08-31 06:00:01.712542*:C6F7EE39:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0 2025-08-31 06:00:02.056293*:C6F7EF0B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301FB6F0 curCtx=0x000000005BF887D0 xsc->flg4=65792 flags=2 2025-08-31 06:00:02.056293*:C6F7EF0C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301FB6F0 2025-08-31 06:00:02.059650 :C6F7EF0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.082266 :C6F7EF2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.084460 :C6F7EF37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-31 06:00:02.084677 :C6F7EF38:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-31 06:00:02.085649 :C6F7EF39:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.085750 :C6F7EF3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.086656 :C6F7EF4E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.088437 :C6F7EF56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.088757 :C6F7EF5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:02.150044*: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-31 06:00:02.150044*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-31 06:00:02.150044*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-31 06:00:02.150044*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-31 06:00:02.150044*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-31 06:00:02.150044*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-31 06:00:02.150044*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-31 06:00:02.227487 :C6F7EFB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:04.135702 :C6F7F0D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 06:00:04.135717 :C6F7F0D5:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.00a.0001dc1c ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 21328, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de