Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250428220008\orcl_j004_11188_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250428220008\orcl_j004_11188_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:37566M/63366M, Ph+PgF:45211M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 30 Windows thread id: 11188, image: ORACLE.EXE (J004) *** 2025-04-28 22:00:08.916 *** SESSION ID:(241.1141) 2025-04-28 22:00:08.916 *** 2025-04-28 22:00:08.916 Process diagnostic dump for ORACLE.EXE (J004), OS id=11188, pid: 30, proc_ser: 54, sid: 241, sess_ser: 1141 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11188 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J004) 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.108263 sec, exc=0.108263 sec, total=0.108263 sec wait times: max=0.500000 sec, heur=5.614696 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.000007 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.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.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 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.499972 sec, exc=0.499972 sec, total=0.499972 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 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.510130 sec, exc=0.510130 sec, total=0.510130 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.505083 sec, exc=0.505083 sec, total=0.505083 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 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.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.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.000010 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.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.000013 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.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 241 serial 1141 --------------------------------------------------- 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, 22:00:03 - 22:00:08] idle wait at each sample [session created at: 22:00:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-28 22:00:08.916 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 30 (osid: 11188, J004) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-27 22:15:34.873852 :8FA4C110:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 22:15:34.873856 :8FA4C111:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=10 time=3062924328 2025-04-27 22:15:34.873857 :8FA4C112:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 22:15:34.873857 :8FA4C113:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 22:15:34.873858 :8FA4C114:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 22:15:34.873858 :8FA4C115:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 22:15:34.873858 :8FA4C116:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 22:15:34.877589 :8FA4C117:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 22:15:34.877639 :8FA4C11D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-27 22:15:34.878130 :8FA4C11E:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 22:15:36.153899 :8FA4C1BF:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 22:15:36.144 2025-04-27 22:15:36.153901 :8FA4C1C0:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8928 pso_num=30 pso_serial#=46 2025-04-27 22:15:36.154029 :8FA4C1C1:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=8928 osp_idx=28 osp_ver=555 osp_pg=0 (spawn #125049) 2025-04-27 22:15:36.154030 :8FA4C1C2:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 22:15:36.154030 :8FA4C1C3:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=11 time=3062925625 2025-04-27 22:15:36.154031 :8FA4C1C4:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 22:15:36.154031 :8FA4C1C5:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 22:15:36.154031 :8FA4C1C6:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 22:15:36.154032 :8FA4C1C7:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 22:15:36.154032 :8FA4C1C8:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 22:15:36.154569 :8FA4C1C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 22:15:36.154613 :8FA4C1CF:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-27 22:15:36.155007 :8FA4C1D0:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 22:15:37.180137 :8FA4C61C:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 22:15:37.175 2025-04-27 22:15:37.180140 :8FA4C61D:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9488 pso_num=30 pso_serial#=47 2025-04-27 22:15:37.180326 :8FA4C61E:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=9488 osp_idx=28 osp_ver=556 osp_pg=0 (spawn #125050) 2025-04-27 22:15:37.180326 :8FA4C61F:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 22:15:37.180326 :8FA4C620:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=12 time=3062926656 2025-04-27 22:15:37.180327 :8FA4C621:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 22:15:37.180327 :8FA4C622:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 22:15:37.180328 :8FA4C623:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 22:15:37.180328 :8FA4C624:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 22:15:37.180329 :8FA4C625:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 22:15:37.180825 :8FA4C626:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 22:15:37.180872 :8FA4C62D:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-27 22:15:37.179054 :8FA4C644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 22:15:37.179432 :8FA4C657:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 23:00:39.702272 :8FA890D1:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 23:00:39.701 2025-04-27 23:00:39.702273 :8FA890D2:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8588 pso_num=30 pso_serial#=48 2025-04-27 23:00:39.702405 :8FA890D3:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=8588 osp_idx=28 osp_ver=557 osp_pg=0 (spawn #125155) 2025-04-27 23:00:39.702406 :8FA890D4:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-27 23:00:39.702406 :8FA890D5:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=13 time=3065629171 2025-04-27 23:00:39.702407 :8FA890D6:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-04-27 23:00:39.702407 :8FA890D7:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-04-27 23:00:39.702408 :8FA890D8:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-04-27 23:00:39.702408 :8FA890D9:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-27 23:00:39.702408 :8FA890DA:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-27 23:00:39.702918 :8FA890DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-27 23:00:39.702957 :8FA890E1:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-27 23:00:39.710902 :8FA890F3:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 23:59:14.237973 :8FAD518E:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 23:59:14.235 2025-04-27 23:59:14.237975 :8FAD518F:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=10448 pso_num=30 pso_serial#=49 2025-04-27 23:59:14.238106 :8FAD5190:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=10448 (legacy spawn) 2025-04-27 23:59:14.252170 :8FAD5191:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 23:59:17.411112 :8FAD528F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 23:59:17.411281 :8FAD5294:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-27 23:59:17.412404 :8FAD5299:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 23:59:17.661994 :8FAD52B5:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 23:59:17.655 2025-04-27 23:59:17.661996 :8FAD52B6:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8316 pso_num=30 pso_serial#=50 2025-04-27 23:59:17.662127 :8FAD52B7:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=8316 (legacy spawn) 2025-04-27 23:59:17.676230 :8FAD52B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 23:59:50.743067 :8FAD5F80:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 23:59:51.059316 :8FAD5F8F:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 23:59:51.052 2025-04-27 23:59:51.059319 :8FAD5F90:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=9344 pso_num=30 pso_serial#=51 2025-04-27 23:59:51.059450 :8FAD5F91:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=9344 (legacy spawn) 2025-04-27 23:59:51.073074 :8FAD5F92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 23:59:54.253419 :8FAD608D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-27 23:59:54.253595 :8FAD6093:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-27 23:59:54.254918 :8FAD6097:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-27 23:59:54.509836 :8FAD609A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-27 23:59:54.498 2025-04-27 23:59:54.509837 :8FAD609B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11040 pso_num=30 pso_serial#=52 2025-04-27 23:59:54.509975 :8FAD609C:db_trace:kso.c@4070:ksonfy(): [10420:30:0] kso: new process: pid=11040 (legacy spawn) 2025-04-27 23:59:54.524021 :8FAD609D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 00:00:27.571986 :8FAD6F26:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-28 00:00:47.102282 :8FAD764A:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-28 00:00:47.099 2025-04-28 00:00:47.102283 :8FAD764B:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=8824 pso_num=30 pso_serial#=53 2025-04-28 00:00:47.102412 :8FAD764C:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=8824 osp_idx=28 osp_ver=562 osp_pg=0 (spawn #125296) 2025-04-28 00:00:47.102413 :8FAD764D:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 00:00:47.102414 :8FAD764E:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=14 time=3069236578 2025-04-28 00:00:47.102414 :8FAD764F:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 00:00:47.102415 :8FAD7650:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 00:00:47.102415 :8FAD7651:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 00:00:47.102415 :8FAD7652:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 00:00:47.102416 :8FAD7653:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 00:00:47.102968 :8FAD7654:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 00:00:47.103010 :8FAD765A:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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-04-28 00:00:47.111235 :8FAD766C:db_trace:kst.c@698:kstpsodel(): [10280:30:0] kst: process state object about to be deleted 2025-04-28 22:00:01.442910 :90162AD8:db_trace:kst.c@673:kstipg(): [10280:30:0] kst: process state object created on 04-28 22:00:01.430 2025-04-28 22:00:01.442912 :90162AD9:db_trace:kst.c@679:kstipg(): [10280:30:0] kst: process info: ospid=11188 pso_num=30 pso_serial#=54 2025-04-28 22:00:01.443043 :90162ADF:db_trace:kso.c@4093:ksonfy(): [10420:30:0] kso: new process: pid=11188 osp_idx=28 osp_ver=563 osp_pg=0 (spawn #128390) 2025-04-28 22:00:01.443044 :90162AE0:db_trace:kso.c@4099:ksonfy(): [10420:30:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-28 22:00:01.443045 :90162AE1:db_trace:kso.c@4103:ksonfy(): [10420:30:0] kso: new process: pso_index=30 reservation=15 time=3148390921 2025-04-28 22:00:01.443045 :90162AE2:db_trace:kso.c@4109:ksonfy(): [10420:30:0] kso: spawn diagnostics: queue time=0 secs 2025-04-28 22:00:01.443046 :90162AE3:db_trace:kso.c@4111:ksonfy(): [10420:30:0] kso: spawn diagnostics: fork time=0 secs 2025-04-28 22:00:01.443046 :90162AE4:db_trace:kso.c@4113:ksonfy(): [10420:30:0] kso: spawn diagnostics: exec time=0 secs 2025-04-28 22:00:01.443047 :90162AE5:db_trace:kso.c@4115:ksonfy(): [10420:30:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-28 22:00:01.443047 :90162AE6:db_trace:kso.c@4117:ksonfy(): [10420:30:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-28 22:00:01.443581 :90162AE7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.448580 :90162B04:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.448621 :90162B07:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:jslv.c:9203 2025-04-28 22:00:01.454111 :90162CA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.646666 :90162DBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.825591 :90162DCA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:01.963579 :90162DEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.108124 :90163065:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.109133 :9016306D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.113108 :90163075:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.115437 :9016307D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.117770 :90163085:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.120112 :9016308D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.121576 :90163095:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.139676 :9016309D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.140285 :901630A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.140891 :901630AD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.141399 :901630B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.144019 :90163121:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.145056 :901631BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.145869 :901631BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.146647 :901631BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.147066 :901631BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.147072 :901631C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.147595 :901631C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.148081 :901631C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.148353 :901631C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.149518 :901631C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.185280 :901631CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.185336 :901631D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.186228 :901631D7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.187129 :901631DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.187552 :901631E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.188734 :901631F0:db_trace:ktu.c@4871:ktuaex1r(): [10442:30:241] Extension usn 7, ext 1, nex 3 2025-04-28 22:00:03.188742 :901631F1:db_trace:ktusm.c@2117:ktusmasp(): [10445:30:241] des1:tsn:3 rdba:0x00400c01 where: kddwh01: kdddel 2025-04-28 22:00:03.207998 :90163221:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.208050 :90163224:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.208898 :9016322A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.220289 :90163232:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.220803 :90163233:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.240944 :9016323B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.241000 :9016323E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.241833 :90163244:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.242780 :9016324C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.243227 :9016324D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.260151 :90163255:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.260199 :90163258:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.260970 :9016325E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.261952 :90163266:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.262418 :90163267:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.274084*:90163270:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031731A20 estart=1745848802 eid=16777602 ctx=0x000000077B42EF30 cwh=0x000000003169B940 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0 2025-04-28 22:00:03.274084*:90163271:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031731A20 curCtx=0x00000000318EF690 pubTabIdxCnt=0 2025-04-28 22:00:03.274084*:90163273:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031731A20 2025-04-28 22:00:03.274084*:90163276:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031731A20 2025-04-28 22:00:03.274084*:90163277:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000031741E40 estart=1745848802 eid=16777602 ctx=0x000000077B447BE8 cwh=0x00000000316E0068 cpu=15625 buffg=838 interb=0 r=0 rb=0 w=0 wb=0 2025-04-28 22:00:03.274084*:90163279:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000031741E40 curCtx=0x00000000316F2BF0 pubTabIdxCnt=0 2025-04-28 22:00:03.274084*:9016327A:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000031741E40 2025-04-28 22:00:03.274084*:9016327B:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000031741E40 2025-04-28 22:00:03.274084*:9016327D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000316FB838 estart=1745848802 eid=16777602 ctx=0x000000077B458BE8 cwh=0x000000002B4707F0 cpu=15625 buffg=876 interb=16384 r=2 rb=16384 w=0 wb=0 2025-04-28 22:00:03.274084*:90163280:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000316FB838 curCtx=0x000000003198A4B8 pubTabIdxCnt=0 2025-04-28 22:00:03.274084*:90163281:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000316FB838 2025-04-28 22:00:03.274084*:90163282:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000316FB838 2025-04-28 22:00:03.274084*:90163283:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031731A20 curCtx=0x00000000318EF690 xsc->flg4=65792 flags=1 2025-04-28 22:00:03.274084*:90163284:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031731A20 2025-04-28 22:00:03.274084*:90163285:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000031741E40 curCtx=0x00000000316F2BF0 xsc->flg4=65792 flags=1 2025-04-28 22:00:03.274084*:90163286:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000031741E40 2025-04-28 22:00:03.274084*:90163289:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000316FB838 curCtx=0x000000003198A4B8 xsc->flg4=65792 flags=2 2025-04-28 22:00:03.274084*:9016328A:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000316FB838 2025-04-28 22:00:03.284453 :9016328B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.284503 :9016328E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.285303 :90163294:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.286237 :9016329C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpus:psdpgi.c:2235 2025-04-28 22:00:03.286669 :9016329D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.307327 :901632A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.307377 :901632A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:psdpgi.c:2235 2025-04-28 22:00:03.308202 :901632B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.308728 :901632BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.309171 :901632C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.309347 :901632CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.309499 :901632D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.309635 :901632DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:30:241] atxpop:jslv.c:9203 2025-04-28 22:00:03.313633 :901632DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.313714 :901632DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:30:241] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-04-28 22:00:03.313809 :901632E7:db_trace:ksl2.c@12699:ksliwat(): [10005:30:241] 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 30 (osid: 11188, J004)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de