Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250821220008\orcl_vkrm_22248_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:35295M/63366M, Ph+PgF:40459M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 22248, image: ORACLE.EXE (VKRM) *** 2025-08-21 22:00:08.730 *** SESSION ID:(193.7733) 2025-08-21 22:00:08.730 *** 2025-08-21 22:00:08.730 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=22248, pid: 24, proc_ser: 114, sid: 193, sess_ser: 7733 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=8.704174 sec, exc=8.704174 sec, total=8.704174 sec wait times: max=infinite, heur=8.704174 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000000 sec since current wait Sampled Session History of session 193 serial 7733 --------------------------------------------------- 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, 22:00:00 - 22:00:08] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-21 22:00:08.730 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 22248, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-21 21:48:16.761431 :C2DB6B40:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:48:16.761431 :C2DB6B41:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=254 time=198598768 2025-08-21 21:48:16.761432 :C2DB6B42:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:48:16.761432 :C2DB6B43:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:48:16.761432 :C2DB6B44:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:48:16.761433 :C2DB6B45:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:48:16.761433 :C2DB6B46:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:48:16.761922 :C2DB6B47: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-08-21 21:48:46.868025 :C2DB750F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:49:16.839787 :C2DB7EF4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:49:16.828 2025-08-21 21:49:16.839790 :C2DB7EF5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4764 pso_num=24 pso_serial#=98 2025-08-21 21:49:16.839956 :C2DB7EF6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4764 osp_idx=22 osp_ver=768168 osp_pg=0 (spawn #512452) 2025-08-21 21:49:16.839956 :C2DB7EF7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:49:16.839956 :C2DB7EF8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=255 time=198658846 2025-08-21 21:49:16.839957 :C2DB7EF9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:49:16.839957 :C2DB7EFA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:49:16.839957 :C2DB7EFB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:49:16.839958 :C2DB7EFC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:49:16.839958 :C2DB7EFD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:49:16.840472 :C2DB7EFE: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-08-21 21:49:46.927538 :C2DB8971:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:50:16.927768 :C2DB937D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:50:16.917 2025-08-21 21:50:16.927770 :C2DB937E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19812 pso_num=24 pso_serial#=99 2025-08-21 21:50:16.927887 :C2DB937F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19812 osp_idx=22 osp_ver=768169 osp_pg=0 (spawn #512455) 2025-08-21 21:50:16.927888 :C2DB9380:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:50:16.927889 :C2DB9381:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=0 time=198718924 2025-08-21 21:50:16.927889 :C2DB9382:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:50:16.927889 :C2DB9383:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:50:16.927890 :C2DB9384:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:50:16.927890 :C2DB9385:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:50:16.927890 :C2DB9386:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:50:16.928456 :C2DB9387: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-08-21 21:50:46.983674 :C2DB9D0F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:51:16.980307 :C2DBA70C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:51:16.968 2025-08-21 21:51:16.980309 :C2DBA70D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7196 pso_num=24 pso_serial#=100 2025-08-21 21:51:16.980442 :C2DBA70E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7196 osp_idx=22 osp_ver=768170 osp_pg=0 (spawn #512457) 2025-08-21 21:51:16.980443 :C2DBA70F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:51:16.980443 :C2DBA710:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=1 time=198778987 2025-08-21 21:51:16.980444 :C2DBA711:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:51:16.980444 :C2DBA712:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:51:16.980445 :C2DBA713:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:51:16.980445 :C2DBA714:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:51:16.980445 :C2DBA715:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:51:16.980934 :C2DBA716: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-08-21 21:51:47.021379 :C2DBB16B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:52:17.058029 :C2DBBB13:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:52:17.047 2025-08-21 21:52:17.058031 :C2DBBB14:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20172 pso_num=24 pso_serial#=101 2025-08-21 21:52:17.058164 :C2DBBB15:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20172 osp_idx=22 osp_ver=768171 osp_pg=0 (spawn #512459) 2025-08-21 21:52:17.058165 :C2DBBB16:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:52:17.058166 :C2DBBB17:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=2 time=198839065 2025-08-21 21:52:17.058166 :C2DBBB18:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:52:17.058167 :C2DBBB19:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:52:17.058167 :C2DBBB1A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:52:17.058167 :C2DBBB1B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:52:17.058168 :C2DBBB1C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:52:17.058642 :C2DBBB1D: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-08-21 21:52:47.132480 :C2DBC4EF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:53:17.139077 :C2DBCEC1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:53:17.127 2025-08-21 21:53:17.139079 :C2DBCEC2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19824 pso_num=24 pso_serial#=102 2025-08-21 21:53:17.139256 :C2DBCEC3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19824 osp_idx=22 osp_ver=768172 osp_pg=0 (spawn #512461) 2025-08-21 21:53:17.139257 :C2DBCEC4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:53:17.139258 :C2DBCEC5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=3 time=198899143 2025-08-21 21:53:17.139258 :C2DBCEC6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:53:17.139259 :C2DBCEC7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:53:17.139259 :C2DBCEC8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:53:17.139260 :C2DBCEC9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:53:17.139261 :C2DBCECA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:53:17.139770 :C2DBCECB: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-08-21 21:53:47.207278 :C2DBD889:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:54:17.226019 :C2DBE25F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:54:17.215 2025-08-21 21:54:17.226020 :C2DBE260:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19984 pso_num=24 pso_serial#=103 2025-08-21 21:54:17.226138 :C2DBE261:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19984 osp_idx=22 osp_ver=768173 osp_pg=0 (spawn #512463) 2025-08-21 21:54:17.226139 :C2DBE262:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:54:17.226139 :C2DBE263:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=4 time=198959237 2025-08-21 21:54:17.226140 :C2DBE264:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:54:17.226140 :C2DBE265:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:54:17.226140 :C2DBE266:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:54:17.226141 :C2DBE267:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:54:17.226141 :C2DBE268:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:54:17.226620 :C2DBE269: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-08-21 21:54:32.248272 :C2DBE77F: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-08-21 21:55:02.331547 :C2DBF117:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:55:17.363631 :C2DBF69C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:55:17.352 2025-08-21 21:55:17.363632 :C2DBF69D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18352 pso_num=24 pso_serial#=104 2025-08-21 21:55:17.363762 :C2DBF69E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18352 osp_idx=22 osp_ver=768174 osp_pg=0 (spawn #512465) 2025-08-21 21:55:17.363763 :C2DBF69F:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:55:17.363764 :C2DBF6A0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=5 time=199019362 2025-08-21 21:55:17.363764 :C2DBF6A1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:55:17.363764 :C2DBF6A2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:55:17.363765 :C2DBF6A3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:55:17.363765 :C2DBF6A4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:55:17.363766 :C2DBF6A5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:55:17.364235 :C2DBF6A6: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-08-21 21:55:47.452169 :C2DC00C2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:56:17.469681 :C2DC0A90:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:56:17.458 2025-08-21 21:56:17.469683 :C2DC0A91:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20640 pso_num=24 pso_serial#=105 2025-08-21 21:56:17.469833 :C2DC0A92:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20640 osp_idx=22 osp_ver=768175 osp_pg=0 (spawn #512468) 2025-08-21 21:56:17.469834 :C2DC0A93:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:56:17.469834 :C2DC0A94:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=6 time=199079471 2025-08-21 21:56:17.469834 :C2DC0A95:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:56:17.469835 :C2DC0A96:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:56:17.469836 :C2DC0A97:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:56:17.469836 :C2DC0A98:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:56:17.469837 :C2DC0A99:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:56:17.470363 :C2DC0A9A: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-08-21 21:56:47.539894 :C2DC1541:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:57:17.534839 :C2DC1EFD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:57:17.523 2025-08-21 21:57:17.534841 :C2DC1EFE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20544 pso_num=24 pso_serial#=106 2025-08-21 21:57:17.534959 :C2DC1EFF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20544 osp_idx=22 osp_ver=768176 osp_pg=0 (spawn #512470) 2025-08-21 21:57:17.534959 :C2DC1F00:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:57:17.534960 :C2DC1F01:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=7 time=199139533 2025-08-21 21:57:17.534960 :C2DC1F02:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:57:17.534961 :C2DC1F03:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:57:17.534961 :C2DC1F04:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:57:17.534961 :C2DC1F05:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:57:17.534962 :C2DC1F06:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:57:17.535434 :C2DC1F07: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-08-21 21:57:47.565440 :C2DC2928:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:58:17.613835 :C2DC32FA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:58:17.602 2025-08-21 21:58:17.613837 :C2DC32FB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22408 pso_num=24 pso_serial#=107 2025-08-21 21:58:17.613952 :C2DC32FC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22408 osp_idx=22 osp_ver=768177 osp_pg=0 (spawn #512472) 2025-08-21 21:58:17.613952 :C2DC32FD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:58:17.613953 :C2DC32FE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=8 time=199199612 2025-08-21 21:58:17.613953 :C2DC32FF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:58:17.613953 :C2DC3300:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:58:17.613954 :C2DC3301:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:58:17.613954 :C2DC3302:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:58:17.613954 :C2DC3303:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:58:17.614489 :C2DC3304: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-08-21 21:58:47.699453 :C2DC3CA9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:59:17.700884 :C2DC45F3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:59:17.695 2025-08-21 21:59:17.700886 :C2DC45F4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11772 pso_num=24 pso_serial#=108 2025-08-21 21:59:17.701034 :C2DC45F5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11772 osp_idx=22 osp_ver=768178 osp_pg=0 (spawn #512473) 2025-08-21 21:59:17.701035 :C2DC45F6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 21:59:17.701035 :C2DC45F7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=9 time=199259705 2025-08-21 21:59:17.701036 :C2DC45F8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 21:59:17.701037 :C2DC45F9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 21:59:17.701037 :C2DC45FA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 21:59:17.701037 :C2DC45FB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 21:59:17.701038 :C2DC45FC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 21:59:17.701536 :C2DC4603: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-08-21 21:59:17.702096 :C2DC461C: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-08-21 21:59:17.710149 :C2DC463B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-21 21:59:17.710525 :C2DC463C: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-08-21 21:59:17.710558 :C2DC463D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-21 21:59:17.711430 :C2DC4645: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-08-21 21:59:17.711571 :C2DC464E: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-08-21 21:59:47.794400 :C2DC50C0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:59:53.080207 :C2DC5250:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:59:53.072 2025-08-21 21:59:53.080209 :C2DC5251:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20856 pso_num=24 pso_serial#=109 2025-08-21 21:59:53.080348 :C2DC5252:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20856 (legacy spawn) 2025-08-21 21:59:53.104539 :C2DC525E: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-08-21 21:59:53.104720 :C2DC5263: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-08-21 21:59:53.105651 :C2DC5268:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:59:53.898586 :C2DC52AD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:59:53.884 2025-08-21 21:59:53.898588 :C2DC52AE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8988 pso_num=24 pso_serial#=110 2025-08-21 21:59:53.898728 :C2DC52AF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8988 (legacy spawn) 2025-08-21 21:59:53.905177 :C2DC52B0: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-08-21 21:59:53.905345 :C2DC52B6: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-08-21 21:59:53.906167 :C2DC52BA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:59:53.914909 :C2DC52BD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:59:53.900 2025-08-21 21:59:53.914911 :C2DC52BE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22068 pso_num=24 pso_serial#=111 2025-08-21 21:59:53.915053 :C2DC52BF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22068 (legacy spawn) 2025-08-21 21:59:53.921581 :C2DC52C0: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-08-21 21:59:53.921743 :C2DC52C9: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-08-21 21:59:53.922548 :C2DC52CA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:59:57.181672 :C2DC53DD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:59:57.169 2025-08-21 21:59:57.181674 :C2DC53DE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22128 pso_num=24 pso_serial#=112 2025-08-21 21:59:57.181817 :C2DC53DF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22128 (legacy spawn) 2025-08-21 21:59:57.188319 :C2DC53E0: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-08-21 21:59:57.188492 :C2DC53E9: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-08-21 21:59:57.189294 :C2DC53EA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 21:59:57.208343 :C2DC53ED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 21:59:57.200 2025-08-21 21:59:57.208345 :C2DC53EE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3560 pso_num=24 pso_serial#=113 2025-08-21 21:59:57.208483 :C2DC53EF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3560 (legacy spawn) 2025-08-21 21:59:57.214952 :C2DC53F0: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-08-21 21:59:57.215122 :C2DC53F9: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-08-21 21:59:57.215965 :C2DC53FA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-21 22:00:00.025214 :C2DC550A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-21 22:00:00.018 2025-08-21 22:00:00.025216 :C2DC550B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22248 pso_num=24 pso_serial#=114 2025-08-21 22:00:00.025326 :C2DC550C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22248 osp_idx=22 osp_ver=768184 osp_pg=0 (spawn #512476) 2025-08-21 22:00:00.025327 :C2DC550D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-21 22:00:00.025327 :C2DC550E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=10 time=199302033 2025-08-21 22:00:00.025327 :C2DC550F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-21 22:00:00.025328 :C2DC5510:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-21 22:00:00.025328 :C2DC5511:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-21 22:00:00.025328 :C2DC5512:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-21 22:00:00.025329 :C2DC5513:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-21 22:00:00.025401 :C2DC5514:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-21 22:00:00.025408 :C2DC5515:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:24:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 22248, VKRM)