Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250814220005\orcl_j001_16212_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:35494M/63366M, Ph+PgF:40844M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16212, image: ORACLE.EXE (J001) *** 2025-08-14 22:00:06.029 *** SESSION ID:(201.6571) 2025-08-14 22:00:06.029 *** 2025-08-14 22:00:06.029 Process diagnostic dump for ORACLE.EXE (J001), OS id=16212, pid: 25, proc_ser: 15, sid: 201, sess_ser: 6571 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16212 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.325701 sec, exc=0.325701 sec, total=0.325701 sec wait times: max=0.500000 sec, heur=1.823109 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.497383 sec, exc=0.497383 sec, total=0.497383 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 201 serial 6571 --------------------------------------------------- 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 --------------------------------------------------- [2 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-14 22:00:06.029 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16212, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-14 21:48:51.434994 :BFC73EE7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21492 pso_num=25 pso_serial#=4 2025-08-14 21:48:51.435127 :BFC73EE8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21492 osp_idx=23 osp_ver=240836 osp_pg=0 (spawn #489117) 2025-08-14 21:48:51.435128 :BFC73EE9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:48:51.435128 :BFC73EEA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=246 time=3888800736 2025-08-14 21:48:51.435128 :BFC73EEB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:48:51.435128 :BFC73EEC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:48:51.435129 :BFC73EED:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:48:51.435129 :BFC73EEE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:48:51.435129 :BFC73EEF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:48:51.435608 :BFC73EF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:49:06.453410 :BFC743AC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 21:49:36.498362 :BFC74D1C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:49:51.525980 :BFC75227:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:49:51.514 2025-08-14 21:49:51.525982 :BFC75228:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4492 pso_num=25 pso_serial#=5 2025-08-14 21:49:51.526111 :BFC75229:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4492 osp_idx=23 osp_ver=240837 osp_pg=0 (spawn #489119) 2025-08-14 21:49:51.526112 :BFC7522A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:49:51.526112 :BFC7522B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=247 time=3888860829 2025-08-14 21:49:51.526113 :BFC7522C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:49:51.526113 :BFC7522D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:49:51.526114 :BFC7522E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:49:51.526114 :BFC7522F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:49:51.526114 :BFC75230:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:49:51.526597 :BFC75231:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:50:21.635482 :BFC75C3D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:50:51.648135 :BFC765D5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:50:51.636 2025-08-14 21:50:51.648137 :BFC765D6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16248 pso_num=25 pso_serial#=6 2025-08-14 21:50:51.648256 :BFC765D7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16248 osp_idx=23 osp_ver=240838 osp_pg=0 (spawn #489121) 2025-08-14 21:50:51.648257 :BFC765D8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:50:51.648257 :BFC765D9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=248 time=3888920954 2025-08-14 21:50:51.648257 :BFC765DA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:50:51.648258 :BFC765DB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:50:51.648258 :BFC765DC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:50:51.648258 :BFC765DD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:50:51.648259 :BFC765DE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:50:51.648844 :BFC765DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:51:21.727871 :BFC76F79:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:51:51.736451 :BFC7791A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:51:51.725 2025-08-14 21:51:51.736453 :BFC7791B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20788 pso_num=25 pso_serial#=7 2025-08-14 21:51:51.736580 :BFC7791C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20788 osp_idx=23 osp_ver=240839 osp_pg=0 (spawn #489123) 2025-08-14 21:51:51.736581 :BFC7791D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:51:51.736583 :BFC7791E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=249 time=3888981033 2025-08-14 21:51:51.736583 :BFC7791F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:51:51.736584 :BFC77920:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:51:51.736584 :BFC77921:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:51:51.736584 :BFC77922:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:51:51.736585 :BFC77923:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:51:51.737059 :BFC77924:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:52:21.818016 :BFC782D9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:52:51.810377 :BFC78C7D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:52:51.799 2025-08-14 21:52:51.810379 :BFC78C7E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18848 pso_num=25 pso_serial#=8 2025-08-14 21:52:51.810511 :BFC78C7F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18848 osp_idx=23 osp_ver=240840 osp_pg=0 (spawn #489127) 2025-08-14 21:52:51.810511 :BFC78C80:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:52:51.810512 :BFC78C81:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=250 time=3889041111 2025-08-14 21:52:51.810512 :BFC78C82:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:52:51.810513 :BFC78C83:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:52:51.810513 :BFC78C84:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:52:51.810513 :BFC78C85:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:52:51.810514 :BFC78C86:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:52:51.810990 :BFC78C87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:53:21.932284 :BFC7971C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:53:51.897843 :BFC7A0D9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:53:51.886 2025-08-14 21:53:51.897844 :BFC7A0DA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10304 pso_num=25 pso_serial#=9 2025-08-14 21:53:51.897960 :BFC7A0DB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10304 osp_idx=23 osp_ver=240841 osp_pg=0 (spawn #489129) 2025-08-14 21:53:51.897961 :BFC7A0DC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:53:51.897961 :BFC7A0DD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=251 time=3889101204 2025-08-14 21:53:51.897962 :BFC7A0DE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:53:51.897962 :BFC7A0DF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:53:51.897962 :BFC7A0E0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:53:51.897963 :BFC7A0E1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:53:51.897963 :BFC7A0E2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:53:51.898432 :BFC7A0E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:54:21.996872 :BFC7AAB7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:54:52.009802 :BFC7B467:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:54:51.999 2025-08-14 21:54:52.009803 :BFC7B468:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6008 pso_num=25 pso_serial#=10 2025-08-14 21:54:52.009942 :BFC7B469:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6008 osp_idx=23 osp_ver=240842 osp_pg=0 (spawn #489131) 2025-08-14 21:54:52.009942 :BFC7B46A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:54:52.009943 :BFC7B46B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=3889161314 2025-08-14 21:54:52.009943 :BFC7B46C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:54:52.009943 :BFC7B46D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:54:52.009944 :BFC7B46E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:54:52.009944 :BFC7B46F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:54:52.009944 :BFC7B470:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:54:52.010443 :BFC7B471:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:55:22.112894 :BFC7BED5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:55:52.108927 :BFC7C85A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:55:52.097 2025-08-14 21:55:52.108929 :BFC7C85B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20760 pso_num=25 pso_serial#=11 2025-08-14 21:55:52.109070 :BFC7C85C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20760 osp_idx=23 osp_ver=240843 osp_pg=0 (spawn #489133) 2025-08-14 21:55:52.109071 :BFC7C85D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:55:52.109071 :BFC7C85E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=3889221408 2025-08-14 21:55:52.109072 :BFC7C85F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:55:52.109072 :BFC7C860:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:55:52.109073 :BFC7C861:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:55:52.109073 :BFC7C862:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:55:52.109074 :BFC7C863:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:55:52.109595 :BFC7C864:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:56:22.224295 :BFC7D223:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:56:52.206804 :BFC7DBC9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:56:52.195 2025-08-14 21:56:52.206806 :BFC7DBCA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20692 pso_num=25 pso_serial#=12 2025-08-14 21:56:52.206924 :BFC7DBCB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20692 osp_idx=23 osp_ver=240844 osp_pg=0 (spawn #489135) 2025-08-14 21:56:52.206924 :BFC7DBCC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:56:52.206925 :BFC7DBCD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=3889281501 2025-08-14 21:56:52.206925 :BFC7DBCE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:56:52.206925 :BFC7DBCF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:56:52.206926 :BFC7DBD0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:56:52.206926 :BFC7DBD1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:56:52.206926 :BFC7DBD2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:56:52.207407 :BFC7DBD3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:57:22.324711 :BFC7E5BA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:57:52.334919 :BFC7EEFF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:57:52.323 2025-08-14 21:57:52.334921 :BFC7EF00:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14932 pso_num=25 pso_serial#=13 2025-08-14 21:57:52.335053 :BFC7EF01:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14932 osp_idx=23 osp_ver=240845 osp_pg=0 (spawn #489137) 2025-08-14 21:57:52.335054 :BFC7EF02:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:57:52.335054 :BFC7EF03:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=3889341642 2025-08-14 21:57:52.335055 :BFC7EF04:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:57:52.335055 :BFC7EF05:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:57:52.335056 :BFC7EF06:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:57:52.335056 :BFC7EF07:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:57:52.335056 :BFC7EF08:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:57:52.335611 :BFC7EF09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:58:22.425274 :BFC7F968:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:58:52.448451 :BFC802F2:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:58:52.437 2025-08-14 21:58:52.448453 :BFC802F3:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9604 pso_num=25 pso_serial#=14 2025-08-14 21:58:52.448575 :BFC802F4:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9604 osp_idx=23 osp_ver=240846 osp_pg=0 (spawn #489139) 2025-08-14 21:58:52.448575 :BFC802F5:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:58:52.448576 :BFC802F6:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=3889401751 2025-08-14 21:58:52.448576 :BFC802F7:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:58:52.448576 :BFC802F8:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:58:52.448577 :BFC802F9:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:58:52.448577 :BFC802FA:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:58:52.448577 :BFC802FB:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:58:52.449059 :BFC802FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 21:59:07.470848 :BFC807F6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 21:59:37.574561 :BFC81191:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-14 21:59:52.560202 :BFC8163A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-14 21:59:52.549 2025-08-14 21:59:52.560204 :BFC8163B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16212 pso_num=25 pso_serial#=15 2025-08-14 21:59:52.560336 :BFC8163C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16212 osp_idx=23 osp_ver=240847 osp_pg=0 (spawn #489141) 2025-08-14 21:59:52.560336 :BFC8163D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-14 21:59:52.560336 :BFC8163E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=3889461861 2025-08-14 21:59:52.560337 :BFC8163F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-14 21:59:52.560337 :BFC81640:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-14 21:59:52.560338 :BFC81641:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-14 21:59:52.560338 :BFC81642:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-14 21:59:52.560338 :BFC81643:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-14 21:59:52.560840 :BFC81644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.026443 :BFC81B85:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 22:00:02.026443*:BFC81BA8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1755180001 eid=16786085 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-14 22:00:02.026443*:BFC81BAD:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-08-14 22:00:02.026443*:BFC81BAF:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-08-14 22:00:02.026443*:BFC81BB0:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-08-14 22:00:02.026443*:BFC81BC6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-08-14 22:00:02.026443*:BFC81BC7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-08-14 22:00:02.033177 :BFC81BC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.033233 :BFC81BCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-14 22:00:02.045124 :BFC81CBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.050578 :BFC81D75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.051372 :BFC81D7D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.057311 :BFC81D8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.057465 :BFC81DA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.058111 :BFC81DB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.058317 :BFC81DBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-14 22:00:02.059807 :BFC81DBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.059851 :BFC81DC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.059969 :BFC81DCF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 22:00:02.151230 :BFC81EAE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 22:00:02.153307 :BFC81EB1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.153346 :BFC81EB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-14 22:00:02.155838 :BFC81EBA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-14 22:00:02.159003 :BFC81EBB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.159084 :BFC81EBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-14 22:00:02.159181 :BFC81ECB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 22:00:04.199257 :BFC822A6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-14 22:00:04.199257*:BFC822A8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026BEC718 estart=1755180003 eid=16786090 ctx=0x000000077FD6A000 cwh=0x0000000026BE0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-14 22:00:04.199257*:BFC822A9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 pubTabIdxCnt=0 2025-08-14 22:00:04.199257*:BFC822AA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026BEC718 2025-08-14 22:00:04.199257*:BFC822AB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026BEC718 2025-08-14 22:00:04.199257*:BFC822AC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026BEC718 curCtx=0x0000000026BE8938 xsc->flg4=65792 flags=2 2025-08-14 22:00:04.199257*:BFC822AD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026BEC718 2025-08-14 22:00:04.201413 :BFC822AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.201453 :BFC822B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-08-14 22:00:04.208751 :BFC822B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.209432 :BFC822BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.210106 :BFC822C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.210314 :BFC822CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-08-14 22:00:04.210724 :BFC822D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.215573 :BFC822D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.215640 :BFC822DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-14 22:00:04.215725 :BFC822E8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 16212, J001)