Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712100948\orcl_j001_15316_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712100948\orcl_j001_15316_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:36427M/63366M, Ph+PgF:42514M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15316, image: ORACLE.EXE (J001) *** 2025-07-12 10:09:49.034 *** SESSION ID:(209.39965) 2025-07-12 10:09:49.034 *** 2025-07-12 10:09:49.034 Process diagnostic dump for ORACLE.EXE (J001), OS id=15316, pid: 26, proc_ser: 155, sid: 209, sess_ser: 39965 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15316 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.042962 sec, exc=0.042962 sec, total=0.042962 sec wait times: max=0.500000 sec, heur=5.538350 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000013 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.502089 sec, exc=0.502089 sec, total=0.502089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.501087 sec, exc=0.501087 sec, total=0.501087 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500118 sec, exc=0.500118 sec, total=0.500118 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time Sampled Session History of session 209 serial 39965 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 10:09:43 - 10:09:49] idle wait at each sample [session created at: 10:09:43] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-12 10:09:49.034 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15316, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-12 10:00:17.612954 :B0F0F2F7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:00:17.612954 :B0F0F2F8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=35 time=995193501 2025-07-12 10:00:17.612955 :B0F0F2F9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:00:17.612955 :B0F0F2FA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:00:17.612956 :B0F0F2FB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:00:17.612956 :B0F0F2FC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:00:17.612957 :B0F0F2FD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:00:17.613452 :B0F0F2FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.613495 :B0F0F304:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 10:00:17.621159 :B0F0F312:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.622216 :B0F0F31A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.622709 :B0F0F322:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.623226 :B0F0F32A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.623660 :B0F0F332:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.626113 :B0F0F33A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.627221 :B0F0F342:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.677816 :B0F0F37E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.678694 :B0F0F386:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.678935 :B0F0F38E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.679149 :B0F0F396:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:00:17.681072 :B0F0F39E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:00:24.826368 :B0F0F5DD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:00:24.815 2025-07-12 10:00:24.826370 :B0F0F5DE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8732 pso_num=26 pso_serial#=146 2025-07-12 10:00:24.826487 :B0F0F5DF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8732 osp_idx=24 osp_ver=608066 osp_pg=0 (spawn #377498) 2025-07-12 10:00:24.826487 :B0F0F5E0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:00:24.826488 :B0F0F5E1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=36 time=995200720 2025-07-12 10:00:24.826488 :B0F0F5E2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:00:24.826488 :B0F0F5E3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:00:24.826489 :B0F0F5E4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:00:24.826489 :B0F0F5E5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:00:24.826489 :B0F0F5E6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:00:24.826982 :B0F0F5E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:00:54.896166 :B0F1009F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:01:24.905016 :B0F10A67:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:01:24.894 2025-07-12 10:01:24.905018 :B0F10A68:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3836 pso_num=26 pso_serial#=147 2025-07-12 10:01:24.905208 :B0F10A69:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=3836 osp_idx=24 osp_ver=608067 osp_pg=0 (spawn #377500) 2025-07-12 10:01:24.905209 :B0F10A6A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:01:24.905209 :B0F10A6B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=37 time=995260798 2025-07-12 10:01:24.905209 :B0F10A6C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:01:24.905210 :B0F10A6D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:01:24.905210 :B0F10A6E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:01:24.905211 :B0F10A6F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:01:24.905211 :B0F10A70:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:01:24.905704 :B0F10A71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:01:55.013936 :B0F113F3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:02:24.994703 :B0F11DA4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:02:24.983 2025-07-12 10:02:24.994705 :B0F11DA5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17508 pso_num=26 pso_serial#=148 2025-07-12 10:02:24.994835 :B0F11DA6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17508 osp_idx=24 osp_ver=608068 osp_pg=0 (spawn #377502) 2025-07-12 10:02:24.994836 :B0F11DA7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:02:24.994836 :B0F11DA8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=38 time=995320876 2025-07-12 10:02:24.994837 :B0F11DA9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:02:24.994837 :B0F11DAA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:02:24.994837 :B0F11DAB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:02:24.994838 :B0F11DAC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:02:24.994838 :B0F11DAD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:02:24.995401 :B0F11DAE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:02:55.112526 :B0F12858:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:03:25.115432 :B0F13234:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:03:25.104 2025-07-12 10:03:25.115434 :B0F13235:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17404 pso_num=26 pso_serial#=149 2025-07-12 10:03:25.115556 :B0F13236:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17404 osp_idx=24 osp_ver=608069 osp_pg=0 (spawn #377504) 2025-07-12 10:03:25.115557 :B0F13237:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:03:25.115557 :B0F13238:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=39 time=995381001 2025-07-12 10:03:25.115558 :B0F13239:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:03:25.115558 :B0F1323A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:03:25.115558 :B0F1323B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:03:25.115559 :B0F1323C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:03:25.115559 :B0F1323D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:03:25.116058 :B0F1323E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:03:55.186956 :B0F13C0F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:04:25.183885 :B0F145E1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:04:25.173 2025-07-12 10:04:25.183887 :B0F145E2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16544 pso_num=26 pso_serial#=150 2025-07-12 10:04:25.184020 :B0F145E3:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16544 osp_idx=24 osp_ver=608070 osp_pg=0 (spawn #377506) 2025-07-12 10:04:25.184021 :B0F145E4:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:04:25.184022 :B0F145E5:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=40 time=995441079 2025-07-12 10:04:25.184022 :B0F145E6:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:04:25.184023 :B0F145E7:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:04:25.184023 :B0F145E8:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:04:25.184023 :B0F145E9:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:04:25.184024 :B0F145EA:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:04:25.184565 :B0F145EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:04:55.251047 :B0F14F64:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:05:25.249198 :B0F15EB0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:05:25.236 2025-07-12 10:05:25.249200 :B0F15EB1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16196 pso_num=26 pso_serial#=151 2025-07-12 10:05:25.249324 :B0F15EB2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16196 osp_idx=24 osp_ver=608071 osp_pg=0 (spawn #377509) 2025-07-12 10:05:25.249325 :B0F15EB3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:05:25.249325 :B0F15EB4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=41 time=995501142 2025-07-12 10:05:25.249326 :B0F15EB5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:05:25.249326 :B0F15EB6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:05:25.249327 :B0F15EB7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:05:25.249327 :B0F15EB8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:05:25.249327 :B0F15EB9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:05:25.249869 :B0F15EBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:05:55.341386 :B0F16A01:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:06:25.330931 :B0F173DB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:06:25.319 2025-07-12 10:06:25.330933 :B0F173DC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15912 pso_num=26 pso_serial#=152 2025-07-12 10:06:25.331058 :B0F173DD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15912 osp_idx=24 osp_ver=608072 osp_pg=0 (spawn #377511) 2025-07-12 10:06:25.331059 :B0F173DE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:06:25.331059 :B0F173DF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=42 time=995561220 2025-07-12 10:06:25.331060 :B0F173E0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:06:25.331060 :B0F173E1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:06:25.331061 :B0F173E2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:06:25.331061 :B0F173E3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:06:25.331061 :B0F173E4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:06:25.331569 :B0F173E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:06:55.447159 :B0F17DA3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:07:25.421675 :B0F1879A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:07:25.410 2025-07-12 10:07:25.421677 :B0F1879B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4884 pso_num=26 pso_serial#=153 2025-07-12 10:07:25.421809 :B0F1879C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=4884 osp_idx=24 osp_ver=608073 osp_pg=0 (spawn #377514) 2025-07-12 10:07:25.421810 :B0F1879D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:07:25.421811 :B0F1879E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=43 time=995621314 2025-07-12 10:07:25.421811 :B0F1879F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:07:25.421811 :B0F187A0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:07:25.421812 :B0F187A1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:07:25.421812 :B0F187A2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:07:25.421813 :B0F187A3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:07:25.422334 :B0F187A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:07:50.460929 :B0F18FF7:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 10:08:20.549942 :B0F199DE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:08:25.544948 :B0F19B57:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:08:25.533 2025-07-12 10:08:25.544950 :B0F19B58:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11772 pso_num=26 pso_serial#=154 2025-07-12 10:08:25.545079 :B0F19B59:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11772 osp_idx=24 osp_ver=608074 osp_pg=0 (spawn #377516) 2025-07-12 10:08:25.545080 :B0F19B5A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:08:25.545080 :B0F19B5B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=44 time=995681439 2025-07-12 10:08:25.545081 :B0F19B5C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:08:25.545081 :B0F19B5D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:08:25.545082 :B0F19B5E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:08:25.545082 :B0F19B5F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:08:25.545082 :B0F19B60:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:08:25.545597 :B0F19B61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:08:55.634720 :B0F1A518:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-12 10:09:25.652450 :B0F1AEDF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-12 10:09:25.641 2025-07-12 10:09:25.652452 :B0F1AEE0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15316 pso_num=26 pso_serial#=155 2025-07-12 10:09:25.652567 :B0F1AEE1:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15316 osp_idx=24 osp_ver=608075 osp_pg=0 (spawn #377518) 2025-07-12 10:09:25.652568 :B0F1AEE2:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-12 10:09:25.652568 :B0F1AEE3:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=45 time=995741533 2025-07-12 10:09:25.652568 :B0F1AEE4:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 10:09:25.652569 :B0F1AEE5:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 10:09:25.652569 :B0F1AEE6:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 10:09:25.652569 :B0F1AEE7:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 10:09:25.652570 :B0F1AEE8:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 10:09:25.653043 :B0F1AEE9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-12 10:09:41.567198 :B0F1B58D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 10:09:41.671881 :B0F1B8DD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 10:09:41.671881*:B0F1B8EC:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BBC718 estart=1752286180 eid=16784029 ctx=0x000000077FD6A000 cwh=0x0000000028BB0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-12 10:09:41.671881*:B0F1B8ED:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BBC718 curCtx=0x0000000028BB8938 pubTabIdxCnt=0 2025-07-12 10:09:41.671881*:B0F1B8EE:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BBC718 2025-07-12 10:09:41.671881*:B0F1B8EF:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BBC718 2025-07-12 10:09:41.671881*:B0F1B8F0:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BBC718 curCtx=0x0000000028BB8938 xsc->flg4=65792 flags=2 2025-07-12 10:09:41.671881*:B0F1B8F1:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BBC718 2025-07-12 10:09:41.677159 :B0F1B8FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:41.677210 :B0F1B8FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:jslv.c:9203 2025-07-12 10:09:41.682247 :B0F1B90F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:41.819490 :B0F1B917:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:41.955244 :B0F1B91F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:42.086666 :B0F1B927:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.210409 :B0F1B980:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.211407 :B0F1B988:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.216510 :B0F1B990:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.230723 :B0F1B998:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.231318 :B0F1B9A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.231879 :B0F1B9A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.232378 :B0F1B9B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.235058 :B0F1B9B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.235953 :B0F1B9C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-12 10:09:43.236683 :B0F1B9C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-12 10:09:43.237428 :B0F1B9C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-12 10:09:43.237911 :B0F1B9C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-12 10:09:43.237917 :B0F1B9C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-12 10:09:43.238490 :B0F1B9C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-12 10:09:43.238994 :B0F1B9C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-12 10:09:43.239271 :B0F1B9C7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-12 10:09:43.240273 :B0F1B9C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-12 10:09:43.471225 :B0F1BA22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.471276 :B0F1BA25:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-12 10:09:43.472236 :B0F1BA2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.473152 :B0F1BA33:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-07-12 10:09:43.473623 :B0F1BA34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.498309 :B0F1BA3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.498379 :B0F1BA3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-07-12 10:09:43.499251 :B0F1BA45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.499716 :B0F1BA4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.500063 :B0F1BA55:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.500252 :B0F1BA5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.500390 :B0F1BA65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.500532 :B0F1BA6D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:jslv.c:9203 2025-07-12 10:09:43.504650 :B0F1BA6E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.504730 :B0F1BA71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-12 10:09:43.504804 :B0F1BA7E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 15316, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de