Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250613220004\orcl_j003_16132_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250613220004\orcl_j003_16132_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:36709M/63366M, Ph+PgF:43188M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 16132, image: ORACLE.EXE (J003) *** 2025-06-13 22:00:04.807 *** SESSION ID:(233.25331) 2025-06-13 22:00:04.807 *** 2025-06-13 22:00:04.807 Process diagnostic dump for ORACLE.EXE (J003), OS id=16132, pid: 29, proc_ser: 135, sid: 233, sess_ser: 25331 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16132 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12584, hash value=3524936693 Current Wait Stack: Not in wait; last wait ended 2.592150 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.592176 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.000174 sec, exc=0.000174 sec, total=0.000174 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.063495 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.000006 sec, exc=0.000006 sec, total=0.000006 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.021293 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.000155 sec, exc=0.000155 sec, total=0.000155 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.730718 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.025363 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.013095 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.000085 sec, exc=0.000085 sec, total=0.000085 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049830 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.005818 sec, exc=0.005818 sec, total=0.005818 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008035 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.000071 sec, exc=0.000071 sec, total=0.000071 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262735 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.005118 sec, exc=0.005118 sec, total=0.005118 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000171 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.000122 sec, exc=0.000122 sec, total=0.000122 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007941 sec of elapsed time Sampled Session History of session 233 serial 25331 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:01 - 22:00:03] not in wait at each sample [1 sample, 22:00:00] waited for 'db file sequential read', seq_num: 27 p1: 'file#'=0x6 p2: 'block#'=0x1b2 p3: 'blocks'=0x1 time_waited: 0.006392 sec (sample interval: 0 sec) [1 sample, 21:59:59] idle wait at each sample [session created at: 22:00:00] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 22:00:00 ] time_waited: 0.006392 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-13 22:00:04.807 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 16132, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-12 22:24:32.935226 :A3DEFC2A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:24:32.935226 :A3DEFC2B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:24:32.935227 :A3DEFC2C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:24:32.936004 :A3DEFC2D: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-06-12 22:24:32.936053 :A3DEFC33: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-06-12 22:24:33.039616 :A3DEFC65:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 22:24:34.238858 :A3DEFCB1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 22:24:34.234 2025-06-12 22:24:34.238860 :A3DEFCB2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15128 pso_num=29 pso_serial#=122 2025-06-12 22:24:34.238994 :A3DEFCB3:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15128 osp_idx=27 osp_ver=12616 osp_pg=0 (spawn #279199) 2025-06-12 22:24:34.238995 :A3DEFCB4:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 22:24:34.238995 :A3DEFCB5:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=176 time=2742817407 2025-06-12 22:24:34.238996 :A3DEFCB6:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 22:24:34.238996 :A3DEFCB7:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 22:24:34.238996 :A3DEFCB8:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 22:24:34.238997 :A3DEFCB9:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 22:24:34.238997 :A3DEFCBA:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 22:24:34.239517 :A3DEFCBB: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-06-12 22:24:34.239567 :A3DEFCC1: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-06-12 22:24:35.617578 :A3DF0177:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 23:59:09.785146 :A3E670E2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 23:59:09.775 2025-06-12 23:59:09.785149 :A3E670E3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16076 pso_num=29 pso_serial#=123 2025-06-12 23:59:09.785310 :A3E670E4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16076 (legacy spawn) 2025-06-12 23:59:09.800441 :A3E670E5: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-06-12 23:59:12.764660 :A3E671D2: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-06-12 23:59:12.764834 :A3E671D7:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-12 23:59:12.766048 :A3E671DC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 23:59:13.125110 :A3E67215:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 23:59:13.120 2025-06-12 23:59:13.125112 :A3E67216:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14976 pso_num=29 pso_serial#=124 2025-06-12 23:59:13.125252 :A3E67217:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=14976 osp_idx=27 osp_ver=12618 osp_pg=0 (spawn #279419) 2025-06-12 23:59:13.125253 :A3E67218:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-12 23:59:13.125253 :A3E67219:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=177 time=2748496313 2025-06-12 23:59:13.125253 :A3E6721A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-12 23:59:13.125254 :A3E6721B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-12 23:59:13.125254 :A3E6721C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-12 23:59:13.125255 :A3E6721D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-12 23:59:13.125255 :A3E6721E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-12 23:59:13.125750 :A3E6721F: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-06-12 23:59:13.126415 :A3E67234: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-06-12 23:59:13.133996 :A3E67250:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-12 23:59:13.134378 :A3E67251: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-06-12 23:59:13.134412 :A3E67254:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-12 23:59:13.135357 :A3E6725A: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-06-12 23:59:13.135495 :A3E67263:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-12 23:59:43.209657 :A3E67D89:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 23:59:46.320219 :A3E67E7A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 23:59:46.312 2025-06-12 23:59:46.320221 :A3E67E7B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4800 pso_num=29 pso_serial#=125 2025-06-12 23:59:46.320355 :A3E67E7C:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4800 (legacy spawn) 2025-06-12 23:59:46.334159 :A3E67E7D: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-06-12 23:59:49.598007 :A3E67F98: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-06-12 23:59:49.598035 :A3E67F9B: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-06-12 23:59:49.598177 :A3E67F9E:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-12 23:59:49.599257 :A3E67FAF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 23:59:50.447936 :A3E68016:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 23:59:50.445 2025-06-12 23:59:50.447938 :A3E68017:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15516 pso_num=29 pso_serial#=126 2025-06-12 23:59:50.448074 :A3E68018:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15516 (legacy spawn) 2025-06-12 23:59:50.462918 :A3E68044: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-06-12 23:59:53.701355 :A3E68156: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-06-12 23:59:53.701540 :A3E6815B:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-12 23:59:53.702795 :A3E68160:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 23:59:54.509395 :A3E68197:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 23:59:54.497 2025-06-12 23:59:54.509397 :A3E68198:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14240 pso_num=29 pso_serial#=127 2025-06-12 23:59:54.509538 :A3E68199:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14240 (legacy spawn) 2025-06-12 23:59:54.523350 :A3E6819A: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-06-12 23:59:57.799099 :A3E6828B: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-06-12 23:59:57.799261 :A3E68290:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-12 23:59:57.800463 :A3E68295:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-12 23:59:58.636880 :A3E682CF:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-12 23:59:58.627 2025-06-12 23:59:58.636882 :A3E682D0:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13572 pso_num=29 pso_serial#=128 2025-06-12 23:59:58.637022 :A3E682D1:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13572 (legacy spawn) 2025-06-12 23:59:58.651381 :A3E682D2: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-06-13 00:06:18.620837 :A3E708E6: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-06-13 00:06:18.621005 :A3E708EB:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 00:06:18.622354 :A3E708F0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 01:00:03.500579 :A3EB2096:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 01:00:03.498 2025-06-13 01:00:03.500581 :A3EB2097:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15680 pso_num=29 pso_serial#=129 2025-06-13 01:00:03.500716 :A3EB2098:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15680 (legacy spawn) 2025-06-13 01:00:03.507106 :A3EB2099: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-06-13 01:00:03.507266 :A3EB20A2:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 01:00:03.508074 :A3EB20A3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 01:00:03.517202 :A3EB20A6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 01:00:03.513 2025-06-13 01:00:03.517204 :A3EB20A7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17100 pso_num=29 pso_serial#=130 2025-06-13 01:00:03.517350 :A3EB20A8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17100 (legacy spawn) 2025-06-13 01:00:03.523848 :A3EB20A9: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-06-13 01:00:03.524016 :A3EB20B2:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 01:00:03.524839 :A3EB20B3:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 01:00:03.849811 :A3EB20E3:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 01:00:03.841 2025-06-13 01:00:03.849813 :A3EB20E4:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14696 pso_num=29 pso_serial#=131 2025-06-13 01:00:03.849949 :A3EB20E5:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14696 (legacy spawn) 2025-06-13 01:00:03.856485 :A3EB20E6: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-06-13 01:00:03.856679 :A3EB20EF:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 01:00:03.857549 :A3EB20F0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 01:00:06.211277 :A3EB215B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 01:00:06.203 2025-06-13 01:00:06.211279 :A3EB215C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16404 pso_num=29 pso_serial#=132 2025-06-13 01:00:06.211416 :A3EB215D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16404 (legacy spawn) 2025-06-13 01:00:06.236259 :A3EB2181: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-06-13 01:00:06.236439 :A3EB218A:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 01:00:06.237336 :A3EB218B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 01:00:06.576158 :A3EB21B7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 01:00:06.563 2025-06-13 01:00:06.576160 :A3EB21B8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16268 pso_num=29 pso_serial#=133 2025-06-13 01:00:06.576298 :A3EB21B9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16268 (legacy spawn) 2025-06-13 01:00:06.582707 :A3EB21BA: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-06-13 01:00:06.582872 :A3EB21C0:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 01:00:06.583637 :A3EB21C4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 01:00:06.592179 :A3EB21C7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 01:00:06.578 2025-06-13 01:00:06.592181 :A3EB21C8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16560 pso_num=29 pso_serial#=134 2025-06-13 01:00:06.592310 :A3EB21C9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16560 (legacy spawn) 2025-06-13 01:00:06.598805 :A3EB21F5: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-06-13 01:00:06.598973 :A3EB21FE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-06-13 01:00:06.599801 :A3EB21FF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-13 22:00:00.426851 :A44BDE9C:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-13 22:00:00.420 2025-06-13 22:00:00.426852 :A44BDE9D:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16132 pso_num=29 pso_serial#=135 2025-06-13 22:00:00.426978 :A44BDEA3:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16132 osp_idx=27 osp_ver=12629 osp_pg=0 (spawn #282474) 2025-06-13 22:00:00.426978 :A44BDEA4:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:00:00.426979 :A44BDEA5:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=178 time=2827743610 2025-06-13 22:00:00.426980 :A44BDEA6:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:00:00.426980 :A44BDEA7:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:00:00.426981 :A44BDEA8:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:00:00.426981 :A44BDEA9:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:00:00.426983 :A44BDEAA:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:00:00.427510 :A44BDEB3: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-06-13 22:00:00.432627 :A44BDECD: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-06-13 22:00:00.432652 :A44BDECE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-13 22:00:00.450215 :A44BE0B8: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-06-13 22:00:00.450428 :A44BE0C0: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-06-13 22:00:00.450615 :A44BE0C8: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-06-13 22:00:00.450900 :A44BE0D0: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-06-13 22:00:00.451075 :A44BE0D8: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-06-13 22:00:00.451243 :A44BE0E0: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-06-13 22:00:00.451420 :A44BE0E8: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-06-13 22:00:00.451586 :A44BE0F0: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-06-13 22:00:00.451745 :A44BE0F8: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-06-13 22:00:00.451915 :A44BE100: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-06-13 22:00:00.452068 :A44BE108: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-06-13 22:00:00.452226 :A44BE110: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-06-13 22:00:00.452381 :A44BE118: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-06-13 22:00:00.452534 :A44BE120: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-06-13 22:00:00.452708 :A44BE128: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-06-13 22:00:00.452874 :A44BE130: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-06-13 22:00:00.453024 :A44BE138: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-06-13 22:00:00.453188 :A44BE140: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-06-13 22:00:00.453345 :A44BE148: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-06-13 22:00:00.453501 :A44BE150: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-06-13 22:00:00.453651 :A44BE158: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-06-13 22:00:00.453804 :A44BE160: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-06-13 22:00:00.453956 :A44BE168: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-06-13 22:00:00.454111 :A44BE170: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-06-13 22:00:00.454744 :A44BE178: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-06-13 22:00:00.454920 :A44BE180: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-06-13 22:00:00.455120 :A44BE188: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-06-13 22:00:00.482179 :A44BE190: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-06-13 22:00:01.804931*:A44BE477:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031FB3AD0 estart=1749823201 eid=19340174 ctx=0x0000000777968158 cwh=0x00000000449D0158 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-13 22:00:01.804931*:A44BE478:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031FB3AD0 curCtx=0x00000000448D1BF8 pubTabIdxCnt=0 2025-06-13 22:00:01.804931*:A44BE479:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031FB3AD0 2025-06-13 22:00:01.804931*:A44BE47A:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031FB3AD0 2025-06-13 22:00:01.804931*:A44BE47B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003202B6F0 estart=1749823199 eid=16777385 ctx=0x000000077B54EAC8 cwh=0x0000000032020068 cpu=1156250 buffg=63143 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-13 22:00:01.804931*:A44BE47C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003202B6F0 curCtx=0x00000000448C7FD8 pubTabIdxCnt=0 2025-06-13 22:00:01.804931*:A44BE47D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003202B6F0 2025-06-13 22:00:01.804931*:A44BE47E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003202B6F0 2025-06-13 22:00:01.804931*:A44BE480:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031FB3AD0 curCtx=0x00000000448D1BF8 xsc->flg4=65792 flags=1 2025-06-13 22:00:01.804931*:A44BE481:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031FB3AD0 2025-06-13 22:00:02.119035 :A44BE4C0: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-06-13 22:00:02.136863 :A44BE4DA:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 7, ext 1, nex 3 2025-06-13 22:00:02.136870 :A44BE4DB:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x0081b059 where: kdiwh22: kdifind 2025-06-13 22:00:02.136870*:A44BE4DC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003202B6F0 curCtx=0x00000000448C7FD8 xsc->flg4=65792 flags=2 2025-06-13 22:00:02.136870*:A44BE4DD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003202B6F0 2025-06-13 22:00:02.139580 :A44BE4DE: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-06-13 22:00:02.159781 :A44BE517: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-06-13 22:00:02.161650 :A44BE51F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-13 22:00:02.161854 :A44BE520:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-13 22:00:02.162777 :A44BE521: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-06-13 22:00:02.162868 :A44BE524: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-06-13 22:00:02.163681 :A44BE536: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-06-13 22:00:02.165153 :A44BE53E: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-06-13 22:00:02.165541 :A44BE546: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-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0 2025-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-13 22:00:02.226816*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-13 22:00:02.303819 :A44BE5D2: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-06-13 22:00:04.193265 :A44BE6FF: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-06-13 22:00:04.193276 :A44BE700:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0007.016.0000c4a9 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 16132, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de