Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250430221731\orcl_m002_10684_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:37663M/63366M, Ph+PgF:45246M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 10684, image: ORACLE.EXE (M002) *** 2025-04-30 22:17:31.787 *** SESSION ID:(193.4939) 2025-04-30 22:17:31.787 *** 2025-04-30 22:17:31.787 Process diagnostic dump for ORACLE.EXE (M002), OS id=10684, pid: 24, proc_ser: 245, sid: 193, sess_ser: 4939 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'db file sequential read' file#=0x2, block#=0x3a22, blocks=0x1 wait_id=1518 seq_num=1519 snap_id=1 wait times: snap=0.000456 sec, exc=0.000456 sec, total=0.000456 sec wait times: max=infinite, heur=0.000456 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.000281 sec since current wait 0: waited for 'db file sequential read' file#=0x2, block#=0x39fa, blocks=0x1 wait_id=1517 seq_num=1518 snap_id=1 wait times: snap=0.001173 sec, exc=0.001173 sec, total=0.001173 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000339 sec of elapsed time 1: waited for 'db file sequential read' file#=0x2, block#=0x39da, blocks=0x1 wait_id=1516 seq_num=1517 snap_id=1 wait times: snap=0.006301 sec, exc=0.006301 sec, total=0.006301 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000199 sec of elapsed time 2: waited for 'db file sequential read' file#=0x2, block#=0x39ca, blocks=0x1 wait_id=1515 seq_num=1516 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.000135 sec of elapsed time 3: waited for 'db file sequential read' file#=0x2, block#=0x39ba, blocks=0x1 wait_id=1514 seq_num=1515 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 4: waited for 'db file sequential read' file#=0x2, block#=0x3972, blocks=0x1 wait_id=1513 seq_num=1514 snap_id=1 wait times: snap=0.000097 sec, exc=0.000097 sec, total=0.000097 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000142 sec of elapsed time 5: waited for 'db file sequential read' file#=0x2, block#=0x396a, blocks=0x1 wait_id=1512 seq_num=1513 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.000136 sec of elapsed time 6: waited for 'db file sequential read' file#=0x2, block#=0x3962, blocks=0x1 wait_id=1511 seq_num=1512 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.000156 sec of elapsed time 7: waited for 'db file sequential read' file#=0x2, block#=0x3952, blocks=0x1 wait_id=1510 seq_num=1511 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.000287 sec of elapsed time 8: waited for 'db file sequential read' file#=0x2, block#=0x394a, blocks=0x1 wait_id=1509 seq_num=1510 snap_id=1 wait times: snap=0.001335 sec, exc=0.001335 sec, total=0.001335 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000379 sec of elapsed time 9: waited for 'db file sequential read' file#=0x2, block#=0x391a, blocks=0x1 wait_id=1508 seq_num=1509 snap_id=1 wait times: snap=0.003700 sec, exc=0.003700 sec, total=0.003700 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000447 sec of elapsed time Sampled Session History of session 193 serial 4939 --------------------------------------------------- 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, 22:17:31] idle wait at each sample [session created at: 22:17:31] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-30 22:17:31.787 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 10684, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-30 22:04:33.907256 :90F779BB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:04:33.907257 :90F779BC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:04:33.907257 :90F779BD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:04:33.907257 :90F779BE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:04:33.907257 :90F779BF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:04:33.907745 :90F779C0: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-04-30 22:05:03.961375 :90F783F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:05:33.991721 :90F7917F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:05:33.980 2025-04-30 22:05:33.991724 :90F79180:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8880 pso_num=24 pso_serial#=232 2025-04-30 22:05:33.991864 :90F79181:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8880 osp_idx=22 osp_ver=200661 osp_pg=0 (spawn #135180) 2025-04-30 22:05:33.991865 :90F79182:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:05:33.991866 :90F79183:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=193 time=3321523468 2025-04-30 22:05:33.991866 :90F79184:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:05:33.991866 :90F79185:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:05:33.991867 :90F79186:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:05:33.991867 :90F79187:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:05:33.991867 :90F79188:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:05:33.992338 :90F79189: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-04-30 22:06:04.038111 :90F7A187:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:06:34.051854 :90F7AAE4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:06:34.041 2025-04-30 22:06:34.051857 :90F7AAE5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10396 pso_num=24 pso_serial#=233 2025-04-30 22:06:34.051990 :90F7AAE6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10396 osp_idx=22 osp_ver=200662 osp_pg=0 (spawn #135182) 2025-04-30 22:06:34.051991 :90F7AAE7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:06:34.051991 :90F7AAE8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=194 time=3321583531 2025-04-30 22:06:34.051992 :90F7AAE9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:06:34.051992 :90F7AAEA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:06:34.051992 :90F7AAEB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:06:34.051993 :90F7AAEC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:06:34.051993 :90F7AAED:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:06:34.052488 :90F7AAEE: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-04-30 22:07:04.116536 :90F7B47E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:07:34.134057 :90F7BE8A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:07:34.123 2025-04-30 22:07:34.134059 :90F7BE8B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10216 pso_num=24 pso_serial#=234 2025-04-30 22:07:34.134197 :90F7BE8C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10216 osp_idx=22 osp_ver=200663 osp_pg=0 (spawn #135184) 2025-04-30 22:07:34.134198 :90F7BE8D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:07:34.134199 :90F7BE8E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=195 time=3321643609 2025-04-30 22:07:34.134199 :90F7BE8F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:07:34.134200 :90F7BE90:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:07:34.134200 :90F7BE91:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:07:34.134201 :90F7BE92:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:07:34.134201 :90F7BE93:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:07:34.134710 :90F7BE94: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-04-30 22:08:04.202269 :90F7C843:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:08:29.192482 :90F7CFEB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:08:29.181 2025-04-30 22:08:29.192484 :90F7CFEC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10980 pso_num=24 pso_serial#=235 2025-04-30 22:08:29.192621 :90F7CFED:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10980 osp_idx=22 osp_ver=200664 osp_pg=0 (spawn #135186) 2025-04-30 22:08:29.192622 :90F7CFEE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:08:29.192622 :90F7CFEF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=196 time=3321698671 2025-04-30 22:08:29.192623 :90F7CFF0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:08:29.192623 :90F7CFF1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:08:29.192623 :90F7CFF2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:08:29.192624 :90F7CFF3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:08:29.192624 :90F7CFF4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:08:29.193108 :90F7CFF5: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-04-30 22:08:34.195354 :90F7D1FF: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-04-30 22:08:34.195382*:90F7D200:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002494BB58 estart=1746022113 eid=16838217 ctx=0x000000077BD98A08 cwh=0x00000000232801B0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-30 22:08:34.195382*:90F7D201:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002494BB58 curCtx=0x000000002328F708 pubTabIdxCnt=0 2025-04-30 22:08:34.195382*:90F7D202:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002494BB58 2025-04-30 22:08:34.195382*:90F7D203:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002494BB58 2025-04-30 22:08:34.195382*:90F7D204:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002494BB58 curCtx=0x000000002328F708 xsc->flg4=65792 flags=2 2025-04-30 22:08:34.195382*:90F7D205:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002494BB58 2025-04-30 22:08:34.195919 :90F7D206: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-04-30 22:08:34.207030 :90F7D20E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-30 22:08:34.207497 :90F7D20F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-30 22:08:34.209821 :90F7D210:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-30 22:08:34.210208 :90F7D211: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-04-30 22:08:34.210242 :90F7D214:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-30 22:08:34.211130 :90F7D21A: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-04-30 22:08:34.211259 :90F7D223: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-04-30 22:09:04.290750 :90F7DB79:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:09:34.303311 :90F7E507:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:09:34.301 2025-04-30 22:09:34.303312 :90F7E508:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11224 pso_num=24 pso_serial#=236 2025-04-30 22:09:34.303395 :90F7E50B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11224 osp_idx=22 osp_ver=200665 osp_pg=0 (spawn #135188) 2025-04-30 22:09:34.303396 :90F7E50D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:09:34.303396 :90F7E50E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=197 time=3321763765 2025-04-30 22:09:34.303396 :90F7E50F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:09:34.303396 :90F7E510:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:09:34.303397 :90F7E511:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:09:34.303397 :90F7E512:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:09:34.303397 :90F7E513:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:09:34.303714 :90F7E51B: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-04-30 22:10:04.378152 :90F7EEDF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:10:34.385913 :90F7F898:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:10:34.375 2025-04-30 22:10:34.385915 :90F7F899:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12188 pso_num=24 pso_serial#=237 2025-04-30 22:10:34.386077 :90F7F89A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12188 osp_idx=22 osp_ver=200666 osp_pg=0 (spawn #135191) 2025-04-30 22:10:34.386078 :90F7F89B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:10:34.386078 :90F7F89C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=198 time=3321823859 2025-04-30 22:10:34.386078 :90F7F89D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:10:34.386079 :90F7F89E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:10:34.386079 :90F7F89F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:10:34.386080 :90F7F8A0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:10:34.386080 :90F7F8A1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:10:34.386560 :90F7F8A2: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-04-30 22:11:04.466632 :90F8030C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:11:34.454282 :90F80C72:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:11:34.443 2025-04-30 22:11:34.454284 :90F80C73:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11044 pso_num=24 pso_serial#=238 2025-04-30 22:11:34.454414 :90F80C74:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11044 osp_idx=22 osp_ver=200667 osp_pg=0 (spawn #135193) 2025-04-30 22:11:34.454415 :90F80C75:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:11:34.454415 :90F80C76:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=199 time=3321883937 2025-04-30 22:11:34.454416 :90F80C77:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:11:34.454416 :90F80C78:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:11:34.454416 :90F80C79:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:11:34.454417 :90F80C7A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:11:34.454417 :90F80C7B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:11:34.454950 :90F80C7C: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-04-30 22:12:04.543448 :90F815E4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:12:34.538257 :90F81F77:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:12:34.526 2025-04-30 22:12:34.538259 :90F81F78:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11280 pso_num=24 pso_serial#=239 2025-04-30 22:12:34.538389 :90F81F79:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11280 osp_idx=22 osp_ver=200668 osp_pg=0 (spawn #135195) 2025-04-30 22:12:34.538390 :90F81F7A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:12:34.538390 :90F81F7B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=200 time=3321944015 2025-04-30 22:12:34.538391 :90F81F7C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:12:34.538391 :90F81F7D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:12:34.538391 :90F81F7E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:12:34.538392 :90F81F7F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:12:34.538392 :90F81F80:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:12:34.538939 :90F81F81: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-04-30 22:13:04.617285 :90F82A3A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:13:34.628445 :90F833C3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:13:34.617 2025-04-30 22:13:34.628447 :90F833C4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5296 pso_num=24 pso_serial#=240 2025-04-30 22:13:34.628580 :90F833C5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=5296 osp_idx=22 osp_ver=200669 osp_pg=0 (spawn #135197) 2025-04-30 22:13:34.628581 :90F833C6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:13:34.628581 :90F833C7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=201 time=3322004109 2025-04-30 22:13:34.628582 :90F833C8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:13:34.628582 :90F833C9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:13:34.628583 :90F833CA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:13:34.628583 :90F833CB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:13:34.628583 :90F833CC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:13:34.629084 :90F833CD: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-04-30 22:14:04.665420 :90F83D95:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:14:34.705568 :90F8473A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:14:34.695 2025-04-30 22:14:34.705570 :90F8473B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8364 pso_num=24 pso_serial#=241 2025-04-30 22:14:34.705756 :90F8473C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8364 osp_idx=22 osp_ver=200670 osp_pg=0 (spawn #135200) 2025-04-30 22:14:34.705757 :90F8473D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:14:34.705758 :90F8473E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=202 time=3322064187 2025-04-30 22:14:34.705758 :90F8473F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:14:34.705759 :90F84740:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:14:34.705759 :90F84741:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:14:34.705759 :90F84742:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:14:34.705760 :90F84743:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:14:34.706246 :90F84744: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-04-30 22:15:04.770696 :90F850D7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:15:34.793205 :90F85A8F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:15:34.782 2025-04-30 22:15:34.793207 :90F85A90:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9656 pso_num=24 pso_serial#=242 2025-04-30 22:15:34.793327 :90F85A91:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9656 osp_idx=22 osp_ver=200671 osp_pg=0 (spawn #135202) 2025-04-30 22:15:34.793328 :90F85A92:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:15:34.793328 :90F85A93:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=203 time=3322124265 2025-04-30 22:15:34.793329 :90F85A94:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:15:34.793329 :90F85A95:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:15:34.793329 :90F85A96:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:15:34.793330 :90F85A97:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:15:34.793330 :90F85A98:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:15:34.793833 :90F85A99: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-04-30 22:16:04.822020 :90F864B5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:16:34.871976 :90F86E54:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:16:34.861 2025-04-30 22:16:34.871978 :90F86E55:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11836 pso_num=24 pso_serial#=243 2025-04-30 22:16:34.872115 :90F86E56:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11836 osp_idx=22 osp_ver=200672 osp_pg=0 (spawn #135204) 2025-04-30 22:16:34.872115 :90F86E57:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:16:34.872115 :90F86E58:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=204 time=3322184343 2025-04-30 22:16:34.872116 :90F86E59:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:16:34.872116 :90F86E5A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:16:34.872117 :90F86E5B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:16:34.872117 :90F86E5C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:16:34.872117 :90F86E5D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:16:34.872606 :90F86E5E: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-04-30 22:17:04.960352 :90F87807:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:17:29.989885 :90F8BA67:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:17:29.987 2025-04-30 22:17:29.989886 :90F8BA68:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10076 pso_num=24 pso_serial#=244 2025-04-30 22:17:29.990036 :90F8BA71:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10076 osp_idx=22 osp_ver=200673 osp_pg=0 (spawn #135206) 2025-04-30 22:17:29.990037 :90F8BA72:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:17:29.990037 :90F8BA73:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=205 time=3322239453 2025-04-30 22:17:29.990038 :90F8BA74:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:17:29.990038 :90F8BA75:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:17:29.990039 :90F8BA76:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:17:29.990039 :90F8BA77:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:17:29.990039 :90F8BA78:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:17:29.991913 :90F8BAC6: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-04-30 22:17:29.991954 :90F8BACF: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-04-30 22:17:30.105886 :90F8BB7E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-30 22:17:31.302054 :90F8BBD7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-30 22:17:31.301 2025-04-30 22:17:31.302056 :90F8BBD8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10684 pso_num=24 pso_serial#=245 2025-04-30 22:17:31.302182 :90F8BBD9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10684 osp_idx=22 osp_ver=200674 osp_pg=0 (spawn #135208) 2025-04-30 22:17:31.302184 :90F8BBDA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-30 22:17:31.302185 :90F8BBDB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=206 time=3322240765 2025-04-30 22:17:31.302185 :90F8BBDC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-30 22:17:31.302185 :90F8BBDD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-30 22:17:31.302186 :90F8BBDE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-30 22:17:31.302186 :90F8BBDF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-30 22:17:31.302186 :90F8BBE0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-30 22:17:31.302717 :90F8BBE1: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-04-30 22:17:31.302758 :90F8BBE7: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: 10684, M002)