Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250620082826\orcl_m002_15000_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:36467M/63366M, Ph+PgF:42828M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15000, image: ORACLE.EXE (M002) *** 2025-06-20 08:28:26.423 *** SESSION ID:(193.20763) 2025-06-20 08:28:26.423 *** 2025-06-20 08:28:26.423 Process diagnostic dump for ORACLE.EXE (M002), OS id=15000, pid: 24, proc_ser: 108, sid: 193, sess_ser: 20763 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x1b8a, blocks=0x1 wait_id=1263 seq_num=1264 snap_id=1 wait times: snap=0.002127 sec, exc=0.002127 sec, total=0.002127 sec wait times: max=infinite, heur=0.002127 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.000143 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x7b2, blocks=0x1 wait_id=1262 seq_num=1263 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000137 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x1d3a, blocks=0x1 wait_id=1261 seq_num=1262 snap_id=1 wait times: snap=0.000050 sec, exc=0.000050 sec, total=0.000050 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000162 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x1a2a, blocks=0x1 wait_id=1260 seq_num=1261 snap_id=1 wait times: snap=0.000072 sec, exc=0.000072 sec, total=0.000072 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000314 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1c5a, blocks=0x1 wait_id=1259 seq_num=1260 snap_id=1 wait times: snap=0.001819 sec, exc=0.001819 sec, total=0.001819 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000335 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x1baa, blocks=0x1 wait_id=1258 seq_num=1259 snap_id=1 wait times: snap=0.005445 sec, exc=0.005445 sec, total=0.005445 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000136 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1d2a, blocks=0x1 wait_id=1257 seq_num=1258 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000151 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1b2a, blocks=0x1 wait_id=1256 seq_num=1257 snap_id=1 wait times: snap=0.000062 sec, exc=0.000062 sec, total=0.000062 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000185 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x7ca, blocks=0x1 wait_id=1255 seq_num=1256 snap_id=1 wait times: snap=0.000064 sec, exc=0.000064 sec, total=0.000064 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000309 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x7d2, blocks=0x1 wait_id=1254 seq_num=1255 snap_id=1 wait times: snap=0.002501 sec, exc=0.002501 sec, total=0.002501 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000140 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x6b2, blocks=0x1 wait_id=1253 seq_num=1254 snap_id=1 wait times: snap=0.000925 sec, exc=0.000925 sec, total=0.000925 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000132 sec of elapsed time Sampled Session History of session 193 serial 20763 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 08:28:26] idle wait at each sample [session created at: 08:28:26] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-20 08:28:26.423 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15000, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-20 08:14:26.835748 :A7232285:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17896 pso_num=24 pso_serial#=93 2025-06-20 08:14:26.835883 :A7232286:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17896 osp_idx=22 osp_ver=437692 osp_pg=0 (spawn #303909) 2025-06-20 08:14:26.835884 :A7232287: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-20 08:14:26.835884 :A7232288:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=156 time=3383010016 2025-06-20 08:14:26.835885 :A7232289:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:14:26.835885 :A723228A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:14:26.835886 :A723228B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:14:26.835886 :A723228C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:14:26.835887 :A723228D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:14:26.836363 :A723228E: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-20 08:14:56.926614 :A7232C74:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:15:26.935205 :A7233661:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:15:26.924 2025-06-20 08:15:26.935207 :A7233662:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17928 pso_num=24 pso_serial#=94 2025-06-20 08:15:26.935335 :A7233663:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17928 osp_idx=22 osp_ver=437693 osp_pg=0 (spawn #303911) 2025-06-20 08:15:26.935335 :A7233664: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-20 08:15:26.935336 :A7233665:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=157 time=3383070125 2025-06-20 08:15:26.935336 :A7233666:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:15:26.935337 :A7233667:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:15:26.935337 :A7233668:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:15:26.935338 :A7233669:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:15:26.935340 :A723366A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:15:26.935824 :A723366B: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-20 08:15:57.006687 :A7234049:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:16:27.006149 :A7234A1A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:16:26.995 2025-06-20 08:16:27.006151 :A7234A1B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14744 pso_num=24 pso_serial#=95 2025-06-20 08:16:27.006290 :A7234A1C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14744 osp_idx=22 osp_ver=437694 osp_pg=0 (spawn #303913) 2025-06-20 08:16:27.006290 :A7234A1D: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-20 08:16:27.006291 :A7234A1E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=158 time=3383130188 2025-06-20 08:16:27.006291 :A7234A1F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:16:27.006292 :A7234A20:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:16:27.006292 :A7234A21:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:16:27.006292 :A7234A22:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:16:27.006293 :A7234A23:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:16:27.006773 :A7234A24: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-20 08:16:57.047995 :A72354C4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:17:27.100262 :A7235F24:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:17:27.088 2025-06-20 08:17:27.100263 :A7235F25:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12500 pso_num=24 pso_serial#=96 2025-06-20 08:17:27.100396 :A7235F26:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12500 osp_idx=22 osp_ver=437695 osp_pg=0 (spawn #303916) 2025-06-20 08:17:27.100397 :A7235F27: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-20 08:17:27.100397 :A7235F28:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=159 time=3383190282 2025-06-20 08:17:27.100398 :A7235F29:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:17:27.100398 :A7235F2A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:17:27.100398 :A7235F2B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:17:27.100399 :A7235F2C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:17:27.100399 :A7235F2D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:17:27.100878 :A7235F2E: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-20 08:17:57.185024 :A72368E2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:18:27.180194 :A72372B2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:18:27.169 2025-06-20 08:18:27.180196 :A72372B3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17992 pso_num=24 pso_serial#=97 2025-06-20 08:18:27.180335 :A72372B4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17992 osp_idx=22 osp_ver=437696 osp_pg=0 (spawn #303918) 2025-06-20 08:18:27.180335 :A72372B5: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-20 08:18:27.180336 :A72372B6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=160 time=3383250360 2025-06-20 08:18:27.180336 :A72372B7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:18:27.180337 :A72372B8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:18:27.180337 :A72372B9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:18:27.180337 :A72372BA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:18:27.180338 :A72372BB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:18:27.180831 :A72372BC: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-20 08:18:57.260469 :A7237C77:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:19:27.273118 :A7238613:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:19:27.262 2025-06-20 08:19:27.273120 :A7238614:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16120 pso_num=24 pso_serial#=98 2025-06-20 08:19:27.273256 :A7238615:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16120 osp_idx=22 osp_ver=437697 osp_pg=0 (spawn #303920) 2025-06-20 08:19:27.273257 :A7238616: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-20 08:19:27.273257 :A7238617:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=161 time=3383310454 2025-06-20 08:19:27.273258 :A7238618:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:19:27.273258 :A7238619:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:19:27.273259 :A723861A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:19:27.273259 :A723861B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:19:27.273260 :A723861C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:19:27.273729 :A723861D: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-20 08:19:52.307671 :A7238EC7: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-20 08:20:22.396305 :A72398A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:20:27.374936 :A7239A5A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:20:27.364 2025-06-20 08:20:27.374938 :A7239A5B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9008 pso_num=24 pso_serial#=99 2025-06-20 08:20:27.375078 :A7239A5C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9008 osp_idx=22 osp_ver=437698 osp_pg=0 (spawn #303923) 2025-06-20 08:20:27.375079 :A7239A5D: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-20 08:20:27.375079 :A7239A5E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=162 time=3383370563 2025-06-20 08:20:27.375080 :A7239A5F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:20:27.375080 :A7239A60:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:20:27.375081 :A7239A61:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:20:27.375081 :A7239A62:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:20:27.375081 :A7239A63:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:20:27.375552 :A7239A64: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-20 08:20:57.452872 :A723A477:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:21:27.467354 :A723AE73:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:21:27.455 2025-06-20 08:21:27.467356 :A723AE74:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16444 pso_num=24 pso_serial#=100 2025-06-20 08:21:27.467477 :A723AE75:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16444 osp_idx=22 osp_ver=437699 osp_pg=0 (spawn #303925) 2025-06-20 08:21:27.467478 :A723AE76: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-20 08:21:27.467479 :A723AE77:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=163 time=3383430657 2025-06-20 08:21:27.467479 :A723AE78:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:21:27.467480 :A723AE79:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:21:27.467480 :A723AE7A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:21:27.467480 :A723AE7B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:21:27.467480 :A723AE7C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:21:27.468012 :A723AE7D: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-20 08:21:57.529458 :A723B8B5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:22:27.539532 :A723C297:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:22:27.528 2025-06-20 08:22:27.539534 :A723C298:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17916 pso_num=24 pso_serial#=101 2025-06-20 08:22:27.539674 :A723C299:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17916 osp_idx=22 osp_ver=437700 osp_pg=0 (spawn #303928) 2025-06-20 08:22:27.539675 :A723C29A: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-20 08:22:27.539675 :A723C29B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=164 time=3383490719 2025-06-20 08:22:27.539676 :A723C29C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:22:27.539676 :A723C29D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:22:27.539676 :A723C29E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:22:27.539677 :A723C29F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:22:27.539677 :A723C2A0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:22:27.540174 :A723C2A1: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-20 08:22:57.576162 :A723CC88:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:23:27.615546 :A723D659:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:23:27.604 2025-06-20 08:23:27.615548 :A723D65A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8164 pso_num=24 pso_serial#=102 2025-06-20 08:23:27.615684 :A723D65B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8164 osp_idx=22 osp_ver=437701 osp_pg=0 (spawn #303930) 2025-06-20 08:23:27.615685 :A723D65C: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-20 08:23:27.615685 :A723D65D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=165 time=3383550797 2025-06-20 08:23:27.615685 :A723D65E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:23:27.615686 :A723D65F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:23:27.615686 :A723D660:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:23:27.615686 :A723D661:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:23:27.615686 :A723D662:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:23:27.616154 :A723D663: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-20 08:23:57.682804 :A723E02A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:24:27.702072 :A723EA08:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:24:27.691 2025-06-20 08:24:27.702074 :A723EA09:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14972 pso_num=24 pso_serial#=103 2025-06-20 08:24:27.702213 :A723EA0A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14972 osp_idx=22 osp_ver=437702 osp_pg=0 (spawn #303932) 2025-06-20 08:24:27.702214 :A723EA0B: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-20 08:24:27.702214 :A723EA0C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=166 time=3383610891 2025-06-20 08:24:27.702215 :A723EA0D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:24:27.702215 :A723EA0E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:24:27.702216 :A723EA0F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:24:27.702216 :A723EA10:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:24:27.702216 :A723EA11:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:24:27.702712 :A723EA12: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-20 08:24:57.783789 :A723F3F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:25:27.781356 :A723FDC1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:25:27.770 2025-06-20 08:25:27.781358 :A723FDC2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16168 pso_num=24 pso_serial#=104 2025-06-20 08:25:27.781489 :A723FDC3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16168 osp_idx=22 osp_ver=437703 osp_pg=0 (spawn #303934) 2025-06-20 08:25:27.781490 :A723FDC4: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-20 08:25:27.781490 :A723FDC5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=167 time=3383670969 2025-06-20 08:25:27.781491 :A723FDC6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:25:27.781491 :A723FDC7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:25:27.781491 :A723FDC8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:25:27.781491 :A723FDC9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:25:27.781492 :A723FDCA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:25:27.781980 :A723FDCB: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-20 08:25:57.853563 :A7240836:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:26:27.868694 :A7241232:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:26:27.858 2025-06-20 08:26:27.868696 :A7241233:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16796 pso_num=24 pso_serial#=105 2025-06-20 08:26:27.868833 :A7241234:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16796 osp_idx=22 osp_ver=437704 osp_pg=0 (spawn #303936) 2025-06-20 08:26:27.868834 :A7241235: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-20 08:26:27.868834 :A7241236:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=168 time=3383731047 2025-06-20 08:26:27.868834 :A7241237:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:26:27.868835 :A7241238:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:26:27.868835 :A7241239:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:26:27.868835 :A724123A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:26:27.868836 :A724123B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:26:27.869318 :A724123C: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-20 08:26:57.939795 :A7241D17:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:27:28.012948 :A7242705:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:27:28.012 2025-06-20 08:27:28.012951 :A7242706:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10448 pso_num=24 pso_serial#=106 2025-06-20 08:27:28.013099 :A7242707:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10448 osp_idx=22 osp_ver=437705 osp_pg=0 (spawn #303938) 2025-06-20 08:27:28.013100 :A7242708: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-20 08:27:28.013100 :A7242709:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=169 time=3383791188 2025-06-20 08:27:28.013101 :A724270A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:27:28.013101 :A724270B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:27:28.013101 :A724270C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:27:28.013102 :A724270D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:27:28.013102 :A724270E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:27:28.013619 :A724270F: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-20 08:27:58.142963 :A724309A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:28:23.898556 :A72473AF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:28:23.889 2025-06-20 08:28:23.898558 :A72473B0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17756 pso_num=24 pso_serial#=107 2025-06-20 08:28:23.898715 :A72473B1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17756 osp_idx=22 osp_ver=437706 osp_pg=0 (spawn #303940) 2025-06-20 08:28:23.898716 :A72473B2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 08:28:23.898716 :A72473B3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=170 time=3383847079 2025-06-20 08:28:23.898717 :A72473B4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:28:23.898717 :A72473B5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:28:23.898717 :A72473B6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:28:23.898717 :A72473B7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:28:23.898718 :A72473B8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:28:23.900025 :A72473B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 08:28:23.900088 :A72473BF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-20 08:28:23.991528 :A72473F1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-20 08:28:26.172286 :A72474AE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-20 08:28:26.158 2025-06-20 08:28:26.172288 :A72474AF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15000 pso_num=24 pso_serial#=108 2025-06-20 08:28:26.172414 :A72474B0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15000 osp_idx=22 osp_ver=437707 osp_pg=0 (spawn #303942) 2025-06-20 08:28:26.172414 :A72474B1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-20 08:28:26.172415 :A72474B2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=171 time=3383849360 2025-06-20 08:28:26.172415 :A72474B3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-20 08:28:26.172415 :A72474B4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-20 08:28:26.172416 :A72474B5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-20 08:28:26.172416 :A72474B6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-20 08:28:26.172416 :A72474B7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-20 08:28:26.172904 :A72474B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-20 08:28:26.172949 :A72474BE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 15000, M002)