Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250522220006\orcl_j001_10584_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250522220006\orcl_j001_10584_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:37338M/63366M, Ph+PgF:44417M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 10584, image: ORACLE.EXE (J001) *** 2025-05-22 22:00:06.115 *** SESSION ID:(193.50235) 2025-05-22 22:00:06.115 *** 2025-05-22 22:00:06.115 Process diagnostic dump for ORACLE.EXE (J001), OS id=10584, pid: 24, proc_ser: 17, sid: 193, sess_ser: 50235 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10584 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.096275 sec, exc=0.096275 sec, total=0.096275 sec wait times: max=0.500000 sec, heur=4.052530 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510100 sec, exc=0.510100 sec, total=0.510100 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499984 sec, exc=0.499984 sec, total=0.499984 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 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=5 seq_num=6 snap_id=1 wait times: snap=0.499005 sec, exc=0.499005 sec, total=0.499005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.329117 sec, exc=0.329117 sec, total=0.329117 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.510117 sec, exc=0.510117 sec, total=0.510117 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499230 sec, exc=0.499230 sec, total=0.499230 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.108621 sec, exc=0.108621 sec, total=0.108621 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 50235 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 22:00:02 - 22:00:06] idle wait at each sample [session created at: 22:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-22 22:00:06.115 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 10584, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-22 21:47:36.937878 :9AA94CAE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:47:36.937878 :9AA94CAF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:47:36.937879 :9AA94CB0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:47:36.937879 :9AA94CB1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:47:36.937879 :9AA94CB2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:47:36.938370 :9AA94CB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:48:07.038544 :9AA9569F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:48:11.991301 :9AA9583E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:48:11.980 2025-05-22 21:48:11.991303 :9AA9583F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12396 pso_num=24 pso_serial#=6 2025-05-22 21:48:11.991485 :9AA95840:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12396 osp_idx=22 osp_ver=304985 osp_pg=0 (spawn #209145) 2025-05-22 21:48:11.991486 :9AA95841:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:48:11.991486 :9AA95842:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=216 time=926266891 2025-05-22 21:48:11.991487 :9AA95843:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:48:11.991487 :9AA95844:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:48:11.991488 :9AA95845:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:48:11.991488 :9AA95846:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:48:11.991488 :9AA95847:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:48:11.991997 :9AA95848:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:48:37.014485 :9AA96067:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 21:49:07.087415 :9AA969ED:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:49:37.143097 :9AA97329:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:49:37.131 2025-05-22 21:49:37.143099 :9AA9732A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12608 pso_num=24 pso_serial#=7 2025-05-22 21:49:37.143231 :9AA9732B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12608 osp_idx=22 osp_ver=304986 osp_pg=0 (spawn #209147) 2025-05-22 21:49:37.143231 :9AA9732C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:49:37.143232 :9AA9732D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=217 time=926352032 2025-05-22 21:49:37.143232 :9AA9732E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:49:37.143232 :9AA9732F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:49:37.143233 :9AA97330:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:49:37.143233 :9AA97331:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:49:37.143234 :9AA97332:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:49:37.143709 :9AA97333:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:50:07.210459 :9AA97CD9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:50:37.227914 :9AA986EA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:50:37.216 2025-05-22 21:50:37.227916 :9AA986EB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9648 pso_num=24 pso_serial#=8 2025-05-22 21:50:37.228039 :9AA986EC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9648 osp_idx=22 osp_ver=304987 osp_pg=0 (spawn #209150) 2025-05-22 21:50:37.228039 :9AA986ED:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:50:37.228040 :9AA986EE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=218 time=926412125 2025-05-22 21:50:37.228041 :9AA986EF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:50:37.228041 :9AA986F0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:50:37.228042 :9AA986F1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:50:37.228042 :9AA986F2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:50:37.228042 :9AA986F3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:50:37.228526 :9AA986F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:51:07.335367 :9AA9918A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:51:37.313600 :9AA99AB8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:51:37.302 2025-05-22 21:51:37.313602 :9AA99AB9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14292 pso_num=24 pso_serial#=9 2025-05-22 21:51:37.313738 :9AA99ABA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14292 osp_idx=22 osp_ver=304988 osp_pg=0 (spawn #209152) 2025-05-22 21:51:37.313738 :9AA99ABB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:51:37.313739 :9AA99ABC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=219 time=926472204 2025-05-22 21:51:37.313739 :9AA99ABD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:51:37.313740 :9AA99ABE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:51:37.313740 :9AA99ABF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:51:37.313740 :9AA99AC0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:51:37.313741 :9AA99AC1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:51:37.314226 :9AA99AC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:52:07.403541 :9AA9A436:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:52:37.454896 :9AA9AD9F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:52:37.444 2025-05-22 21:52:37.454899 :9AA9ADA0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13364 pso_num=24 pso_serial#=10 2025-05-22 21:52:37.455035 :9AA9ADA1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13364 osp_idx=22 osp_ver=304989 osp_pg=0 (spawn #209154) 2025-05-22 21:52:37.455036 :9AA9ADA2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:52:37.455036 :9AA9ADA3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=220 time=926532344 2025-05-22 21:52:37.455036 :9AA9ADA4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:52:37.455036 :9AA9ADA5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:52:37.455037 :9AA9ADA6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:52:37.455037 :9AA9ADA7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:52:37.455037 :9AA9ADA8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:52:37.455536 :9AA9ADA9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:53:07.547857 :9AA9B849:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:53:37.554539 :9AA9C1A2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:53:37.543 2025-05-22 21:53:37.554542 :9AA9C1A3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11800 pso_num=24 pso_serial#=11 2025-05-22 21:53:37.554666 :9AA9C1A4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11800 osp_idx=22 osp_ver=304990 osp_pg=0 (spawn #209156) 2025-05-22 21:53:37.554667 :9AA9C1A5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:53:37.554668 :9AA9C1A6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=221 time=926592454 2025-05-22 21:53:37.554668 :9AA9C1A7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:53:37.554668 :9AA9C1A8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:53:37.554669 :9AA9C1A9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:53:37.554669 :9AA9C1AA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:53:37.554670 :9AA9C1AB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:53:37.555194 :9AA9C1AC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:54:07.650615 :9AA9CB1D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:54:37.652566 :9AA9D49C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:54:37.641 2025-05-22 21:54:37.652568 :9AA9D49D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13484 pso_num=24 pso_serial#=12 2025-05-22 21:54:37.652709 :9AA9D49E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13484 osp_idx=22 osp_ver=304991 osp_pg=0 (spawn #209159) 2025-05-22 21:54:37.652709 :9AA9D49F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:54:37.652710 :9AA9D4A0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=222 time=926652547 2025-05-22 21:54:37.652710 :9AA9D4A1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:54:37.652711 :9AA9D4A2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:54:37.652711 :9AA9D4A3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:54:37.652711 :9AA9D4A4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:54:37.652712 :9AA9D4A5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:54:37.653190 :9AA9D4A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:55:07.726317 :9AA9DDCC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:55:37.740284 :9AA9E7DA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:55:37.729 2025-05-22 21:55:37.740286 :9AA9E7DB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12784 pso_num=24 pso_serial#=13 2025-05-22 21:55:37.740457 :9AA9E7DC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12784 osp_idx=22 osp_ver=304992 osp_pg=0 (spawn #209162) 2025-05-22 21:55:37.740458 :9AA9E7DD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:55:37.740458 :9AA9E7DE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=223 time=926712625 2025-05-22 21:55:37.740459 :9AA9E7DF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:55:37.740459 :9AA9E7E0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:55:37.740460 :9AA9E7E1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:55:37.740460 :9AA9E7E2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:55:37.740460 :9AA9E7E3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:55:37.740930 :9AA9E7E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:56:07.820420 :9AA9F1CA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:56:37.826033 :9AA9FB66:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:56:37.815 2025-05-22 21:56:37.826035 :9AA9FB67:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14132 pso_num=24 pso_serial#=14 2025-05-22 21:56:37.826173 :9AA9FB68:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14132 osp_idx=22 osp_ver=304993 osp_pg=0 (spawn #209164) 2025-05-22 21:56:37.826174 :9AA9FB69:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:56:37.826175 :9AA9FB6A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=224 time=926772719 2025-05-22 21:56:37.826175 :9AA9FB6B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:56:37.826175 :9AA9FB6C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:56:37.826176 :9AA9FB6D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:56:37.826176 :9AA9FB6E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:56:37.826176 :9AA9FB6F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:56:37.826690 :9AA9FB70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:57:07.863819 :9AAA04CC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:57:37.926140 :9AAA0E76:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:57:37.914 2025-05-22 21:57:37.926143 :9AAA0E77:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10684 pso_num=24 pso_serial#=15 2025-05-22 21:57:37.926265 :9AAA0E78:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10684 osp_idx=22 osp_ver=304994 osp_pg=0 (spawn #209166) 2025-05-22 21:57:37.926266 :9AAA0E79:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:57:37.926266 :9AAA0E7A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=225 time=926832813 2025-05-22 21:57:37.926266 :9AAA0E7B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:57:37.926267 :9AAA0E7C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:57:37.926267 :9AAA0E7D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:57:37.926268 :9AAA0E7E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:57:37.926268 :9AAA0E7F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:57:37.926729 :9AAA0E80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:58:08.026837 :9AAA17DD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:58:12.994258 :9AAA19FE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:58:12.983 2025-05-22 21:58:12.994260 :9AAA19FF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13708 pso_num=24 pso_serial#=16 2025-05-22 21:58:12.994390 :9AAA1A00:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13708 osp_idx=22 osp_ver=304995 osp_pg=0 (spawn #209168) 2025-05-22 21:58:12.994391 :9AAA1A01:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:58:12.994391 :9AAA1A02:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=226 time=926867891 2025-05-22 21:58:12.994392 :9AAA1A03:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:58:12.994392 :9AAA1A04:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:58:12.994392 :9AAA1A05:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:58:12.994392 :9AAA1A06:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:58:12.994393 :9AAA1A07:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:58:12.994852 :9AAA1A08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 21:58:38.026279 :9AAA2198:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 21:59:08.127976 :9AAA2B30:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-22 21:59:38.129886 :9AAA34E9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-22 21:59:38.119 2025-05-22 21:59:38.129888 :9AAA34EA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10584 pso_num=24 pso_serial#=17 2025-05-22 21:59:38.130024 :9AAA34EB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10584 osp_idx=22 osp_ver=304996 osp_pg=0 (spawn #209170) 2025-05-22 21:59:38.130024 :9AAA34EC:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-22 21:59:38.130025 :9AAA34ED:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=227 time=926953016 2025-05-22 21:59:38.130025 :9AAA34EE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-22 21:59:38.130026 :9AAA34EF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-22 21:59:38.130026 :9AAA34F0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-22 21:59:38.130026 :9AAA34F1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-22 21:59:38.130026 :9AAA34F2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-22 21:59:38.130521 :9AAA34F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.038214 :9AAA3E16:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.038214*:9AAA3E24:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495B898 estart=1747922401 eid=16780918 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-05-22 22:00:02.038214*:9AAA3E25:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495B898 curCtx=0x0000000024957AB8 pubTabIdxCnt=0 2025-05-22 22:00:02.038214*:9AAA3E26:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495B898 2025-05-22 22:00:02.038214*:9AAA3E27:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495B898 2025-05-22 22:00:02.038214*:9AAA3E28:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495B898 curCtx=0x0000000024957AB8 xsc->flg4=65792 flags=2 2025-05-22 22:00:02.038214*:9AAA3E29:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495B898 2025-05-22 22:00:02.043752 :9AAA3E34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.043800 :9AAA3E37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-05-22 22:00:02.048666 :9AAA3E61:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-22 22:00:02.049245 :9AAA3E74:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 e5d9110000000000 2025-05-22 22:00:02.050921 :9AAA3E8A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.050981 :9AAA3E97:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.050990 :9AAA3E9D:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-05-22 22:00:02.050995 :9AAA3E9E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.051019 :9AAA3EAA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.051766 :9AAA3EB6:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 226099024) 2025-05-22 22:00:02.051771 :9AAA3EB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.051796 :9AAA3EC5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.051805 :9AAA3ECF:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1) 2025-05-22 22:00:02.051808 :9AAA3ED0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.051836 :9AAA3EDC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.051840 :9AAA3EE2:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560) 2025-05-22 22:00:02.051843 :9AAA3EE5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.051896 :9AAA3F1A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.052473 :9AAA3FAB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.052501 :9AAA3FAC:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-05-22 22:00:02.053409 :9AAA3FB7:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 e6d9110000000000 2025-05-22 22:00:02.053789 :9AAA3FB8:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 226099024) 2025-05-22 22:00:02.053795 :9AAA3FB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.053820 :9AAA3FC4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:02.062019 :9AAA4028:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-05-22 22:00:02.063667 :9AAA4037:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.063743 :9AAA403A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-05-22 22:00:02.173198 :9AAA416E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-22 22:00:04.012053 :9AAA45C8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 10584, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de