Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250717081613\orcl_m002_18496_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:36259M/63366M, Ph+PgF:42193M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 18496, image: ORACLE.EXE (M002) *** 2025-07-17 08:16:13.497 *** SESSION ID:(193.27003) 2025-07-17 08:16:13.497 *** 2025-07-17 08:16:13.497 Process diagnostic dump for ORACLE.EXE (M002), OS id=18496, pid: 24, proc_ser: 130, sid: 193, sess_ser: 27003 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x1e6a, blocks=0x1 wait_id=1372 seq_num=1373 snap_id=1 wait times: snap=0.000119 sec, exc=0.000119 sec, total=0.000119 sec wait times: max=infinite, heur=0.000119 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.000622 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x1de2, blocks=0x1 wait_id=1371 seq_num=1372 snap_id=1 wait times: snap=0.000058 sec, exc=0.000058 sec, total=0.000058 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000236 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x1dd2, blocks=0x1 wait_id=1370 seq_num=1371 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.000132 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x1dca, blocks=0x1 wait_id=1369 seq_num=1370 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000140 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x1dba, blocks=0x1 wait_id=1368 seq_num=1369 snap_id=1 wait times: snap=0.000048 sec, exc=0.000048 sec, total=0.000048 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000133 sec of elapsed time 4: waited for 'db file sequential read' file#=0x2, block#=0x1da2, blocks=0x1 wait_id=1367 seq_num=1368 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000134 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x1d9a, blocks=0x1 wait_id=1366 seq_num=1367 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000142 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x1d82, blocks=0x1 wait_id=1365 seq_num=1366 snap_id=1 wait times: snap=0.000047 sec, exc=0.000047 sec, total=0.000047 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000139 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x1d72, blocks=0x1 wait_id=1364 seq_num=1365 snap_id=1 wait times: snap=0.000055 sec, exc=0.000055 sec, total=0.000055 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000148 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x1d6a, blocks=0x1 wait_id=1363 seq_num=1364 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.000154 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x1d62, blocks=0x1 wait_id=1362 seq_num=1363 snap_id=1 wait times: snap=0.000086 sec, exc=0.000086 sec, total=0.000086 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000343 sec of elapsed time Sampled Session History of session 193 serial 27003 --------------------------------------------------- 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:16:13] waited for 'db file sequential read', seq_num: 1259 p1: 'file#'=0x2 p2: 'block#'=0xec2 p3: 'blocks'=0x1 time_waited: >= 0 sec (still in wait) [1 sample, 08:16:12] idle wait at each sample [session created at: 08:16:13] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'db file sequential read' [1 sample, 08:16:13 ] time_waited: >= 0 sec (still in wait) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-07-17 08:16:13.513 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 18496, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-17 08:02:34.688756 :B31DB241:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:02:34.688756 :B31DB242:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:02:34.689236 :B31DB243:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:03:04.771793 :B31DBC4D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:03:34.799966 :B31DC62B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:03:34.788 2025-07-17 08:03:34.799967 :B31DC62C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19336 pso_num=24 pso_serial#=115 2025-07-17 08:03:34.800103 :B31DC62D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19336 osp_idx=22 osp_ver=579995 osp_pg=0 (spawn #393903) 2025-07-17 08:03:34.800104 :B31DC62E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:03:34.800104 :B31DC62F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=202 time=1420190689 2025-07-17 08:03:34.800104 :B31DC630:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:03:34.800105 :B31DC631:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:03:34.800105 :B31DC632:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:03:34.800106 :B31DC633:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:03:34.800106 :B31DC634:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:03:34.800618 :B31DC635:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:04:04.915524 :B31DD02A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:04:34.904626 :B31DDA0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:04:34.893 2025-07-17 08:04:34.904628 :B31DDA0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15096 pso_num=24 pso_serial#=116 2025-07-17 08:04:34.904747 :B31DDA10:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15096 osp_idx=22 osp_ver=579996 osp_pg=0 (spawn #393905) 2025-07-17 08:04:34.904748 :B31DDA11:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:04:34.904749 :B31DDA12:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=203 time=1420250798 2025-07-17 08:04:34.904750 :B31DDA13:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:04:34.904751 :B31DDA14:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:04:34.904751 :B31DDA15:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:04:34.904751 :B31DDA16:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:04:34.904752 :B31DDA17:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:04:34.905302 :B31DDA18:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:05:04.982812 :B31DE4E8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:05:05.038248 :B31DE4EB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:05:05.030 2025-07-17 08:05:05.038250 :B31DE4EC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=636 pso_num=24 pso_serial#=117 2025-07-17 08:05:05.038389 :B31DE4ED:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=636 (legacy spawn) 2025-07-17 08:05:05.044872 :B31DE4EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-17 08:05:05.045051 :B31DE4F3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-17 08:05:05.045847 :B31DE4F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:05:34.981209 :B31DF401:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:05:34.970 2025-07-17 08:05:34.981210 :B31DF402:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18956 pso_num=24 pso_serial#=118 2025-07-17 08:05:34.981345 :B31DF403:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18956 osp_idx=22 osp_ver=579998 osp_pg=0 (spawn #393908) 2025-07-17 08:05:34.981346 :B31DF404:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:05:34.981346 :B31DF405:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=204 time=1420310876 2025-07-17 08:05:34.981347 :B31DF406:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:05:34.981347 :B31DF407:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:05:34.981348 :B31DF408:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:05:34.981348 :B31DF409:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:05:34.981349 :B31DF40A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:05:34.981850 :B31DF40B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:06:05.077564 :B31DFF26:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:06:35.059774 :B31E0904:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:06:35.048 2025-07-17 08:06:35.059776 :B31E0905:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18968 pso_num=24 pso_serial#=119 2025-07-17 08:06:35.059914 :B31E0906:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18968 osp_idx=22 osp_ver=579999 osp_pg=0 (spawn #393910) 2025-07-17 08:06:35.059915 :B31E0907:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:06:35.059915 :B31E0908:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=205 time=1420370954 2025-07-17 08:06:35.059916 :B31E0909:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:06:35.059917 :B31E090A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:06:35.059917 :B31E090B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:06:35.059918 :B31E090C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:06:35.059918 :B31E090D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:06:35.060416 :B31E090E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:07:05.143151 :B31E12CD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:07:35.188074 :B31E1F75:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:07:35.187 2025-07-17 08:07:35.188077 :B31E1F76:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16236 pso_num=24 pso_serial#=120 2025-07-17 08:07:35.188199 :B31E1F77:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16236 osp_idx=22 osp_ver=580000 osp_pg=0 (spawn #393912) 2025-07-17 08:07:35.188200 :B31E1F78:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:07:35.188201 :B31E1F79:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=206 time=1420431064 2025-07-17 08:07:35.188201 :B31E1F7A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:07:35.188202 :B31E1F7B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:07:35.188202 :B31E1F7C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:07:35.188202 :B31E1F7D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:07:35.188203 :B31E1F7E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:07:35.188713 :B31E1F7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:08:05.318574 :B31E29AB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:08:35.327714 :B31E3373:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:08:35.316 2025-07-17 08:08:35.327716 :B31E3374:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18340 pso_num=24 pso_serial#=121 2025-07-17 08:08:35.327849 :B31E3375:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18340 osp_idx=22 osp_ver=580001 osp_pg=0 (spawn #393914) 2025-07-17 08:08:35.327850 :B31E3376:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:08:35.327850 :B31E3377:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=207 time=1420491220 2025-07-17 08:08:35.327851 :B31E3378:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:08:35.327851 :B31E3379:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:08:35.327851 :B31E337A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:08:35.327852 :B31E337B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:08:35.327852 :B31E337C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:08:35.328323 :B31E337D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:09:00.349490 :B31E3B87:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-17 08:09:30.463769 :B31E455F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:09:35.417445 :B31E4711:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:09:35.406 2025-07-17 08:09:35.417447 :B31E4712:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18960 pso_num=24 pso_serial#=122 2025-07-17 08:09:35.417582 :B31E4713:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18960 osp_idx=22 osp_ver=580002 osp_pg=0 (spawn #393916) 2025-07-17 08:09:35.417583 :B31E4714:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:09:35.417584 :B31E4715:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=208 time=1420551298 2025-07-17 08:09:35.417584 :B31E4716:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:09:35.417584 :B31E4717:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:09:35.417584 :B31E4718:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:09:35.417585 :B31E4719:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:09:35.417585 :B31E471A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:09:35.418054 :B31E471B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:10:05.536179 :B31E50FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:10:35.503387 :B31E5B17:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:10:35.493 2025-07-17 08:10:35.503389 :B31E5B18:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18028 pso_num=24 pso_serial#=123 2025-07-17 08:10:35.503508 :B31E5B19:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18028 osp_idx=22 osp_ver=580003 osp_pg=0 (spawn #393919) 2025-07-17 08:10:35.503509 :B31E5B1A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:10:35.503510 :B31E5B1B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=209 time=1420611392 2025-07-17 08:10:35.503510 :B31E5B1C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:10:35.503510 :B31E5B1D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:10:35.503511 :B31E5B1E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:10:35.503511 :B31E5B1F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:10:35.503511 :B31E5B20:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:10:35.504016 :B31E5B21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:11:05.600679 :B31E64F2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:11:35.611675 :B31E6E96:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:11:35.600 2025-07-17 08:11:35.611676 :B31E6E97:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15876 pso_num=24 pso_serial#=124 2025-07-17 08:11:35.611797 :B31E6E98:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15876 osp_idx=22 osp_ver=580004 osp_pg=0 (spawn #393921) 2025-07-17 08:11:35.611798 :B31E6E99:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:11:35.611798 :B31E6E9A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=210 time=1420671501 2025-07-17 08:11:35.611799 :B31E6E9B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:11:35.611799 :B31E6E9C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:11:35.611799 :B31E6E9D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:11:35.611800 :B31E6E9E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:11:35.611800 :B31E6E9F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:11:35.612338 :B31E6EA0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:12:05.731456 :B31E787E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:12:35.696593 :B31E829D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:12:35.686 2025-07-17 08:12:35.696595 :B31E829E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19208 pso_num=24 pso_serial#=125 2025-07-17 08:12:35.696723 :B31E829F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19208 osp_idx=22 osp_ver=580005 osp_pg=0 (spawn #393923) 2025-07-17 08:12:35.696724 :B31E82A0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:12:35.696724 :B31E82A1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=211 time=1420731579 2025-07-17 08:12:35.696725 :B31E82A2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:12:35.696725 :B31E82A3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:12:35.696725 :B31E82A4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:12:35.696726 :B31E82A5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:12:35.696726 :B31E82A6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:12:35.697206 :B31E82A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:13:05.812428 :B31E8CAB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:13:35.804004 :B31E966E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:13:35.793 2025-07-17 08:13:35.804006 :B31E966F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16700 pso_num=24 pso_serial#=126 2025-07-17 08:13:35.804130 :B31E9670:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16700 osp_idx=22 osp_ver=580006 osp_pg=0 (spawn #393926) 2025-07-17 08:13:35.804130 :B31E9671:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:13:35.804130 :B31E9672:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=212 time=1420791689 2025-07-17 08:13:35.804130 :B31E9673:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:13:35.804131 :B31E9674:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:13:35.804131 :B31E9675:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:13:35.804132 :B31E9676:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:13:35.804132 :B31E9677:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:13:35.804626 :B31E9678:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:14:05.874069 :B31EA044:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:14:35.869087 :B31EA9EB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:14:35.858 2025-07-17 08:14:35.869089 :B31EA9EC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18456 pso_num=24 pso_serial#=127 2025-07-17 08:14:35.869233 :B31EA9ED:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18456 osp_idx=22 osp_ver=580007 osp_pg=0 (spawn #393928) 2025-07-17 08:14:35.869234 :B31EA9EE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:14:35.869234 :B31EA9EF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=213 time=1420851751 2025-07-17 08:14:35.869235 :B31EA9F0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:14:35.869236 :B31EA9F1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:14:35.869236 :B31EA9F2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:14:35.869237 :B31EA9F3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:14:35.869237 :B31EA9F4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:14:35.869711 :B31EA9F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:15:05.952410 :B31EB4CF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:15:35.980883 :B31EBEF4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:15:35.970 2025-07-17 08:15:35.980885 :B31EBEF5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19060 pso_num=24 pso_serial#=128 2025-07-17 08:15:35.981013 :B31EBEF6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19060 osp_idx=22 osp_ver=580008 osp_pg=0 (spawn #393931) 2025-07-17 08:15:35.981014 :B31EBEF7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:15:35.981014 :B31EBEF8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=214 time=1420911876 2025-07-17 08:15:35.981014 :B31EBEF9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:15:35.981015 :B31EBEFA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:15:35.981015 :B31EBEFB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:15:35.981015 :B31EBEFC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:15:35.981016 :B31EBEFD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:15:35.981489 :B31EBEFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:16:06.081268 :B31EC8D8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:16:10.879061 :B31F04B2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:16:10.868 2025-07-17 08:16:10.879063 :B31F04B3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10520 pso_num=24 pso_serial#=129 2025-07-17 08:16:10.879214 :B31F04B4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10520 osp_idx=22 osp_ver=580009 osp_pg=0 (spawn #393933) 2025-07-17 08:16:10.879215 :B31F04B5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:16:10.879215 :B31F04B6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=215 time=1420946767 2025-07-17 08:16:10.879216 :B31F04B7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:16:10.879216 :B31F04B8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:16:10.879216 :B31F04B9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:16:10.879217 :B31F04BA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:16:10.879217 :B31F04BB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:16:10.880472 :B31F04BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:16:10.880526 :B31F04C2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-17 08:16:11.000585 :B31F04FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-17 08:16:13.114110 :B31F05DB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-17 08:16:13.107 2025-07-17 08:16:13.114113 :B31F05DC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18496 pso_num=24 pso_serial#=130 2025-07-17 08:16:13.114251 :B31F05DD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18496 osp_idx=22 osp_ver=580010 osp_pg=0 (spawn #393935) 2025-07-17 08:16:13.114252 :B31F05DE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-17 08:16:13.114253 :B31F05DF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=216 time=1420949001 2025-07-17 08:16:13.114253 :B31F05E0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-17 08:16:13.114254 :B31F05E1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-17 08:16:13.114254 :B31F05E2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-17 08:16:13.114254 :B31F05E3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-17 08:16:13.114255 :B31F05E4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-17 08:16:13.114785 :B31F05E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-17 08:16:13.114825 :B31F05EB: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: 18496, M002)