Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250720081839\orcl_j001_15780_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:36152M/63366M, Ph+PgF:42017M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 15780, image: ORACLE.EXE (J001) *** 2025-07-20 08:18:39.802 *** SESSION ID:(209.58425) 2025-07-20 08:18:39.802 *** 2025-07-20 08:18:39.802 Process diagnostic dump for ORACLE.EXE (J001), OS id=15780, pid: 26, proc_ser: 22, sid: 209, sess_ser: 58425 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15780 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.179297 sec, exc=0.179297 sec, total=0.179297 sec wait times: max=0.500000 sec, heur=12.241718 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=23 seq_num=24 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 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.500128 sec, exc=0.500128 sec, total=0.500128 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=0.499983 sec, exc=0.499983 sec, total=0.499983 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=20 seq_num=21 snap_id=1 wait times: snap=0.515503 sec, exc=0.515503 sec, total=0.515503 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=0.499979 sec, exc=0.499979 sec, total=0.499979 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=18 seq_num=19 snap_id=1 wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=17 seq_num=18 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.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 209 serial 58425 --------------------------------------------------- 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 --------------------------------------------------- [13 samples, 08:18:27 - 08:18:39] idle wait at each sample [session created at: 08:18:27] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-20 08:18:39.802 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 15780, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-20 08:03:56.290253 :B47874BC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:04:26.268418 :B4787E81:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:04:26.257 2025-07-20 08:04:26.268420 :B4787E82:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19380 pso_num=26 pso_serial#=8 2025-07-20 08:04:26.268559 :B4787E83:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19380 osp_idx=24 osp_ver=650003 osp_pg=0 (spawn #403900) 2025-07-20 08:04:26.268559 :B4787E84:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:04:26.268560 :B4787E85:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=116 time=1679442158 2025-07-20 08:04:26.268560 :B4787E86:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:04:26.268561 :B4787E87:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:04:26.268561 :B4787E88:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:04:26.268561 :B4787E89:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:04:26.268561 :B4787E8A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:04:26.269051 :B4787E8B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:04:56.340893 :B4788874:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:05:26.366035 :B478927D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:05:26.354 2025-07-20 08:05:26.366037 :B478927E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16988 pso_num=26 pso_serial#=9 2025-07-20 08:05:26.366168 :B478927F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16988 osp_idx=24 osp_ver=650004 osp_pg=0 (spawn #403904) 2025-07-20 08:05:26.366169 :B4789280:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:05:26.366169 :B4789281:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=117 time=1679502251 2025-07-20 08:05:26.366169 :B4789282:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:05:26.366170 :B4789283:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:05:26.366170 :B4789284:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:05:26.366170 :B4789285:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:05:26.366171 :B4789286:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:05:26.366645 :B4789287:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:05:51.402048 :B478A0FE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-20 08:06:21.495638 :B478AA94:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:06:26.464577 :B478AC47:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:06:26.453 2025-07-20 08:06:26.464579 :B478AC48:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12540 pso_num=26 pso_serial#=10 2025-07-20 08:06:26.464708 :B478AC49:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12540 osp_idx=24 osp_ver=650005 osp_pg=0 (spawn #403906) 2025-07-20 08:06:26.464709 :B478AC4A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:06:26.464709 :B478AC4B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=118 time=1679562345 2025-07-20 08:06:26.464710 :B478AC4C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:06:26.464710 :B478AC4D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:06:26.464711 :B478AC4E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:06:26.464711 :B478AC4F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:06:26.464711 :B478AC50:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:06:26.465207 :B478AC51:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:06:56.559936 :B478B6FF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:07:26.564976 :B478C0C0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:07:26.554 2025-07-20 08:07:26.564978 :B478C0C1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17496 pso_num=26 pso_serial#=11 2025-07-20 08:07:26.565174 :B478C0C2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17496 osp_idx=24 osp_ver=650006 osp_pg=0 (spawn #403908) 2025-07-20 08:07:26.565174 :B478C0C3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:07:26.565175 :B478C0C4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=119 time=1679622454 2025-07-20 08:07:26.565175 :B478C0C5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:07:26.565175 :B478C0C6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:07:26.565176 :B478C0C7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:07:26.565176 :B478C0C8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:07:26.565176 :B478C0C9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:07:26.565657 :B478C0CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:07:56.653789 :B478CA77:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:08:26.650428 :B478D408:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:08:26.640 2025-07-20 08:08:26.650430 :B478D409:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19432 pso_num=26 pso_serial#=12 2025-07-20 08:08:26.650559 :B478D40A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19432 osp_idx=24 osp_ver=650007 osp_pg=0 (spawn #403910) 2025-07-20 08:08:26.650559 :B478D40B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:08:26.650560 :B478D40C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=120 time=1679682533 2025-07-20 08:08:26.650560 :B478D40D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:08:26.650561 :B478D40E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:08:26.650561 :B478D40F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:08:26.650561 :B478D410:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:08:26.650562 :B478D411:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:08:26.651050 :B478D412:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:08:56.734615 :B478DEE3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:09:26.758885 :B478E8C8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:09:26.747 2025-07-20 08:09:26.758888 :B478E8C9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9904 pso_num=26 pso_serial#=13 2025-07-20 08:09:26.759017 :B478E8CA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9904 osp_idx=24 osp_ver=650008 osp_pg=0 (spawn #403912) 2025-07-20 08:09:26.759018 :B478E8CB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:09:26.759018 :B478E8CC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=121 time=1679742642 2025-07-20 08:09:26.759018 :B478E8CD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:09:26.759019 :B478E8CE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:09:26.759019 :B478E8CF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:09:26.759020 :B478E8D0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:09:26.759020 :B478E8D1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:09:26.759511 :B478E8D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:09:56.803891 :B478F287:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:10:26.817495 :B478FC21:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:10:26.806 2025-07-20 08:10:26.817497 :B478FC22:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19260 pso_num=26 pso_serial#=14 2025-07-20 08:10:26.817623 :B478FC23:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19260 osp_idx=24 osp_ver=650009 osp_pg=0 (spawn #403914) 2025-07-20 08:10:26.817624 :B478FC24:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:10:26.817624 :B478FC25:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=122 time=1679802704 2025-07-20 08:10:26.817624 :B478FC26:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:10:26.817624 :B478FC27:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:10:26.817625 :B478FC28:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:10:26.817625 :B478FC29:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:10:26.817625 :B478FC2A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:10:26.818125 :B478FC2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:10:56.910231 :B47905A9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:11:26.913289 :B4790F5B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:11:26.901 2025-07-20 08:11:26.913291 :B4790F5C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13660 pso_num=26 pso_serial#=15 2025-07-20 08:11:26.913426 :B4790F5D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13660 osp_idx=24 osp_ver=650010 osp_pg=0 (spawn #403916) 2025-07-20 08:11:26.913427 :B4790F5E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:11:26.913427 :B4790F5F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=123 time=1679862798 2025-07-20 08:11:26.913428 :B4790F60:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:11:26.913428 :B4790F61:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:11:26.913429 :B4790F62:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:11:26.913429 :B4790F63:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:11:26.913429 :B4790F64:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:11:26.913921 :B4790F65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:11:57.037765 :B4791A0B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:12:26.999374 :B47923D4:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:12:26.988 2025-07-20 08:12:26.999376 :B47923D5:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18964 pso_num=26 pso_serial#=16 2025-07-20 08:12:26.999514 :B47923D6:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18964 osp_idx=24 osp_ver=650011 osp_pg=0 (spawn #403919) 2025-07-20 08:12:26.999514 :B47923D7:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:12:26.999515 :B47923D8:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=124 time=1679922892 2025-07-20 08:12:26.999515 :B47923D9:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:12:26.999515 :B47923DA:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:12:26.999516 :B47923DB:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:12:26.999516 :B47923DC:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:12:26.999516 :B47923DD:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:12:26.999990 :B47923DE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:12:57.101141 :B4792D8F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:13:27.099341 :B4793711:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:13:27.087 2025-07-20 08:13:27.099343 :B4793712:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15508 pso_num=26 pso_serial#=17 2025-07-20 08:13:27.099462 :B4793713:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15508 osp_idx=24 osp_ver=650012 osp_pg=0 (spawn #403921) 2025-07-20 08:13:27.099463 :B4793714:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:13:27.099463 :B4793715:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=125 time=1679982986 2025-07-20 08:13:27.099464 :B4793716:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:13:27.099464 :B4793717:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:13:27.099465 :B4793718:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:13:27.099465 :B4793719:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:13:27.099465 :B479371A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:13:27.099947 :B479371B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:13:57.200885 :B479410E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:14:27.188364 :B4794ACD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:14:27.177 2025-07-20 08:14:27.188366 :B4794ACE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16492 pso_num=26 pso_serial#=18 2025-07-20 08:14:27.188485 :B4794ACF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16492 osp_idx=24 osp_ver=650013 osp_pg=0 (spawn #403923) 2025-07-20 08:14:27.188486 :B4794AD0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:14:27.188486 :B4794AD1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=126 time=1680043079 2025-07-20 08:14:27.188486 :B4794AD2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:14:27.188486 :B4794AD3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:14:27.188487 :B4794AD4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:14:27.188487 :B4794AD5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:14:27.188487 :B4794AD6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:14:27.189056 :B4794AD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:14:57.257991 :B4795471:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:15:27.274188 :B4795E45:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:15:27.262 2025-07-20 08:15:27.274190 :B4795E46:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18912 pso_num=26 pso_serial#=19 2025-07-20 08:15:27.274302 :B4795E47:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18912 osp_idx=24 osp_ver=650014 osp_pg=0 (spawn #403926) 2025-07-20 08:15:27.274303 :B4795E48:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:15:27.274303 :B4795E49:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=127 time=1680103158 2025-07-20 08:15:27.274304 :B4795E4A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:15:27.274304 :B4795E4B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:15:27.274304 :B4795E4C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:15:27.274305 :B4795E4D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:15:27.274305 :B4795E4E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:15:27.274810 :B4795E4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:15:52.304781 :B47965DA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-20 08:15:52.304827*:B47965DB:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BABB58 estart=1752970551 eid=16965812 ctx=0x000000077BD98A08 cwh=0x0000000027B801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-20 08:15:52.304827*:B47965DC:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BABB58 curCtx=0x0000000027B8F708 pubTabIdxCnt=0 2025-07-20 08:15:52.304827*:B47965DD:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BABB58 2025-07-20 08:15:52.304827*:B47965DE:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BABB58 2025-07-20 08:15:52.304827*:B47965DF:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BABB58 curCtx=0x0000000027B8F708 xsc->flg4=65792 flags=2 2025-07-20 08:15:52.304827*:B47965E0:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BABB58 2025-07-20 08:15:52.305469 :B47965E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-20 08:15:52.310888 :B47965E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-20 08:15:52.311927 :B47965F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-20 08:15:52.312066 :B47965FA:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-20 08:16:22.363805 :B4796FA3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:16:27.348710 :B47971A0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:16:27.337 2025-07-20 08:16:27.348712 :B47971A1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15096 pso_num=26 pso_serial#=20 2025-07-20 08:16:27.348843 :B47971A2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15096 osp_idx=24 osp_ver=650015 osp_pg=0 (spawn #403928) 2025-07-20 08:16:27.348843 :B47971A3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:16:27.348844 :B47971A4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=128 time=1680163236 2025-07-20 08:16:27.348844 :B47971A5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:16:27.348845 :B47971A6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:16:27.348845 :B47971A7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:16:27.348845 :B47971A8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:16:27.348845 :B47971A9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:16:27.349332 :B47971AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:16:57.434781 :B4797B9A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:17:27.455541 :B479854F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:17:27.444 2025-07-20 08:17:27.455543 :B4798550:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16748 pso_num=26 pso_serial#=21 2025-07-20 08:17:27.455684 :B4798551:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16748 osp_idx=24 osp_ver=650016 osp_pg=0 (spawn #403930) 2025-07-20 08:17:27.455685 :B4798552:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:17:27.455685 :B4798553:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=129 time=1680223345 2025-07-20 08:17:27.455686 :B4798554:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:17:27.455686 :B4798555:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:17:27.455686 :B4798556:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:17:27.455687 :B4798557:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:17:27.455687 :B4798558:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:17:27.456178 :B4798559:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-20 08:17:57.547264 :B4798F23:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-07-20 08:18:27.566835 :B47998CB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 08:18:27.564 2025-07-20 08:18:27.566837 :B47998CC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15780 pso_num=26 pso_serial#=22 2025-07-20 08:18:27.566974 :B47998CD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15780 osp_idx=24 osp_ver=650017 osp_pg=0 (spawn #403932) 2025-07-20 08:18:27.566975 :B47998CE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-20 08:18:27.566975 :B47998CF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=130 time=1680283454 2025-07-20 08:18:27.566975 :B47998D0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-07-20 08:18:27.566976 :B47998D1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-07-20 08:18:27.566976 :B47998D2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-07-20 08:18:27.566977 :B47998D3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-20 08:18:27.566977 :B47998D4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-20 08:18:27.567452 :B47998D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 15780, J001)