Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802060008\orcl_j001_12740_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802060008\orcl_j001_12740_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:35794M/63366M, Ph+PgF:41413M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 12740, image: ORACLE.EXE (J001) *** 2025-08-02 06:00:08.847 *** SESSION ID:(193.2553) 2025-08-02 06:00:08.847 *** 2025-08-02 06:00:08.847 Process diagnostic dump for ORACLE.EXE (J001), OS id=12740, pid: 24, proc_ser: 23, sid: 193, sess_ser: 2553 ------------------------------------------------------------------------------- current sql: <none> client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 12740 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.189963 sec, exc=0.189963 sec, total=0.189963 sec wait times: max=0.500000 sec, heur=6.789907 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 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.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000007 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.502260 sec, exc=0.502260 sec, total=0.502260 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 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=6 seq_num=7 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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 9: 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.000008 sec of elapsed time Sampled Session History of session 193 serial 2553 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 06:00:02 - 06:00:08] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 06:00:08.847 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 12740, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 05:47:33.331315 :BA32EAEC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:47:33.331315 :BA32EAED:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:47:33.331315 :BA32EAEE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:47:33.331316 :BA32EAEF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:47:33.331316 :BA32EAF0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:47:33.331779 :BA32EAF1: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-08-02 05:48:03.395768 :BA32F5A1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:48:33.435953 :BA32FF41:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:48:33.424 2025-08-02 05:48:33.435956 :BA32FF42:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16248 pso_num=24 pso_serial#=12 2025-08-02 05:48:33.436093 :BA32FF43:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16248 osp_idx=22 osp_ver=653587 osp_pg=0 (spawn #446885) 2025-08-02 05:48:33.436094 :BA32FF44:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:48:33.436094 :BA32FF45:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=70 time=2794382736 2025-08-02 05:48:33.436095 :BA32FF46:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:48:33.436095 :BA32FF47:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:48:33.436096 :BA32FF48:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:48:33.436096 :BA32FF49:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:48:33.436096 :BA32FF4A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:48:33.436579 :BA32FF4B: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-08-02 05:49:03.509213 :BA3308FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:49:33.513995 :BA3312E3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:49:33.503 2025-08-02 05:49:33.513997 :BA3312E4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10604 pso_num=24 pso_serial#=13 2025-08-02 05:49:33.514135 :BA3312E5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10604 osp_idx=22 osp_ver=653588 osp_pg=0 (spawn #446887) 2025-08-02 05:49:33.514135 :BA3312E6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:49:33.514137 :BA3312E7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=71 time=2794442814 2025-08-02 05:49:33.514138 :BA3312E8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:49:33.514138 :BA3312E9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:49:33.514138 :BA3312EA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:49:33.514138 :BA3312EB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:49:33.514139 :BA3312EC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:49:33.514620 :BA3312ED: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-08-02 05:50:03.591548 :BA331CEC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:50:33.573844 :BA3326D4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:50:33.563 2025-08-02 05:50:33.573846 :BA3326D5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17272 pso_num=24 pso_serial#=14 2025-08-02 05:50:33.573979 :BA3326D6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17272 osp_idx=22 osp_ver=653589 osp_pg=0 (spawn #446890) 2025-08-02 05:50:33.573979 :BA3326D7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:50:33.573980 :BA3326D8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=72 time=2794502876 2025-08-02 05:50:33.573980 :BA3326D9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:50:33.573980 :BA3326DA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:50:33.573981 :BA3326DB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:50:33.573981 :BA3326DC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:50:33.573981 :BA3326DD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:50:33.574504 :BA3326DE: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-08-02 05:51:03.623395 :BA3330BB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:51:33.656281 :BA333AAC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:51:33.645 2025-08-02 05:51:33.656283 :BA333AAD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10744 pso_num=24 pso_serial#=15 2025-08-02 05:51:33.656401 :BA333AAE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10744 osp_idx=22 osp_ver=653590 osp_pg=0 (spawn #446892) 2025-08-02 05:51:33.656401 :BA333AAF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:51:33.656402 :BA333AB0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=73 time=2794562954 2025-08-02 05:51:33.656402 :BA333AB1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:51:33.656402 :BA333AB2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:51:33.656403 :BA333AB3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:51:33.656403 :BA333AB4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:51:33.656403 :BA333AB5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:51:33.656930 :BA333AB6: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-08-02 05:52:03.740468 :BA334480:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:52:33.765017 :BA334EAE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:52:33.754 2025-08-02 05:52:33.765019 :BA334EAF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8288 pso_num=24 pso_serial#=16 2025-08-02 05:52:33.765155 :BA334EB0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8288 osp_idx=22 osp_ver=653591 osp_pg=0 (spawn #446894) 2025-08-02 05:52:33.765155 :BA334EB1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:52:33.765156 :BA334EB2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=74 time=2794623064 2025-08-02 05:52:33.765156 :BA334EB3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:52:33.765157 :BA334EB4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:52:33.765157 :BA334EB5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:52:33.765157 :BA334EB6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:52:33.765158 :BA334EB7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:52:33.765677 :BA334EB8: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-08-02 05:52:48.781827 :BA33546B: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-08-02 05:53:18.858513 :BA335E32:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:53:33.868697 :BA336374:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:53:33.857 2025-08-02 05:53:33.868699 :BA336375:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13728 pso_num=24 pso_serial#=17 2025-08-02 05:53:33.868835 :BA336376:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13728 osp_idx=22 osp_ver=653592 osp_pg=0 (spawn #446896) 2025-08-02 05:53:33.868835 :BA336377:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:53:33.868836 :BA336378:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=75 time=2794683173 2025-08-02 05:53:33.868836 :BA336379:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:53:33.868837 :BA33637A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:53:33.868837 :BA33637B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:53:33.868837 :BA33637C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:53:33.868838 :BA33637D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:53:33.869329 :BA33637E: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-08-02 05:54:03.937683 :BA336D59:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:54:33.933881 :BA337722:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:54:33.922 2025-08-02 05:54:33.933883 :BA337723:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11796 pso_num=24 pso_serial#=18 2025-08-02 05:54:33.933999 :BA337724:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11796 osp_idx=22 osp_ver=653593 osp_pg=0 (spawn #446898) 2025-08-02 05:54:33.934000 :BA337725:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:54:33.934000 :BA337726:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=76 time=2794743236 2025-08-02 05:54:33.934000 :BA337727:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:54:33.934001 :BA337728:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:54:33.934001 :BA337729:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:54:33.934001 :BA33772A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:54:33.934002 :BA33772B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:54:33.934490 :BA33772C: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-08-02 05:55:04.019159 :BA338140:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:55:34.018991 :BA338B44:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:55:34.008 2025-08-02 05:55:34.018993 :BA338B45:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19156 pso_num=24 pso_serial#=19 2025-08-02 05:55:34.019133 :BA338B46:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19156 osp_idx=22 osp_ver=653594 osp_pg=0 (spawn #446901) 2025-08-02 05:55:34.019133 :BA338B47:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:55:34.019134 :BA338B48:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=77 time=2794803314 2025-08-02 05:55:34.019134 :BA338B49:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:55:34.019135 :BA338B4A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:55:34.019135 :BA338B4B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:55:34.019135 :BA338B4C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:55:34.019136 :BA338B4D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:55:34.019632 :BA338B4E: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-08-02 05:56:04.109161 :BA339589:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:56:34.125330 :BA339F9A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:56:34.114 2025-08-02 05:56:34.125332 :BA339F9B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12464 pso_num=24 pso_serial#=20 2025-08-02 05:56:34.125454 :BA339F9C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12464 osp_idx=22 osp_ver=653595 osp_pg=0 (spawn #446903) 2025-08-02 05:56:34.125454 :BA339F9D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:56:34.125455 :BA339F9E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=78 time=2794863423 2025-08-02 05:56:34.125455 :BA339F9F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:56:34.125456 :BA339FA0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:56:34.125456 :BA339FA1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:56:34.125456 :BA339FA2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:56:34.125457 :BA339FA3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:56:34.125930 :BA339FA4: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-08-02 05:57:04.246733 :BA33A92A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:57:34.228750 :BA33B340:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:57:34.218 2025-08-02 05:57:34.228752 :BA33B341:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19860 pso_num=24 pso_serial#=21 2025-08-02 05:57:34.228870 :BA33B342:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19860 osp_idx=22 osp_ver=653596 osp_pg=0 (spawn #446905) 2025-08-02 05:57:34.228871 :BA33B343:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:57:34.228871 :BA33B344:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=79 time=2794923533 2025-08-02 05:57:34.228872 :BA33B345:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:57:34.228872 :BA33B346:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:57:34.228872 :BA33B347:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:57:34.228873 :BA33B348:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:57:34.228873 :BA33B349:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:57:34.229373 :BA33B34A: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-08-02 05:58:04.279022 :BA33BDC8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:58:34.315899 :BA33C7C2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:58:34.305 2025-08-02 05:58:34.315901 :BA33C7C3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18604 pso_num=24 pso_serial#=22 2025-08-02 05:58:34.316032 :BA33C7C4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18604 osp_idx=22 osp_ver=653597 osp_pg=0 (spawn #446907) 2025-08-02 05:58:34.316033 :BA33C7C5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:58:34.316033 :BA33C7C6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=80 time=2794983611 2025-08-02 05:58:34.316033 :BA33C7C7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:58:34.316033 :BA33C7C8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:58:34.316034 :BA33C7C9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:58:34.316034 :BA33C7CA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:58:34.316034 :BA33C7CB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:58:34.316562 :BA33C7CC: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-08-02 05:59:04.390435 :BA33D1B5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-02 05:59:34.411991 :BA33DB6B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-02 05:59:34.401 2025-08-02 05:59:34.411993 :BA33DB6C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12740 pso_num=24 pso_serial#=23 2025-08-02 05:59:34.412127 :BA33DB6D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12740 osp_idx=22 osp_ver=653598 osp_pg=0 (spawn #446909) 2025-08-02 05:59:34.412127 :BA33DB6E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 05:59:34.412127 :BA33DB6F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=81 time=2795043704 2025-08-02 05:59:34.412127 :BA33DB70:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 05:59:34.412128 :BA33DB71:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 05:59:34.412128 :BA33DB72:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 05:59:34.412129 :BA33DB73:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 05:59:34.412129 :BA33DB74:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 05:59:34.412605 :BA33DB75: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-08-02 06:00:02.032449 :BA33E614: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-08-02 06:00:02.032449*:BA33E623:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5B898 estart=1754085601 eid=16785306 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-02 06:00:02.032449*:BA33E624:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 pubTabIdxCnt=0 2025-08-02 06:00:02.032449*:BA33E625:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5B898 2025-08-02 06:00:02.032449*:BA33E626:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5B898 2025-08-02 06:00:02.032449*:BA33E627:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5B898 curCtx=0x0000000024D57AB8 xsc->flg4=65792 flags=2 2025-08-02 06:00:02.032449*:BA33E628:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5B898 2025-08-02 06:00:02.037987 :BA33E633: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-08-02 06:00:02.038031 :BA33E636:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-02 06:00:02.042966 :BA33E660:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-02 06:00:02.043564 :BA33E674:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 1568120000000000 2025-08-02 06:00:02.045200 :BA33E68A: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-08-02 06:00:02.045279 :BA33E699: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-08-02 06:00:02.045293 :BA33E69D:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1) 2025-08-02 06:00:02.045300 :BA33E69E: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-08-02 06:00:02.045331 :BA33E6AE: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-08-02 06:00:02.046070 :BA33E6B6:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 233185684) 2025-08-02 06:00:02.046075 :BA33E6B7: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-08-02 06:00:02.046099 :BA33E6C2: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-08-02 06:00:02.046107 :BA33E6CF:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1) 2025-08-02 06:00:02.046109 :BA33E6D0: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-08-02 06:00:02.046140 :BA33E6DB: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-08-02 06:00:02.046144 :BA33E6E2:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560) 2025-08-02 06:00:02.046146 :BA33E6E7: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-08-02 06:00:02.046221 :BA33E70E: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-08-02 06:00:02.046722 :BA33E785: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-08-02 06:00:02.046737 :BA33E792:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7 2025-08-02 06:00:02.047633 :BA33E799:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 1668120000000000 2025-08-02 06:00:02.048016 :BA33E79A:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 233185684) 2025-08-02 06:00:02.048021 :BA33E79B: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-08-02 06:00:02.048046 :BA33E7A6: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-08-02 06:00:02.056610 :BA33E80F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-02 06:00:02.058250 :BA33E81B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-08-02 06:00:02.058290 :BA33E81D: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-08-02 06:00:02.058329 :BA33E81E: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-08-02 06:00:02.058446 :BA33E831:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-02 06:00:02.157290 :BA33E94E: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: 12740, J001)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de