Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250820220004\orcl_j003_21668_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250820220004\orcl_j003_21668_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:35341M/63366M, Ph+PgF:40562M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 29 Windows thread id: 21668, image: ORACLE.EXE (J003) *** 2025-08-20 22:00:04.550 *** SESSION ID:(233.32501) 2025-08-20 22:00:04.550 *** 2025-08-20 22:00:04.550 Process diagnostic dump for ORACLE.EXE (J003), OS id=21668, pid: 29, proc_ser: 119, sid: 233, sess_ser: 32501 ------------------------------------------------------------------------------- current sql: call dbms_stats.gather_database_stats_job_proc ( ) client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21668 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J003) application name: DBMS_SCHEDULER, hash value=2478762354 action name: ORA$AT_OS_OPT_SY_12948, hash value=798007514 Current Wait Stack: Not in wait; last wait ended 2.363979 sec ago Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 2.364005 sec since last wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0xc9, filetype=0x2 wait_id=39 seq_num=40 snap_id=1 wait times: snap=0.000133 sec, exc=0.000133 sec, total=0.000133 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.066880 sec of elapsed time 1: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=38 seq_num=39 snap_id=1 wait times: snap=0.000008 sec, exc=0.000008 sec, total=0.000008 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.758932 sec of elapsed time 2: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.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.025347 sec of elapsed time 3: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=36 seq_num=37 snap_id=1 wait times: snap=0.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.012946 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x78fa, blocks=0x1 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.000085 sec, exc=0.000085 sec, total=0.000085 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.049663 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1e5a, blocks=0x1 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.009072 sec, exc=0.009072 sec, total=0.009072 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.008012 sec of elapsed time 6: waited for 'db file sequential read' file#=0x1, block#=0x2d00, blocks=0x1 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.000067 sec, exc=0.000067 sec, total=0.000067 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.262487 sec of elapsed time 7: waited for 'db file sequential read' file#=0x1, block#=0x130, blocks=0x1 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.006673 sec, exc=0.006673 sec, total=0.006673 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000173 sec of elapsed time 8: waited for 'db file sequential read' file#=0x1, block#=0xc8, blocks=0x1 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.000115 sec, exc=0.000115 sec, total=0.000115 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.007873 sec of elapsed time 9: waited for 'asynch descriptor resize' outstanding #aio=0x0, current aio limit=0xffffffff, new aio limit=0x82 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.000001 sec, exc=0.000001 sec, total=0.000001 sec wait times: max=307445734561 min 49 sec wait counts: calls=0 os=0 occurred after 0.000063 sec of elapsed time Sampled Session History of session 233 serial 32501 --------------------------------------------------- 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:00 - 22:00:04] not in wait at each sample [1 sample, 21:59:59] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-20 22:00:04.550 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 29 (osid: 21668, J003) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-20 00:04:20.530672 :C2063A79: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-08-20 00:04:20.531529 :C2063A7E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:05:16.914377 :C2064DB0:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:05:16.906 2025-08-20 00:05:16.914379 :C2064DB1:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21940 pso_num=29 pso_serial#=105 2025-08-20 00:05:16.914570 :C2064DB2:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21940 (legacy spawn) 2025-08-20 00:05:16.921041 :C2064DB3: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-20 00:05:16.921202 :C2064DBC: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-08-20 00:05:16.922040 :C2064DBD:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:05:22.753158 :C2064F7B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:05:22.745 2025-08-20 00:05:22.753161 :C2064F7C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20236 pso_num=29 pso_serial#=106 2025-08-20 00:05:22.753295 :C2064F7D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20236 (legacy spawn) 2025-08-20 00:05:22.759787 :C2064F7E: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-20 00:05:22.759965 :C2064F83: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-08-20 00:05:22.760853 :C2064F88:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:05:26.776480 :C2065098:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:05:26.762 2025-08-20 00:05:26.776482 :C2065099:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21676 pso_num=29 pso_serial#=107 2025-08-20 00:05:26.776646 :C206509A:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21676 (legacy spawn) 2025-08-20 00:05:26.794431 :C206509B: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-20 00:05:26.794463 :C206509E: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-20 00:05:26.794621 :C20650A1: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-08-20 00:05:26.795571 :C20650B2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:05:26.804918 :C20650B5:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:05:26.793 2025-08-20 00:05:26.804920 :C20650B6:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22020 pso_num=29 pso_serial#=108 2025-08-20 00:05:26.805064 :C20650B7:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22020 (legacy spawn) 2025-08-20 00:05:26.812212 :C20650B8: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-20 00:05:26.812425 :C20650BF: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-08-20 00:05:26.813323 :C20650C2:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:05:29.871108 :C20651B2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:05:29.857 2025-08-20 00:05:29.871111 :C20651B3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22220 pso_num=29 pso_serial#=109 2025-08-20 00:05:29.871273 :C20651B4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22220 (legacy spawn) 2025-08-20 00:05:29.877715 :C20651B5: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-20 00:05:29.877902 :C20651BA: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-08-20 00:05:29.878690 :C20651BF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:05:29.887379 :C20651C2:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:05:29.873 2025-08-20 00:05:29.887381 :C20651C3:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22404 pso_num=29 pso_serial#=110 2025-08-20 00:05:29.887517 :C20651C4:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22404 (legacy spawn) 2025-08-20 00:05:29.894004 :C20651C5: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-20 00:05:29.894167 :C20651CE: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-08-20 00:05:29.894994 :C20651CF:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:06:06.482886 :C2066337:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:06:06.470 2025-08-20 00:06:06.482888 :C2066338:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=10444 pso_num=29 pso_serial#=111 2025-08-20 00:06:06.483022 :C2066339:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=10444 (legacy spawn) 2025-08-20 00:06:06.489714 :C206633A: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-20 00:06:06.489886 :C206633F: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-08-20 00:06:06.490700 :C2066344:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 00:06:06.499447 :C2066347:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 00:06:06.486 2025-08-20 00:06:06.499449 :C2066348:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4148 pso_num=29 pso_serial#=112 2025-08-20 00:06:06.499594 :C2066349:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=4148 (legacy spawn) 2025-08-20 00:06:06.506113 :C206634A: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-20 00:06:06.506144 :C206634D: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-20 00:06:06.506306 :C2066354: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-08-20 00:06:06.507097 :C2066361:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 01:00:20.187784 :C20A8D7B:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 01:00:20.179 2025-08-20 01:00:20.187786 :C20A8D7C:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=14008 pso_num=29 pso_serial#=113 2025-08-20 01:00:20.187923 :C20A8D7D:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=14008 (legacy spawn) 2025-08-20 01:00:20.194487 :C20A8D7E: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-20 01:00:20.194665 :C20A8D83: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-08-20 01:00:20.195494 :C20A8D88:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 01:00:27.024225 :C20A8FA1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 01:00:27.014 2025-08-20 01:00:27.024227 :C20A8FA2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21668 pso_num=29 pso_serial#=114 2025-08-20 01:00:27.024408 :C20A8FA3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21668 (legacy spawn) 2025-08-20 01:00:27.031830 :C20A8FA4: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-20 01:00:27.032041 :C20A8FAD: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-08-20 01:00:27.032957 :C20A8FAE:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 01:00:45.447255 :C20A963F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 01:00:45.433 2025-08-20 01:00:45.447257 :C20A9640:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=16340 pso_num=29 pso_serial#=115 2025-08-20 01:00:45.447387 :C20A9641:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=16340 osp_idx=27 osp_ver=16179 osp_pg=0 (spawn #506243) 2025-08-20 01:00:45.447388 :C20A9642: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-20 01:00:45.447389 :C20A9643:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=49 time=37347455 2025-08-20 01:00:45.447389 :C20A9644:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 01:00:45.447389 :C20A9645:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 01:00:45.447390 :C20A9646:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 01:00:45.447390 :C20A9647:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 01:00:45.447391 :C20A9648:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 01:00:45.447905 :C20A9649: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-20 01:00:45.447948 :C20A964F: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-20 01:00:45.455383 :C20A965D: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-20 01:00:45.456461 :C20A9665: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-20 01:00:45.456938 :C20A966D: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-20 01:00:45.457373 :C20A9675: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-20 01:00:45.457834 :C20A967D: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-20 01:00:45.460411 :C20A9685: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-20 01:00:45.461537 :C20A968D: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-20 01:00:45.514208 :C20A96A0: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-20 01:00:45.515133 :C20A96A8: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-20 01:00:45.515391 :C20A96B0: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-20 01:00:45.515612 :C20A96B8: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-20 01:00:45.517421 :C20A96C0:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 08:08:59.640353 :C22C2017:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 08:08:59.636 2025-08-20 08:08:59.640355 :C22C2018:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=7280 pso_num=29 pso_serial#=116 2025-08-20 08:08:59.640963 :C22C2019:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=7280 osp_idx=27 osp_ver=16180 osp_pg=0 (spawn #507234) 2025-08-20 08:08:59.640964 :C22C201A: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-20 08:08:59.640965 :C22C201B:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=50 time=63041643 2025-08-20 08:08:59.640965 :C22C201C:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 08:08:59.640965 :C22C201D:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 08:08:59.640966 :C22C201E:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 08:08:59.640966 :C22C201F:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 08:08:59.640966 :C22C2020:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 08:08:59.646303 :C22C2021: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-20 08:08:59.646354 :C22C2027: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-20 08:08:59.646815 :C22C2028:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 08:09:01.855734 :C22C217D:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 08:09:01.850 2025-08-20 08:09:01.855736 :C22C217E:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=4644 pso_num=29 pso_serial#=117 2025-08-20 08:09:01.855867 :C22C217F:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=4644 osp_idx=27 osp_ver=16181 osp_pg=0 (spawn #507236) 2025-08-20 08:09:01.855867 :C22C2180: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-20 08:09:01.855868 :C22C2181:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=51 time=63043862 2025-08-20 08:09:01.855868 :C22C2182:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 08:09:01.855869 :C22C2183:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 08:09:01.855869 :C22C2184:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 08:09:01.855869 :C22C2185:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 08:09:01.855870 :C22C2186:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 08:09:01.856433 :C22C2187: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-20 08:09:01.856476 :C22C218D: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-20 08:09:01.856878 :C22C218E:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 17:01:51.596229 :C255ABC1:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 17:01:51.588 2025-08-20 17:01:51.596231 :C255ABC2:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20504 pso_num=29 pso_serial#=118 2025-08-20 17:01:51.596346 :C255ABC3:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20504 (legacy spawn) 2025-08-20 17:01:51.602842 :C255ABF5: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-20 17:01:51.619995 :C255ABFD: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-08-20 17:01:51.620954 :C255AC02:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted 2025-08-20 22:00:00.387374 :C26CBF42:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-20 22:00:00.380 2025-08-20 22:00:00.387375 :C26CBF43:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21668 pso_num=29 pso_serial#=119 2025-08-20 22:00:00.387500 :C26CBF47:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21668 osp_idx=27 osp_ver=16183 osp_pg=0 (spawn #509157) 2025-08-20 22:00:00.387501 :C26CBF48: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-20 22:00:00.387501 :C26CBF49:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=52 time=112902393 2025-08-20 22:00:00.387502 :C26CBF4A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 22:00:00.387502 :C26CBF4B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 22:00:00.387503 :C26CBF4C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 22:00:00.387503 :C26CBF4D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 22:00:00.387504 :C26CBF4E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 22:00:00.388037 :C26CBF55: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-20 22:00:00.393093 :C26CBF73: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-20 22:00:00.393132 :C26CBF76:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203 2025-08-20 22:00:00.410894 :C26CC14D: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-20 22:00:00.411118 :C26CC155: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-20 22:00:00.411293 :C26CC15D: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-20 22:00:00.411455 :C26CC165: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-20 22:00:00.411840 :C26CC16D: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-20 22:00:00.412024 :C26CC175: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-20 22:00:00.412193 :C26CC17D: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-20 22:00:00.412458 :C26CC185: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-20 22:00:00.412706 :C26CC18D: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-20 22:00:00.412940 :C26CC195: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-20 22:00:00.413601 :C26CC19D: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-20 22:00:00.414037 :C26CC1A5: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-20 22:00:00.414206 :C26CC1AD: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-20 22:00:00.414388 :C26CC1B5: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-20 22:00:00.414552 :C26CC1BD: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-20 22:00:00.414712 :C26CC1C5: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-20 22:00:00.414869 :C26CC1CD: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-20 22:00:00.415063 :C26CC1D5: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-20 22:00:00.441018 :C26CC1DD: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-20 22:00:02.003550*:C26CC506:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000325D3AD0 estart=1755698402 eid=21580078 ctx=0x0000000777968158 cwh=0x000000002DC20AB8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-08-20 22:00:02.003550*:C26CC507:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000325D3AD0 curCtx=0x000000005917C4B8 pubTabIdxCnt=0 2025-08-20 22:00:02.003550*:C26CC508:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000325D3AD0 2025-08-20 22:00:02.003550*:C26CC509:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000325D3AD0 2025-08-20 22:00:02.003550*:C26CC50A:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003261AAF8 estart=1755698400 eid=16777533 ctx=0x000000077B54EAC8 cwh=0x000000002DC20608 cpu=1390625 buffg=74744 interb=196608 r=24 rb=196608 w=0 wb=0 2025-08-20 22:00:02.003550*:C26CC50B:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003261AAF8 curCtx=0x0000000058F91240 pubTabIdxCnt=0 2025-08-20 22:00:02.003550*:C26CC50C:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003261AAF8 2025-08-20 22:00:02.003550*:C26CC50D:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003261AAF8 2025-08-20 22:00:02.003550*:C26CC50E:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000325D3AD0 curCtx=0x000000005917C4B8 xsc->flg4=65792 flags=1 2025-08-20 22:00:02.003550*:C26CC50F:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000325D3AD0 2025-08-20 22:00:02.101100 :C26CC543: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-20 22:00:02.101100*:C26CC54A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003261AAF8 curCtx=0x0000000058F91240 xsc->flg4=65792 flags=2 2025-08-20 22:00:02.101100*:C26CC54B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003261AAF8 2025-08-20 22:00:02.105927 :C26CC54C: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-20 22:00:02.128621 :C26CC57C: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-20 22:00:02.130939 :C26CC591:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235 2025-08-20 22:00:02.131170 :C26CC592:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235 2025-08-20 22:00:02.132177 :C26CC596: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-20 22:00:02.132308 :C26CC599: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-20 22:00:02.133365 :C26CC5AD: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-20 22:00:02.135180 :C26CC5B7: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-20 22:00:02.135502 :C26CC5BF: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-20 22:00:02.191050*: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-20 22:00:02.191050*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze 2025-08-20 22:00:02.191050*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000 2025-08-20 22:00:02.237926*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0 2025-08-20 22:00:02.237926*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin): qksanPostAnalyzeDump(): Dumping analyze context after compilation qksanStatus=SUCCESS ecode=0 2025-08-20 22:00:02.237926*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-20 22:00:02.237926*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0 2025-08-20 22:00:02.297955 :C26CC649: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-20 22:00:04.251106 :C26CC7FD: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-20 22:00:04.251118 :C26CC7FE:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0009.004.0001da0e ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 29 (osid: 21668, J003)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de