Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250619082755\orcl_j001_17364_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:36505M/63366M, Ph+PgF:42870M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 17364, image: ORACLE.EXE (J001) *** 2025-06-19 08:27:55.872 *** SESSION ID:(193.7283) 2025-06-19 08:27:55.872 *** 2025-06-19 08:27:55.872 Process diagnostic dump for ORACLE.EXE (J001), OS id=17364, pid: 24, proc_ser: 229, sid: 193, sess_ser: 7283 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 17364 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.443683 sec, exc=0.443683 sec, total=0.443683 sec wait times: max=0.500000 sec, heur=8.465291 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.000016 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000024 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000016 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 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.000017 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.510123 sec, exc=0.510123 sec, total=0.510123 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time Sampled Session History of session 193 serial 7283 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 08:27:47 - 08:27:55] idle wait at each sample [session created at: 08:27:47] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-19 08:27:55.872 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 17364, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-19 08:13:20.944488 :A6B15AF8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:13:20.933 2025-06-19 08:13:20.944490 :A6B15AF9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11896 pso_num=24 pso_serial#=215 2025-06-19 08:13:20.944628 :A6B15AFA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11896 osp_idx=22 osp_ver=431949 osp_pg=0 (spawn #300574) 2025-06-19 08:13:20.944629 :A6B15AFB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:13:20.944629 :A6B15AFC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=223 time=3296544125 2025-06-19 08:13:20.944630 :A6B15AFD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:13:20.944630 :A6B15AFE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:13:20.944631 :A6B15AFF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:13:20.944631 :A6B15B00:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:13:20.944631 :A6B15B01:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:13:20.945132 :A6B15B02: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-06-19 08:13:51.026992 :A6B16474:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:14:21.033871 :A6B16E87:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:14:21.023 2025-06-19 08:14:21.033873 :A6B16E88:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17456 pso_num=24 pso_serial#=216 2025-06-19 08:14:21.034008 :A6B16E89:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17456 osp_idx=22 osp_ver=431950 osp_pg=0 (spawn #300577) 2025-06-19 08:14:21.034008 :A6B16E8A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:14:21.034009 :A6B16E8B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=224 time=3296604219 2025-06-19 08:14:21.034009 :A6B16E8C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:14:21.034010 :A6B16E8D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:14:21.034010 :A6B16E8E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:14:21.034010 :A6B16E8F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:14:21.034011 :A6B16E90:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:14:21.034485 :A6B16E91: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-06-19 08:14:51.103765 :A6B1781A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:15:21.088582 :A6B181B3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:15:21.077 2025-06-19 08:15:21.088584 :A6B181B4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16048 pso_num=24 pso_serial#=217 2025-06-19 08:15:21.088722 :A6B181B5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16048 osp_idx=22 osp_ver=431951 osp_pg=0 (spawn #300579) 2025-06-19 08:15:21.088723 :A6B181B6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:15:21.088724 :A6B181B7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=225 time=3296664266 2025-06-19 08:15:21.088724 :A6B181B8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:15:21.088724 :A6B181B9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:15:21.088725 :A6B181BA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:15:21.088725 :A6B181BB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:15:21.088726 :A6B181BC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:15:21.089192 :A6B181BD: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-06-19 08:15:51.165123 :A6B18BD3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:16:21.229420 :A6B1956B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:16:21.230 2025-06-19 08:16:21.229422 :A6B1956C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15784 pso_num=24 pso_serial#=218 2025-06-19 08:16:21.229561 :A6B1956D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15784 osp_idx=22 osp_ver=431952 osp_pg=0 (spawn #300581) 2025-06-19 08:16:21.229561 :A6B1956E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:16:21.229562 :A6B1956F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=226 time=3296724407 2025-06-19 08:16:21.229562 :A6B19570:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:16:21.229563 :A6B19571:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:16:21.229563 :A6B19572:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:16:21.229563 :A6B19573:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:16:21.229564 :A6B19574:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:16:21.230080 :A6B19575: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-06-19 08:16:51.328020 :A6B19F0E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:17:21.342127 :A6B1A93F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:17:21.331 2025-06-19 08:17:21.342129 :A6B1A940:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18376 pso_num=24 pso_serial#=219 2025-06-19 08:17:21.342275 :A6B1A941:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18376 osp_idx=22 osp_ver=431953 osp_pg=0 (spawn #300584) 2025-06-19 08:17:21.342276 :A6B1A942:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:17:21.342276 :A6B1A943:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=227 time=3296784532 2025-06-19 08:17:21.342277 :A6B1A944:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:17:21.342277 :A6B1A945:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:17:21.342277 :A6B1A946:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:17:21.342278 :A6B1A947:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:17:21.342278 :A6B1A948:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:17:21.342839 :A6B1A949: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-06-19 08:17:46.379635 :A6B1B187: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-06-19 08:18:16.450310 :A6B1BB10:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:18:21.454668 :A6B1BD1C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:18:21.443 2025-06-19 08:18:21.454670 :A6B1BD1D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16436 pso_num=24 pso_serial#=220 2025-06-19 08:18:21.454810 :A6B1BD1E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16436 osp_idx=22 osp_ver=431954 osp_pg=0 (spawn #300586) 2025-06-19 08:18:21.454810 :A6B1BD1F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:18:21.454810 :A6B1BD20:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=228 time=3296844641 2025-06-19 08:18:21.454811 :A6B1BD21:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:18:21.454811 :A6B1BD22:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:18:21.454811 :A6B1BD23:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:18:21.454812 :A6B1BD24:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:18:21.454812 :A6B1BD25:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:18:21.455296 :A6B1BD26: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-06-19 08:18:51.537668 :A6B1C722:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:19:21.542119 :A6B1D0F7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:19:21.530 2025-06-19 08:19:21.542121 :A6B1D0F8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17316 pso_num=24 pso_serial#=221 2025-06-19 08:19:21.542260 :A6B1D0F9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17316 osp_idx=22 osp_ver=431955 osp_pg=0 (spawn #300589) 2025-06-19 08:19:21.542261 :A6B1D0FA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:19:21.542262 :A6B1D0FB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=229 time=3296904719 2025-06-19 08:19:21.542262 :A6B1D0FC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:19:21.542262 :A6B1D0FD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:19:21.542263 :A6B1D0FE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:19:21.542263 :A6B1D0FF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:19:21.542263 :A6B1D100:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:19:21.542761 :A6B1D101: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-06-19 08:19:51.608249 :A6B1DA85:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:20:21.623463 :A6B1E443:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:20:21.612 2025-06-19 08:20:21.623466 :A6B1E444:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17160 pso_num=24 pso_serial#=222 2025-06-19 08:20:21.623607 :A6B1E445:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17160 osp_idx=22 osp_ver=431956 osp_pg=0 (spawn #300591) 2025-06-19 08:20:21.623608 :A6B1E446:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:20:21.623608 :A6B1E447:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=230 time=3296964813 2025-06-19 08:20:21.623609 :A6B1E448:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:20:21.623609 :A6B1E449:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:20:21.623610 :A6B1E44A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:20:21.623610 :A6B1E44B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:20:21.623610 :A6B1E44C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:20:21.624085 :A6B1E44D: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-06-19 08:20:51.723037 :A6B1EE08:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:21:21.741216 :A6B1F7DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:21:21.730 2025-06-19 08:21:21.741219 :A6B1F7E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17380 pso_num=24 pso_serial#=223 2025-06-19 08:21:21.741363 :A6B1F7E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17380 osp_idx=22 osp_ver=431957 osp_pg=0 (spawn #300593) 2025-06-19 08:21:21.741364 :A6B1F7E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:21:21.741365 :A6B1F7E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=231 time=3297024922 2025-06-19 08:21:21.741365 :A6B1F7E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:21:21.741366 :A6B1F7E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:21:21.741366 :A6B1F7E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:21:21.741367 :A6B1F7E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:21:21.741367 :A6B1F7E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:21:21.741846 :A6B1F7E9: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-06-19 08:21:51.828053 :A6B20179:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:22:21.839436 :A6B20B18:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:22:21.828 2025-06-19 08:22:21.839438 :A6B20B19:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17040 pso_num=24 pso_serial#=224 2025-06-19 08:22:21.839573 :A6B20B1A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17040 osp_idx=22 osp_ver=431958 osp_pg=0 (spawn #300595) 2025-06-19 08:22:21.839573 :A6B20B1B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:22:21.839574 :A6B20B1C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=232 time=3297085016 2025-06-19 08:22:21.839574 :A6B20B1D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:22:21.839574 :A6B20B1E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:22:21.839575 :A6B20B1F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:22:21.839575 :A6B20B20:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:22:21.839575 :A6B20B21:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:22:21.840049 :A6B20B22: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-06-19 08:22:51.944459 :A6B215D4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:23:21.940229 :A6B21F9A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:23:21.928 2025-06-19 08:23:21.940231 :A6B21F9B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17124 pso_num=24 pso_serial#=225 2025-06-19 08:23:21.940363 :A6B21F9C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17124 osp_idx=22 osp_ver=431959 osp_pg=0 (spawn #300597) 2025-06-19 08:23:21.940364 :A6B21F9D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:23:21.940364 :A6B21F9E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=233 time=3297145125 2025-06-19 08:23:21.940364 :A6B21F9F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:23:21.940364 :A6B21FA0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:23:21.940365 :A6B21FA1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:23:21.940365 :A6B21FA2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:23:21.940365 :A6B21FA3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:23:21.940887 :A6B21FA4: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-06-19 08:23:52.013260 :A6B2294E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:24:22.037349 :A6B23301:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:24:22.026 2025-06-19 08:24:22.037352 :A6B23302:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17188 pso_num=24 pso_serial#=226 2025-06-19 08:24:22.037478 :A6B23303:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17188 osp_idx=22 osp_ver=431960 osp_pg=0 (spawn #300599) 2025-06-19 08:24:22.037479 :A6B23304:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:24:22.037479 :A6B23305:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=234 time=3297205219 2025-06-19 08:24:22.037480 :A6B23306:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:24:22.037480 :A6B23307:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:24:22.037481 :A6B23308:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:24:22.037481 :A6B23309:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:24:22.037482 :A6B2330A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:24:22.037968 :A6B2330B: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-06-19 08:24:52.134164 :A6B23C96:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:25:22.140535 :A6B2461F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:25:22.130 2025-06-19 08:25:22.140537 :A6B24620:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17324 pso_num=24 pso_serial#=227 2025-06-19 08:25:22.140675 :A6B24621:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17324 osp_idx=22 osp_ver=431961 osp_pg=0 (spawn #300601) 2025-06-19 08:25:22.140676 :A6B24622:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:25:22.140677 :A6B24623:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=235 time=3297265329 2025-06-19 08:25:22.140677 :A6B24624:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:25:22.140677 :A6B24625:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:25:22.140678 :A6B24626:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:25:22.140678 :A6B24627:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:25:22.140678 :A6B24628:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:25:22.141186 :A6B24629: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-06-19 08:25:52.249165 :A6B2502A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:26:22.248444 :A6B25A08:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:26:22.237 2025-06-19 08:26:22.248446 :A6B25A09:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18004 pso_num=24 pso_serial#=228 2025-06-19 08:26:22.248586 :A6B25A0A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18004 osp_idx=22 osp_ver=431962 osp_pg=0 (spawn #300603) 2025-06-19 08:26:22.248587 :A6B25A0B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:26:22.248587 :A6B25A0C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=236 time=3297325438 2025-06-19 08:26:22.248588 :A6B25A0D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:26:22.248588 :A6B25A0E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:26:22.248588 :A6B25A0F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:26:22.248589 :A6B25A10:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:26:22.248589 :A6B25A11:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:26:22.249065 :A6B25A12: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-06-19 08:26:52.362980 :A6B263DB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-19 08:27:22.370496 :A6B26DFE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-19 08:27:22.359 2025-06-19 08:27:22.370498 :A6B26DFF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17364 pso_num=24 pso_serial#=229 2025-06-19 08:27:22.370622 :A6B26E00:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17364 osp_idx=22 osp_ver=431963 osp_pg=0 (spawn #300606) 2025-06-19 08:27:22.370623 :A6B26E01:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-19 08:27:22.370624 :A6B26E02:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=237 time=3297385547 2025-06-19 08:27:22.370624 :A6B26E03:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-19 08:27:22.370625 :A6B26E04:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-19 08:27:22.370625 :A6B26E05:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-19 08:27:22.370625 :A6B26E06:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-19 08:27:22.370626 :A6B26E07:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-19 08:27:22.371132 :A6B26E08: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-06-19 08:27:47.399856 :A6B27615: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-06-19 08:27:47.399895*:A6B27616:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1750292867 eid=16916641 ctx=0x000000077BD98A08 cwh=0x00000000232801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-06-19 08:27:47.399895*:A6B27617:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F708 pubTabIdxCnt=0 2025-06-19 08:27:47.399895*:A6B27618:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-06-19 08:27:47.399895*:A6B27619:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-06-19 08:27:47.399895*:A6B2761A:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F708 xsc->flg4=65792 flags=2 2025-06-19 08:27:47.399895*:A6B2761B:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-06-19 08:27:47.400496 :A6B2761C: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-06-19 08:27:47.405921 :A6B27624: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-06-19 08:27:47.406872 :A6B2762C: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-06-19 08:27:47.407010 :A6B27635: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: 17364, J001)