Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250702220008\orcl_j000_13868_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:36835M/63366M, Ph+PgF:42808M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 13868, image: ORACLE.EXE (J000) *** 2025-07-02 22:00:08.503 *** SESSION ID:(193.41095) 2025-07-02 22:00:08.503 *** 2025-07-02 22:00:08.503 Process diagnostic dump for ORACLE.EXE (J000), OS id=13868, pid: 24, proc_ser: 66, sid: 193, sess_ser: 41095 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13868 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.000518 sec, exc=0.000518 sec, total=0.000518 sec wait times: max=0.500000 sec, heur=2.493363 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.492814 sec, exc=0.492814 sec, total=0.492814 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 41095 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:06 - 22:00:08] idle wait at each sample [session created at: 22:00:06] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-02 22:00:08.503 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 13868, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-02 21:53:23.310201 :ACB20C2E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17968 pso_num=24 pso_serial#=58 2025-07-02 21:53:23.310328 :ACB20C2F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17968 osp_idx=22 osp_ver=502163 osp_pg=0 (spawn #345811) 2025-07-02 21:53:23.310329 :ACB20C30:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:53:23.310329 :ACB20C31:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=120 time=173979204 2025-07-02 21:53:23.310330 :ACB20C32:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:53:23.310330 :ACB20C33:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:53:23.310330 :ACB20C34:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:53:23.310331 :ACB20C35:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:53:23.310331 :ACB20C36:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:53:23.310810 :ACB20C37: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-07-02 21:53:53.425126 :ACB21675:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 21:54:23.383956 :ACB22052:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 21:54:23.373 2025-07-02 21:54:23.383959 :ACB22053:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16768 pso_num=24 pso_serial#=59 2025-07-02 21:54:23.384079 :ACB22054:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16768 osp_idx=22 osp_ver=502164 osp_pg=0 (spawn #345813) 2025-07-02 21:54:23.384079 :ACB22055:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:54:23.384079 :ACB22056:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=121 time=174039267 2025-07-02 21:54:23.384080 :ACB22057:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:54:23.384080 :ACB22058:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:54:23.384081 :ACB22059:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:54:23.384081 :ACB2205A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:54:23.384081 :ACB2205B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:54:23.384648 :ACB2205C: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-07-02 21:54:53.473551 :ACB229F1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 21:55:23.473080 :ACB233E3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 21:55:23.461 2025-07-02 21:55:23.473082 :ACB233E4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9904 pso_num=24 pso_serial#=60 2025-07-02 21:55:23.473243 :ACB233E5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9904 osp_idx=22 osp_ver=502165 osp_pg=0 (spawn #345816) 2025-07-02 21:55:23.473244 :ACB233E6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:55:23.473245 :ACB233E7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=122 time=174099361 2025-07-02 21:55:23.473245 :ACB233E8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:55:23.473245 :ACB233E9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:55:23.473246 :ACB233EA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:55:23.473246 :ACB233EB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:55:23.473247 :ACB233EC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:55:23.473740 :ACB233ED: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-07-02 21:55:53.588665 :ACB23E12:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 21:56:23.555148 :ACB247E7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 21:56:23.544 2025-07-02 21:56:23.555150 :ACB247E8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8996 pso_num=24 pso_serial#=61 2025-07-02 21:56:23.555286 :ACB247E9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8996 osp_idx=22 osp_ver=502166 osp_pg=0 (spawn #345818) 2025-07-02 21:56:23.555287 :ACB247EA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:56:23.555287 :ACB247EB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=123 time=174159439 2025-07-02 21:56:23.555288 :ACB247EC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:56:23.555288 :ACB247ED:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:56:23.555289 :ACB247EE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:56:23.555289 :ACB247EF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:56:23.555289 :ACB247F0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:56:23.555772 :ACB247F1: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-07-02 21:56:48.592333 :ACB2500E: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-07-02 21:57:18.662400 :ACB25986:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 21:57:23.651845 :ACB25B73:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 21:57:23.640 2025-07-02 21:57:23.651848 :ACB25B74:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15672 pso_num=24 pso_serial#=62 2025-07-02 21:57:23.651962 :ACB25B75:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15672 osp_idx=22 osp_ver=502167 osp_pg=0 (spawn #345820) 2025-07-02 21:57:23.651963 :ACB25B76:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:57:23.651964 :ACB25B77:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=124 time=174219533 2025-07-02 21:57:23.651964 :ACB25B78:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:57:23.651964 :ACB25B79:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:57:23.651965 :ACB25B7A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:57:23.651966 :ACB25B7B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:57:23.651966 :ACB25B7C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:57:23.652464 :ACB25B7D: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-07-02 21:57:53.758968 :ACB26551:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 21:58:23.739219 :ACB26F12:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 21:58:23.728 2025-07-02 21:58:23.739221 :ACB26F13:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6988 pso_num=24 pso_serial#=63 2025-07-02 21:58:23.739358 :ACB26F14:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=6988 osp_idx=22 osp_ver=502168 osp_pg=0 (spawn #345823) 2025-07-02 21:58:23.739359 :ACB26F15:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:58:23.739359 :ACB26F16:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=125 time=174279626 2025-07-02 21:58:23.739360 :ACB26F17:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:58:23.739360 :ACB26F18:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:58:23.739361 :ACB26F19:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:58:23.739361 :ACB26F1A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:58:23.739362 :ACB26F1B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:58:23.739848 :ACB26F1C: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-07-02 21:58:53.820016 :ACB2792A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 21:59:23.833610 :ACB282E6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 21:59:23.822 2025-07-02 21:59:23.833613 :ACB282E7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=208 pso_num=24 pso_serial#=64 2025-07-02 21:59:23.833752 :ACB282E8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=208 osp_idx=22 osp_ver=502169 osp_pg=0 (spawn #345825) 2025-07-02 21:59:23.833753 :ACB282E9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:59:23.833753 :ACB282EA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=126 time=174339720 2025-07-02 21:59:23.833753 :ACB282EB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:59:23.833754 :ACB282EC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:59:23.833754 :ACB282ED:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:59:23.833754 :ACB282EE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:59:23.833755 :ACB282EF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:59:23.834259 :ACB282F0: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-07-02 21:59:53.922401 :ACB28C9A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 22:00:00.044709 :ACB28F36:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 22:00:00.036 2025-07-02 22:00:00.044711 :ACB28F37:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4140 pso_num=24 pso_serial#=65 2025-07-02 22:00:00.044850 :ACB28F38:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4140 osp_idx=22 osp_ver=502170 osp_pg=0 (spawn #345828) 2025-07-02 22:00:00.044851 :ACB28F39:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 22:00:00.044852 :ACB28F3A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=127 time=174375939 2025-07-02 22:00:00.044852 :ACB28F3B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 22:00:00.044852 :ACB28F3C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 22:00:00.044853 :ACB28F3D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 22:00:00.044853 :ACB28F3E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 22:00:00.044853 :ACB28F3F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 22:00:00.045345 :ACB28F40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-02 22:00:00.045393 :ACB28F47:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 22:00:00.045470 :ACB28F5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-02 22:00:00.049441 :ACB28F6A: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-07-02 22:00:00.050648 :ACB28F72: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-07-02 22:00:00.050673 :ACB28F75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-02 22:00:00.050712 :ACB28F80:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 22:00:00.052618 :ACB28F8B: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-07-02 22:00:00.052645 :ACB28F8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-02 22:00:00.052684 :ACB28F99:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 22:00:00.053129 :ACB28FA4: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-07-02 22:00:00.055740 :ACB28FAC: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-07-02 22:00:00.060991 :ACB28FB4: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-07-02 22:00:00.066802 :ACB28FBC: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-07-02 22:00:00.068180 :ACB28FC4: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-07-02 22:00:00.070674 :ACB28FCC: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-07-02 22:00:00.071417 :ACB28FD4: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-07-02 22:00:00.072697 :ACB28FDC: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-07-02 22:00:00.073631 :ACB28FE4: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-07-02 22:00:00.076274 :ACB28FEC: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-07-02 22:00:00.079722 :ACB28FF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-02 22:00:00.080080 :ACB29003: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-07-02 22:00:00.080128 :ACB29006:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-07-02 22:00:00.080142 :ACB29007:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-07-02 22:00:00.082149 :ACB2900D: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-07-02 22:00:00.082194 :ACB29010:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-07-02 22:00:00.082206 :ACB29011:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-07-02 22:00:00.084225 :ACB29017: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-07-02 22:00:00.084273 :ACB2901A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-07-02 22:00:00.084285 :ACB2901B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-07-02 22:00:00.084455 :ACB29021: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-07-02 22:00:00.084470 :ACB29024:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-02 22:00:00.084509 :ACB2902F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 22:00:00.086075 :ACB2903A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-02 22:00:02.051822 :ACB29107:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-02 22:00:02.046 2025-07-02 22:00:02.051825 :ACB29108:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13868 pso_num=24 pso_serial#=66 2025-07-02 22:00:02.051966 :ACB29109:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13868 osp_idx=22 osp_ver=502171 osp_pg=0 (spawn #345829) 2025-07-02 22:00:02.051966 :ACB2910A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 22:00:02.051967 :ACB2910B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=128 time=174377939 2025-07-02 22:00:02.051967 :ACB2910C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 22:00:02.051968 :ACB2910D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 22:00:02.051968 :ACB2910E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 22:00:02.051969 :ACB2910F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 22:00:02.051969 :ACB29110:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 22:00:02.052502 :ACB29111: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-07-02 22:00:02.057854 :ACB29130: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-07-02 22:00:02.057902 :ACB29133:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-02 22:00:02.071978 :ACB291A6: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-07-02 22:00:02.078948 :ACB292E2: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-07-02 22:00:02.080430 :ACB29304: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-07-02 22:00:02.087941 :ACB29329:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-02 22:00:02.088207 :ACB29339:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-02 22:00:02.089431 :ACB29349: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-07-02 22:00:02.089810 :ACB29353:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-02 22:00:02.092341 :ACB29365: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-07-02 22:00:02.092430 :ACB29368: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-07-02 22:00:02.092515 :ACB29373: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-07-02 22:00:02.187204 :ACB2945A: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-07-02 22:00:02.189326 :ACB2945F: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-07-02 22:00:02.189365 :ACB29462:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-02 22:00:02.191856 :ACB29468:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-02 22:00:02.195131 :ACB29469: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-07-02 22:00:02.195208 :ACB2946C: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-07-02 22:00:02.195303 :ACB29479: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-07-02 22:00:04.103225 :ACB298A3: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-07-02 22:00:04.103225*:ACB298A9:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1751464803 eid=16783449 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-02 22:00:04.103225*:ACB298AA:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-07-02 22:00:04.103225*:ACB298AB:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-07-02 22:00:04.103225*:ACB298AC:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-07-02 22:00:04.103225*:ACB298AD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-07-02 22:00:04.103225*:ACB298AE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-07-02 22:00:04.105486 :ACB298AF: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-07-02 22:00:04.105528 :ACB298B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-02 22:00:04.113800 :ACB298B8: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-07-02 22:00:04.114535 :ACB298C0: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-07-02 22:00:04.115249 :ACB298C8: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-07-02 22:00:04.115553 :ACB298D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-02 22:00:06.008278 :ACB2996E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=29 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 22:00:06.008278*:ACB2996F:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D95CA0 estart=1751464803 eid=16786187 ctx=0x000000077782F480 cwh=0x0000000024D50068 cpu=0 buffg=22 interb=0 r=0 rb=0 w=0 wb=0 2025-07-02 22:00:06.008278*:ACB29971:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D95CA0 curCtx=0x0000000024D94D80 pubTabIdxCnt=0 2025-07-02 22:00:06.008278*:ACB29972:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D95CA0 2025-07-02 22:00:06.008278*:ACB29973:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D95CA0 2025-07-02 22:00:06.008376 :ACB29974:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=26 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-02 22:00:06.008376*:ACB29976:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D95CA0 curCtx=0x0000000024D94D80 xsc->flg4=65792 flags=2 2025-07-02 22:00:06.008376*:ACB29977:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D95CA0 2025-07-02 22:00:06.008799 :ACB2997E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=26 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 2025-07-02 22:00:06.009611 :ACB29989: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-07-02 22:00:06.009651 :ACB2998C: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-07-02 22:00:06.009699 :ACB29993:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=29 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-02 22:00:06.009702 :ACB29994: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-07-02 22:00:06.009716 :ACB2999B: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 13868, J000)