Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831081748\orcl_j001_11336_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:35070M/63366M, Ph+PgF:40024M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 11336, image: ORACLE.EXE (J001) *** 2025-08-31 08:17:48.157 *** SESSION ID:(209.25765) 2025-08-31 08:17:48.157 *** 2025-08-31 08:17:48.157 Process diagnostic dump for ORACLE.EXE (J001), OS id=11336, pid: 26, proc_ser: 117, sid: 209, sess_ser: 25765 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11336 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.042745 sec, exc=0.042745 sec, total=0.042745 sec wait times: max=0.500000 sec, heur=26.100255 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=51 seq_num=52 snap_id=1 wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.499968 sec, exc=0.499968 sec, total=0.499968 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 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.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.510087 sec, exc=0.510087 sec, total=0.510087 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 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.000015 sec of elapsed time Sampled Session History of session 209 serial 25765 --------------------------------------------------- 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 --------------------------------------------------- [27 samples, 08:17:22 - 08:17:48] idle wait at each sample [session created at: 08:17:22] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 08:17:48.157 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 11336, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 08:03:20.776641 :C701906F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22564 osp_idx=24 osp_ver=764593 osp_pg=0 (spawn #543892) 2025-08-31 08:03:20.776641 :C7019070:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:03:20.776642 :C7019071:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=41 time=1013052080 2025-08-31 08:03:20.776642 :C7019072:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:03:20.776643 :C7019073:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:03:20.776643 :C7019074:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:03:20.776643 :C7019075:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:03:20.776644 :C7019076:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:03:20.777125 :C7019077: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-08-31 08:03:50.865029 :C7019AB6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:04:20.858176 :C701A476:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:04:20.847 2025-08-31 08:04:20.858178 :C701A477:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22884 pso_num=26 pso_serial#=104 2025-08-31 08:04:20.858371 :C701A478:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22884 osp_idx=24 osp_ver=764594 osp_pg=0 (spawn #543894) 2025-08-31 08:04:20.858371 :C701A479:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:04:20.858372 :C701A47A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=42 time=1013112158 2025-08-31 08:04:20.858373 :C701A47B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:04:20.858373 :C701A47C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:04:20.858374 :C701A47D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:04:20.858374 :C701A47E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:04:20.858375 :C701A47F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:04:20.858859 :C701A480: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-08-31 08:04:50.966717 :C701AE28:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:05:05.950852 :C701B318:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:05:05.939 2025-08-31 08:05:05.950854 :C701B319:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23468 pso_num=26 pso_serial#=105 2025-08-31 08:05:05.950977 :C701B31A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23468 osp_idx=24 osp_ver=764595 osp_pg=0 (spawn #543896) 2025-08-31 08:05:05.950978 :C701B31B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:05:05.950979 :C701B31C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=43 time=1013157252 2025-08-31 08:05:05.950979 :C701B31D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:05:05.950979 :C701B31E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:05:05.950980 :C701B31F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:05:05.950980 :C701B320:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:05:05.950980 :C701B321:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:05:05.951456 :C701B322: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-08-31 08:05:20.979729 :C701B7F7: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-08-31 08:05:51.065569 :C701C2FB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:06:21.045803 :C701D1AB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:06:21.034 2025-08-31 08:06:21.045805 :C701D1AC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21664 pso_num=26 pso_serial#=106 2025-08-31 08:06:21.045920 :C701D1AD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21664 osp_idx=24 osp_ver=764596 osp_pg=0 (spawn #543899) 2025-08-31 08:06:21.045921 :C701D1AE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:06:21.045921 :C701D1AF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=44 time=1013232346 2025-08-31 08:06:21.045922 :C701D1B0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:06:21.045922 :C701D1B1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:06:21.045922 :C701D1B2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:06:21.045923 :C701D1B3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:06:21.045923 :C701D1B4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:06:21.046457 :C701D1B5: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-08-31 08:06:51.104596 :C701DBA4:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:07:21.107772 :C701E580:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:07:21.097 2025-08-31 08:07:21.107775 :C701E581:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22748 pso_num=26 pso_serial#=107 2025-08-31 08:07:21.107892 :C701E582:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22748 osp_idx=24 osp_ver=764597 osp_pg=0 (spawn #543901) 2025-08-31 08:07:21.107893 :C701E583:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:07:21.107894 :C701E584:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=45 time=1013292408 2025-08-31 08:07:21.107894 :C701E585:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:07:21.107895 :C701E586:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:07:21.107895 :C701E587:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:07:21.107895 :C701E588:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:07:21.107895 :C701E589:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:07:21.108386 :C701E58A: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-08-31 08:07:51.221056 :C701EF13:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:08:21.212065 :C701F905:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:08:21.200 2025-08-31 08:08:21.212067 :C701F906:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23132 pso_num=26 pso_serial#=108 2025-08-31 08:08:21.212195 :C701F907:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23132 osp_idx=24 osp_ver=764598 osp_pg=0 (spawn #543903) 2025-08-31 08:08:21.212195 :C701F908:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:08:21.212196 :C701F909:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=46 time=1013352502 2025-08-31 08:08:21.212196 :C701F90A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:08:21.212197 :C701F90B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:08:21.212197 :C701F90C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:08:21.212197 :C701F90D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:08:21.212198 :C701F90E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:08:21.212702 :C701F90F: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-08-31 08:08:51.285919 :C70203E6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:09:21.318216 :C7020DBF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:09:21.307 2025-08-31 08:09:21.318218 :C7020DC0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22368 pso_num=26 pso_serial#=109 2025-08-31 08:09:21.318336 :C7020DC1:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22368 osp_idx=24 osp_ver=764599 osp_pg=0 (spawn #543906) 2025-08-31 08:09:21.318337 :C7020DC2:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:09:21.318337 :C7020DC3:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=47 time=1013412612 2025-08-31 08:09:21.318337 :C7020DC4:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:09:21.318337 :C7020DC5:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:09:21.318338 :C7020DC6:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:09:21.318338 :C7020DC7:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:09:21.318338 :C7020DC8:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:09:21.318810 :C7020DC9: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-08-31 08:09:51.427395 :C7021797:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:10:21.408887 :C702216A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:10:21.397 2025-08-31 08:10:21.408890 :C702216B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21856 pso_num=26 pso_serial#=110 2025-08-31 08:10:21.409010 :C702216C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21856 osp_idx=24 osp_ver=764600 osp_pg=0 (spawn #543908) 2025-08-31 08:10:21.409011 :C702216D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:10:21.409011 :C702216E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=48 time=1013472705 2025-08-31 08:10:21.409012 :C702216F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:10:21.409012 :C7022170:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:10:21.409014 :C7022171:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:10:21.409015 :C7022172:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:10:21.409015 :C7022173:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:10:21.409543 :C7022174: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-08-31 08:10:51.467531 :C7022B0B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:11:21.466691 :C70234BA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:11:21.455 2025-08-31 08:11:21.466693 :C70234BB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22700 pso_num=26 pso_serial#=111 2025-08-31 08:11:21.466817 :C70234BC:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22700 osp_idx=24 osp_ver=764601 osp_pg=0 (spawn #543910) 2025-08-31 08:11:21.466817 :C70234BD:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:11:21.466818 :C70234BE:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=49 time=1013532768 2025-08-31 08:11:21.466818 :C70234BF:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:11:21.466818 :C70234C0:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:11:21.466819 :C70234C1:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:11:21.466819 :C70234C2:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:11:21.466819 :C70234C3:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:11:21.467332 :C70234C4: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-08-31 08:11:51.540277 :C7023F4A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:12:21.561994 :C7024901:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:12:21.550 2025-08-31 08:12:21.561996 :C7024902:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22264 pso_num=26 pso_serial#=112 2025-08-31 08:12:21.562114 :C7024903:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22264 osp_idx=24 osp_ver=764602 osp_pg=0 (spawn #543912) 2025-08-31 08:12:21.562114 :C7024904:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:12:21.562115 :C7024905:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=50 time=1013592862 2025-08-31 08:12:21.562115 :C7024906:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:12:21.562116 :C7024907:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:12:21.562116 :C7024908:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:12:21.562117 :C7024909:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:12:21.562117 :C702490A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:12:21.562668 :C702490B: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-08-31 08:12:51.656222 :C70252CB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:13:21.682753 :C7025C76:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:13:21.671 2025-08-31 08:13:21.682755 :C7025C77:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20900 pso_num=26 pso_serial#=113 2025-08-31 08:13:21.682895 :C7025C78:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=20900 osp_idx=24 osp_ver=764603 osp_pg=0 (spawn #543914) 2025-08-31 08:13:21.682896 :C7025C79:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:13:21.682896 :C7025C7A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=51 time=1013652987 2025-08-31 08:13:21.682897 :C7025C7B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:13:21.682898 :C7025C7C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:13:21.682898 :C7025C7D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:13:21.682898 :C7025C7E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:13:21.682898 :C7025C7F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:13:21.683371 :C7025C80: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-08-31 08:13:51.754646 :C7026675:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:14:21.775401 :C7027030:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:14:21.764 2025-08-31 08:14:21.775404 :C7027031:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23384 pso_num=26 pso_serial#=114 2025-08-31 08:14:21.775542 :C7027032:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23384 osp_idx=24 osp_ver=764604 osp_pg=0 (spawn #543916) 2025-08-31 08:14:21.775542 :C7027033:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:14:21.775543 :C7027034:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=52 time=1013713080 2025-08-31 08:14:21.775543 :C7027035:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:14:21.775544 :C7027036:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:14:21.775544 :C7027037:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:14:21.775545 :C7027038:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:14:21.775545 :C7027039:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:14:21.776020 :C702703A: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-08-31 08:14:51.838782 :C7027A00:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:15:06.852991 :C7027EFF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:15:06.841 2025-08-31 08:15:06.852993 :C7027F00:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22308 pso_num=26 pso_serial#=115 2025-08-31 08:15:06.853133 :C7027F01:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22308 osp_idx=24 osp_ver=764605 osp_pg=0 (spawn #543918) 2025-08-31 08:15:06.853133 :C7027F02:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:15:06.853134 :C7027F03:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=53 time=1013758143 2025-08-31 08:15:06.853135 :C7027F04:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:15:06.853135 :C7027F05:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:15:06.853136 :C7027F06:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:15:06.853136 :C7027F07:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:15:06.853137 :C7027F08:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:15:06.853663 :C7027F09: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-08-31 08:15:21.858506 :C70283F2: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-08-31 08:15:21.858537*:C70283F3:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000028BABB58 estart=1756599321 eid=17032450 ctx=0x000000077BD98A08 cwh=0x0000000027B801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-31 08:15:21.858537*:C70283F4:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000028BABB58 curCtx=0x0000000027B8F708 pubTabIdxCnt=0 2025-08-31 08:15:21.858537*:C70283F5:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000028BABB58 2025-08-31 08:15:21.858537*:C70283F6:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000028BABB58 2025-08-31 08:15:21.858537*:C70283F7:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000028BABB58 curCtx=0x0000000027B8F708 xsc->flg4=65792 flags=2 2025-08-31 08:15:21.858537*:C70283F8:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000028BABB58 2025-08-31 08:15:21.859116 :C70283F9: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-08-31 08:15:21.866709 :C7028401:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpus:psdpgi.c:2235 2025-08-31 08:15:21.867087 :C7028402: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-08-31 08:15:21.867127 :C7028405:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:26:209] atxpop:psdpgi.c:2235 2025-08-31 08:15:21.868029 :C702840B: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-08-31 08:15:21.868155 :C7028414: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-08-31 08:15:51.971053 :C7028E51:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:16:21.975557 :C7029807:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:16:21.964 2025-08-31 08:16:21.975559 :C7029808:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21176 pso_num=26 pso_serial#=116 2025-08-31 08:16:21.975679 :C7029809:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21176 osp_idx=24 osp_ver=764606 osp_pg=0 (spawn #543922) 2025-08-31 08:16:21.975680 :C702980A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:16:21.975680 :C702980B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=54 time=1013833268 2025-08-31 08:16:21.975681 :C702980C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:16:21.975681 :C702980D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:16:21.975681 :C702980E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:16:21.975682 :C702980F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:16:21.975682 :C7029810:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:16:21.976173 :C7029811: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-08-31 08:16:52.057145 :C702A1F0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-08-31 08:17:22.066944 :C702ABB0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 08-31 08:17:22.056 2025-08-31 08:17:22.066946 :C702ABB1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11336 pso_num=26 pso_serial#=117 2025-08-31 08:17:22.067081 :C702ABB2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11336 osp_idx=24 osp_ver=764607 osp_pg=0 (spawn #543924) 2025-08-31 08:17:22.067082 :C702ABB3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:17:22.067082 :C702ABB4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=55 time=1013893362 2025-08-31 08:17:22.067083 :C702ABB5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:17:22.067083 :C702ABB6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:17:22.067084 :C702ABB7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:17:22.067084 :C702ABB8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:17:22.067084 :C702ABB9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:17:22.067549 :C702ABBA: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: 11336, J001)