Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250605220006\orcl_j003_12424_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250605220006\orcl_j003_12424_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:36869M/63366M, Ph+PgF:43521M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 12424, image: ORACLE.EXE (J003) *** 2025-06-05 22:00:06.251 *** SESSION ID:(233.24313) 2025-06-05 22:00:06.251 *** 2025-06-05 22:00:06.251 Process diagnostic dump for ORACLE.EXE (J003), OS id=12424, pid: 29, proc_ser: 140, sid: 233, sess_ser: 24313 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12424 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12544, hash value=2936390334 Current Wait Stack: Not in wait; last wait ended 2.402226 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.402251 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.000156 sec, exc=0.000156 sec, total=0.000156 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.062878 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.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.753717 sec of elapsed time 2: 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.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.026855 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.000004 sec, exc=0.000004 sec, total=0.000004 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.012943 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.000101 sec, exc=0.000101 sec, total=0.000101 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049457 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.004543 sec, exc=0.004543 sec, total=0.004543 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008023 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.005412 sec, exc=0.005412 sec, total=0.005412 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262781 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.004819 sec, exc=0.004819 sec, total=0.004819 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000228 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.000121 sec, exc=0.000121 sec, total=0.000121 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007943 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=31 seq_num=32 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.000059 sec of elapsed time Sampled Session History of session 233 serial 24313 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00:02 - 22:00:06] not in wait at each sample [1 sample, 22:00:01] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-05 22:00:06.251 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 12424, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-04 23:59:01.442656 :A065EB99:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13924 pso_num=29 pso_serial#=126 2025-06-04 23:59:01.442795 :A065EB9A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13924 (legacy spawn) 2025-06-04 23:59:01.457962 :A065EB9B: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-04 23:59:04.920304 :A065ECA2: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-04 23:59:04.920495 :A065ECA7: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-04 23:59:04.921757 :A065ECAC:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 23:59:05.164614 :A065ECB0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 23:59:05.158 2025-06-04 23:59:05.164616 :A065ECB1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16000 pso_num=29 pso_serial#=127 2025-06-04 23:59:05.164748 :A065ECB2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=16000 (legacy spawn) 2025-06-04 23:59:05.178609 :A065ECB3: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-04 23:59:38.348156 :A065F8A4:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 23:59:38.439930 :A065F8A7:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 23:59:38.439 2025-06-04 23:59:38.439931 :A065F8A8:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12084 pso_num=29 pso_serial#=128 2025-06-04 23:59:38.440073 :A065F8A9:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12084 (legacy spawn) 2025-06-04 23:59:38.453595 :A065F8AA: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-04 23:59:41.729652 :A065FA27: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-04 23:59:41.729811 :A065FA30: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-04 23:59:41.731017 :A065FA31:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-04 23:59:41.982473 :A065FA34:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-04 23:59:41.977 2025-06-04 23:59:41.982475 :A065FA35:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=8316 pso_num=29 pso_serial#=129 2025-06-04 23:59:41.982613 :A065FA36:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=8316 (legacy spawn) 2025-06-04 23:59:41.996718 :A065FA37: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-05 00:00:15.154920 :A0660560:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 01:00:04.073978 :A06AAD60:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 01:00:04.065 2025-06-05 01:00:04.073980 :A06AAD61:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=13812 pso_num=29 pso_serial#=130 2025-06-05 01:00:04.074122 :A06AAD62:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=13812 (legacy spawn) 2025-06-05 01:00:04.080732 :A06AAD63: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-05 01:00:04.080892 :A06AAD6C: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-05 01:00:04.081721 :A06AAD6D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 01:00:04.084061 :A06AAD70:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 01:00:04.080 2025-06-05 01:00:04.084063 :A06AAD71:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14672 pso_num=29 pso_serial#=131 2025-06-05 01:00:04.084198 :A06AAD72:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14672 (legacy spawn) 2025-06-05 01:00:04.090620 :A06AAD73: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-05 01:00:04.090786 :A06AAD7C: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-05 01:00:04.091581 :A06AAD7D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 01:00:04.100313 :A06AAD80:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 01:00:04.096 2025-06-05 01:00:04.100315 :A06AAD81:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15696 pso_num=29 pso_serial#=132 2025-06-05 01:00:04.100456 :A06AAD82:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15696 (legacy spawn) 2025-06-05 01:00:04.106878 :A06AAD83: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-05 01:00:04.107040 :A06AAD8C: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-05 01:00:04.107825 :A06AAD8D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 01:00:07.142777 :A06AAE81:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 01:00:07.129 2025-06-05 01:00:07.142779 :A06AAE82:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12024 pso_num=29 pso_serial#=133 2025-06-05 01:00:07.142913 :A06AAE83:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=12024 (legacy spawn) 2025-06-05 01:00:07.149355 :A06AAE84: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-05 01:00:07.149524 :A06AAE8D: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-05 01:00:07.150305 :A06AAE8E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 01:00:07.158880 :A06AAE91:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 01:00:07.145 2025-06-05 01:00:07.158883 :A06AAE92:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11796 pso_num=29 pso_serial#=134 2025-06-05 01:00:07.159021 :A06AAE93:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11796 (legacy spawn) 2025-06-05 01:00:07.180734 :A06AAE94: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-05 01:00:07.180893 :A06AAE9D: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-05 01:00:07.181673 :A06AAE9E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 01:00:29.589202 :A06AB5A8:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 01:00:29.580 2025-06-05 01:00:29.589204 :A06AB5A9:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15452 pso_num=29 pso_serial#=135 2025-06-05 01:00:29.589344 :A06AB5AA:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=15452 (legacy spawn) 2025-06-05 01:00:29.595848 :A06AB5AB: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-05 01:00:29.596014 :A06AB5B0: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-05 01:00:29.596792 :A06AB5B5:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 08:20:43.006639 :A08D7D23:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 08:20:42.993 2025-06-05 08:20:43.006642 :A08D7D24:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=15256 pso_num=29 pso_serial#=136 2025-06-05 08:20:43.007928 :A08D7D25:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=15256 osp_idx=27 osp_ver=12120 osp_pg=0 (spawn #253924) 2025-06-05 08:20:43.007930 :A08D7D26: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-05 08:20:43.007930 :A08D7D27:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=8 time=2087401625 2025-06-05 08:20:43.007930 :A08D7D28:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:20:43.007931 :A08D7D29:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:20:43.007931 :A08D7D2A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:20:43.007931 :A08D7D2B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:20:43.007932 :A08D7D2C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:20:43.011947 :A08D7D2D: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-05 08:20:43.012001 :A08D7D33: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-05 08:20:43.012440 :A08D7D34:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 08:20:45.278579 :A08D7E7F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 08:20:45.269 2025-06-05 08:20:45.278581 :A08D7E80:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16360 pso_num=29 pso_serial#=137 2025-06-05 08:20:45.278708 :A08D7E81:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16360 osp_idx=27 osp_ver=12121 osp_pg=0 (spawn #253926) 2025-06-05 08:20:45.278709 :A08D7E82: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-05 08:20:45.278709 :A08D7E83:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=9 time=2087403922 2025-06-05 08:20:45.278710 :A08D7E84:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 08:20:45.278710 :A08D7E85:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 08:20:45.278711 :A08D7E86:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 08:20:45.278711 :A08D7E87:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 08:20:45.278711 :A08D7E88:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 08:20:45.279286 :A08D7E89: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-05 08:20:45.279331 :A08D7E8F: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-05 08:20:45.279690 :A08D7E90:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 20:00:00.785768 :A0C46D09:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 20:00:00.783 2025-06-05 20:00:00.785769 :A0C46D0A:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=1112 pso_num=29 pso_serial#=138 2025-06-05 20:00:00.785911 :A0C46D0B:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=1112 osp_idx=27 osp_ver=12122 osp_pg=0 (spawn #255533) 2025-06-05 20:00:00.785911 :A0C46D0C: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-05 20:00:00.785912 :A0C46D0D:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=10 time=2129359422 2025-06-05 20:00:00.785912 :A0C46D0E:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 20:00:00.785913 :A0C46D0F:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 20:00:00.785913 :A0C46D10:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 20:00:00.785914 :A0C46D11:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 20:00:00.785914 :A0C46D12:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 20:00:00.786450 :A0C46D13: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-05 20:00:00.786503 :A0C46D19: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-05 20:00:00.794735 :A0C46D3B:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 21:00:07.635669 :A0C91E6A:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 21:00:07.633 2025-06-05 21:00:07.635671 :A0C91E6B:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10448 pso_num=29 pso_serial#=139 2025-06-05 21:00:07.635804 :A0C91E6C:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=10448 osp_idx=27 osp_ver=12123 osp_pg=0 (spawn #255672) 2025-06-05 21:00:07.635805 :A0C91E6D: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-05 21:00:07.635805 :A0C91E6E:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=11 time=2132966266 2025-06-05 21:00:07.635805 :A0C91E6F:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 21:00:07.635806 :A0C91E70:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 21:00:07.635806 :A0C91E71:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 21:00:07.635807 :A0C91E72:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 21:00:07.635807 :A0C91E73:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 21:00:07.636337 :A0C91E74: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-05 21:00:07.636382 :A0C91E7A: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-05 21:00:07.644517 :A0C91E9C:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-06-05 22:00:02.054123 :A0CDB455:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 06-05 22:00:02.043 2025-06-05 22:00:02.054125 :A0CDB456:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=12424 pso_num=29 pso_serial#=140 2025-06-05 22:00:02.054259 :A0CDB457:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=12424 osp_idx=27 osp_ver=12124 osp_pg=0 (spawn #255809) 2025-06-05 22:00:02.054260 :A0CDB458: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-05 22:00:02.054261 :A0CDB459:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=12 time=2136560688 2025-06-05 22:00:02.054262 :A0CDB45A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-06-05 22:00:02.054264 :A0CDB45B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-06-05 22:00:02.054264 :A0CDB45C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-06-05 22:00:02.054264 :A0CDB45D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-05 22:00:02.054265 :A0CDB45E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-05 22:00:02.054854 :A0CDB468: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-05 22:00:02.059748 :A0CDB47F: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-05 22:00:02.059789 :A0CDB482:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-06-05 22:00:02.077203 :A0CDB66D: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-05 22:00:02.077411 :A0CDB675: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-05 22:00:02.077587 :A0CDB67D: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-05 22:00:02.077752 :A0CDB685: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-05 22:00:02.077914 :A0CDB68D: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-05 22:00:02.078071 :A0CDB695: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-05 22:00:02.078238 :A0CDB69D: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-05 22:00:02.078389 :A0CDB6A5: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-05 22:00:02.078539 :A0CDB6AD: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-05 22:00:02.078692 :A0CDB6B5: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-05 22:00:02.078843 :A0CDB6BD: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-05 22:00:02.078990 :A0CDB6C5: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-05 22:00:02.079169 :A0CDB6CD: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-05 22:00:02.079329 :A0CDB6D5: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-05 22:00:02.079475 :A0CDB6DD: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-05 22:00:02.079628 :A0CDB6E5: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-05 22:00:02.079971 :A0CDB6ED: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-05 22:00:02.080142 :A0CDB6F5: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-05 22:00:02.080298 :A0CDB6FD: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-05 22:00:02.080628 :A0CDB705: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-05 22:00:02.080887 :A0CDB70D: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-05 22:00:02.081143 :A0CDB715: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-05 22:00:02.081785 :A0CDB71D: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-05 22:00:02.108103 :A0CDB725: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-05 22:00:03.271666*:A0CDB921:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DC93AD0 estart=1749132003 eid=19096620 ctx=0x0000000777968158 cwh=0x0000000029A90AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-06-05 22:00:03.271666*:A0CDB922:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DC93AD0 curCtx=0x000000004285C4B8 pubTabIdxCnt=0 2025-06-05 22:00:03.271666*:A0CDB923:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DC93AD0 2025-06-05 22:00:03.271666*:A0CDB924:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DC93AD0 2025-06-05 22:00:03.271666*:A0CDB925:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002DCCAAF8 estart=1749132002 eid=16777369 ctx=0x000000077B54EAC8 cwh=0x0000000029A90608 cpu=984375 buffg=55142 interb=196608 r=24 rb=196608 w=0 wb=0 2025-06-05 22:00:03.271666*:A0CDB926:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002DCCAAF8 curCtx=0x0000000042671240 pubTabIdxCnt=0 2025-06-05 22:00:03.271666*:A0CDB927:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002DCCAAF8 2025-06-05 22:00:03.271666*:A0CDB928:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002DCCAAF8 2025-06-05 22:00:03.271666*:A0CDB929:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DC93AD0 curCtx=0x000000004285C4B8 xsc->flg4=65792 flags=1 2025-06-05 22:00:03.271666*:A0CDB92A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DC93AD0 2025-06-05 22:00:03.757723 :A0CDB9AD:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 1, nex 2 2025-06-05 22:00:03.757730 :A0CDB9AE:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x008102a7 where: kdiwh22: kdifind 2025-06-05 22:00:03.772129 :A0CDB9BF:db_trace:ktu.c@4871:ktuaex1r(): [10442:29:233] Extension usn 5, ext 2, nex 3 2025-06-05 22:00:03.772137 :A0CDB9C0:db_trace:ktusm.c@2117:ktusmasp(): [10445:29:233] des1:tsn:1 rdba:0x00818fe7 where: kduwh01: kdusru 2025-06-05 22:00:03.772137*:A0CDB9C1:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002DCCAAF8 curCtx=0x0000000042671240 xsc->flg4=65792 flags=2 2025-06-05 22:00:03.772137*:A0CDB9C2:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002DCCAAF8 2025-06-05 22:00:03.773685 :A0CDB9C3: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-05 22:00:03.794000 :A0CDB9FC: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-05 22:00:03.795915 :A0CDBA04:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-06-05 22:00:03.796101 :A0CDBA05:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-06-05 22:00:03.796970 :A0CDBA06: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-05 22:00:03.797053 :A0CDBA09: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-05 22:00:03.797888 :A0CDBA1B: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-05 22:00:03.799387 :A0CDBA23: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-05 22:00:03.799663 :A0CDBA2B: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-05 22:00:03.849831*: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-05 22:00:03.849831*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-06-05 22:00:03.849831*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-06-05 22:00:03.865429*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-06-05 22:00:03.865429*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-06-05 22:00:03.865429*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-06-05 22:00:03.865429*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-06-05 22:00:03.938643 :A0CDBA9B: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-05 22:00:05.805741 :A0CDBC89: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-05 22:00:05.805756 :A0CDBC8A:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.009.0001cb43 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 12424, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de