Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250507082248\orcl_j001_11948_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:37438M/63366M, Ph+PgF:44872M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 11948, image: ORACLE.EXE (J001) *** 2025-05-07 08:22:49.029 *** SESSION ID:(201.41617) 2025-05-07 08:22:49.029 *** 2025-05-07 08:22:49.029 Process diagnostic dump for ORACLE.EXE (J001), OS id=11948, pid: 25, proc_ser: 229, sid: 201, sess_ser: 41617 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 11948 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.132567 sec, exc=0.132567 sec, total=0.132567 sec wait times: max=0.500000 sec, heur=6.645418 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.510113 sec, exc=0.510113 sec, total=0.510113 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.497964 sec, exc=0.497964 sec, total=0.497964 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.486413 sec, exc=0.486413 sec, total=0.486413 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510112 sec, exc=0.510112 sec, total=0.510112 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000004 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510096 sec, exc=0.510096 sec, total=0.510096 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 201 serial 41617 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 08:22:42 - 08:22:49] idle wait at each sample [session created at: 08:22:42] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-07 08:22:49.030 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 11948, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-07 08:09:46.103728 :93CFC862:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:09:46.103729 :93CFC863:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:09:46.103729 :93CFC864:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:09:46.103729 :93CFC865:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:09:46.103730 :93CFC866:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:09:46.104200 :93CFC867:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:10:16.165817 :93CFD1EE:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:10:20.197429 :93CFD3B4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:10:20.185 2025-05-07 08:10:20.197431 :93CFD3B5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9012 pso_num=25 pso_serial#=216 2025-05-07 08:10:20.197564 :93CFD3B6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9012 osp_idx=23 osp_ver=77735 osp_pg=0 (spawn #156947) 2025-05-07 08:10:20.197564 :93CFD3B7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:10:20.197565 :93CFD3B8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=3876197359 2025-05-07 08:10:20.197565 :93CFD3B9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:10:20.197566 :93CFD3BA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:10:20.197566 :93CFD3BB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:10:20.197566 :93CFD3BC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:10:20.197567 :93CFD3BD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:10:20.198056 :93CFD3BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:10:20.198095 :93CFD3C4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-05-07 08:10:20.202216 :93CFD3DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-07 08:10:20.202261 :93CFD3DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-07 08:10:20.202505 :93CFD3DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-07 08:10:20.202519 :93CFD3E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-07 08:10:20.202812 :93CFD3E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-07 08:10:20.202826 :93CFD3E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-07 08:10:20.203033 :93CFD3E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-07 08:10:20.203047 :93CFD3E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-07 08:10:20.203258 :93CFD3E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-07 08:10:20.203272 :93CFD3E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-07 08:10:20.203480 :93CFD3E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-05-07 08:10:20.203494 :93CFD3E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-05-07 08:10:20.205849 :93CFD3E9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:10:46.176347 :93CFDC20:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:10:46.165 2025-05-07 08:10:46.176349 :93CFDC21:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9932 pso_num=25 pso_serial#=217 2025-05-07 08:10:46.176484 :93CFDC22:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9932 osp_idx=23 osp_ver=77736 osp_pg=0 (spawn #156949) 2025-05-07 08:10:46.176485 :93CFDC23:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:10:46.176485 :93CFDC24:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=3876223343 2025-05-07 08:10:46.176485 :93CFDC25:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:10:46.176486 :93CFDC26:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:10:46.176486 :93CFDC27:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:10:46.176486 :93CFDC28:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:10:46.176487 :93CFDC29:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:10:46.176969 :93CFDC2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:11:16.234915 :93CFE6B3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:11:46.262669 :93CFF042:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:11:46.252 2025-05-07 08:11:46.262671 :93CFF043:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11640 pso_num=25 pso_serial#=218 2025-05-07 08:11:46.262797 :93CFF044:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11640 osp_idx=23 osp_ver=77737 osp_pg=0 (spawn #156951) 2025-05-07 08:11:46.262798 :93CFF045:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:11:46.262798 :93CFF046:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=2 time=3876283421 2025-05-07 08:11:46.262799 :93CFF047:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:11:46.262799 :93CFF048:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:11:46.262799 :93CFF049:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:11:46.262799 :93CFF04A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:11:46.262800 :93CFF04B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:11:46.263295 :93CFF04C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:12:16.346937 :93CFF9C5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:12:41.365836 :93D0024D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:12:41.355 2025-05-07 08:12:41.365838 :93D0024E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11576 pso_num=25 pso_serial#=219 2025-05-07 08:12:41.365987 :93D0024F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11576 osp_idx=23 osp_ver=77738 osp_pg=0 (spawn #156953) 2025-05-07 08:12:41.365987 :93D00250:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:12:41.365988 :93D00251:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=3876338531 2025-05-07 08:12:41.365988 :93D00252:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:12:41.365989 :93D00253:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:12:41.365989 :93D00254:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:12:41.365990 :93D00255:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:12:41.365990 :93D00256:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:12:41.366531 :93D00257:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:12:46.367057 :93D003B8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-07 08:13:16.436346 :93D00D6B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:13:46.484067 :93D01708:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:13:46.473 2025-05-07 08:13:46.484070 :93D01709:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10436 pso_num=25 pso_serial#=220 2025-05-07 08:13:46.484201 :93D0170A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10436 osp_idx=23 osp_ver=77739 osp_pg=0 (spawn #156955) 2025-05-07 08:13:46.484201 :93D0170B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:13:46.484202 :93D0170C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=4 time=3876403640 2025-05-07 08:13:46.484202 :93D0170D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:13:46.484202 :93D0170E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:13:46.484203 :93D0170F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:13:46.484203 :93D01710:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:13:46.484204 :93D01711:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:13:46.484703 :93D01712:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:14:16.563391 :93D020CB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:14:46.596497 :93D02A74:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:14:46.586 2025-05-07 08:14:46.596499 :93D02A75:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1820 pso_num=25 pso_serial#=221 2025-05-07 08:14:46.596631 :93D02A76:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1820 osp_idx=23 osp_ver=77740 osp_pg=0 (spawn #156958) 2025-05-07 08:14:46.596631 :93D02A77:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:14:46.596632 :93D02A78:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=5 time=3876463750 2025-05-07 08:14:46.596632 :93D02A79:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:14:46.596632 :93D02A7A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:14:46.596633 :93D02A7B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:14:46.596633 :93D02A7C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:14:46.596633 :93D02A7D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:14:46.597164 :93D02A7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:15:16.679159 :93D03406:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:15:46.662071 :93D03DC8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:15:46.650 2025-05-07 08:15:46.662073 :93D03DC9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11992 pso_num=25 pso_serial#=222 2025-05-07 08:15:46.662213 :93D03DCA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11992 osp_idx=23 osp_ver=77741 osp_pg=0 (spawn #156960) 2025-05-07 08:15:46.662214 :93D03DCB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:15:46.662215 :93D03DCC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=6 time=3876523828 2025-05-07 08:15:46.662215 :93D03DCD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:15:46.662216 :93D03DCE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:15:46.662216 :93D03DCF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:15:46.662216 :93D03DD0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:15:46.662217 :93D03DD1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:15:46.662699 :93D03DD2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:16:16.754877 :93D047E3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:16:46.764243 :93D051D5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:16:46.753 2025-05-07 08:16:46.764244 :93D051D6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11788 pso_num=25 pso_serial#=223 2025-05-07 08:16:46.764372 :93D051D7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11788 osp_idx=23 osp_ver=77742 osp_pg=0 (spawn #156962) 2025-05-07 08:16:46.764373 :93D051D8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:16:46.764373 :93D051D9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=3876583921 2025-05-07 08:16:46.764374 :93D051DA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:16:46.764374 :93D051DB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:16:46.764374 :93D051DC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:16:46.764375 :93D051DD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:16:46.764375 :93D051DE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:16:46.764853 :93D051DF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:17:16.844803 :93D05B5E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:17:46.924177 :93D06528:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:17:46.924 2025-05-07 08:17:46.924178 :93D06529:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9048 pso_num=25 pso_serial#=224 2025-05-07 08:17:46.924268 :93D06531:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9048 osp_idx=23 osp_ver=77743 osp_pg=0 (spawn #156964) 2025-05-07 08:17:46.924268 :93D06532:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:17:46.924269 :93D06533:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=3876644078 2025-05-07 08:17:46.924269 :93D06534:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:17:46.924269 :93D06535:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:17:46.924269 :93D06536:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:17:46.924269 :93D06537:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:17:46.924269 :93D06538:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:17:46.924586 :93D06539:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:18:16.994311 :93D06EAD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:18:47.038989 :93D07884:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:18:47.028 2025-05-07 08:18:47.038991 :93D07885:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10604 pso_num=25 pso_serial#=225 2025-05-07 08:18:47.039119 :93D07886:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10604 osp_idx=23 osp_ver=77744 osp_pg=0 (spawn #156966) 2025-05-07 08:18:47.039120 :93D07887:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:18:47.039120 :93D07888:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=3876704203 2025-05-07 08:18:47.039121 :93D07889:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:18:47.039121 :93D0788A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:18:47.039122 :93D0788B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:18:47.039122 :93D0788C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:18:47.039122 :93D0788D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:18:47.039594 :93D0788E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:19:17.093653 :93D08241:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:19:47.116769 :93D08C0B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:19:47.106 2025-05-07 08:19:47.116771 :93D08C0C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10164 pso_num=25 pso_serial#=226 2025-05-07 08:19:47.116902 :93D08C0D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10164 osp_idx=23 osp_ver=77745 osp_pg=0 (spawn #156968) 2025-05-07 08:19:47.116903 :93D08C0E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:19:47.116903 :93D08C0F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=10 time=3876764281 2025-05-07 08:19:47.116904 :93D08C10:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:19:47.116904 :93D08C11:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:19:47.116905 :93D08C12:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:19:47.116905 :93D08C13:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:19:47.116906 :93D08C14:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:19:47.117372 :93D08C15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:20:17.177427 :93D0958F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:20:47.213272 :93D0A00F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:20:47.202 2025-05-07 08:20:47.213274 :93D0A010:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10388 pso_num=25 pso_serial#=227 2025-05-07 08:20:47.213413 :93D0A011:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10388 osp_idx=23 osp_ver=77746 osp_pg=0 (spawn #156971) 2025-05-07 08:20:47.213414 :93D0A012:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:20:47.213415 :93D0A013:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=11 time=3876824375 2025-05-07 08:20:47.213415 :93D0A014:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:20:47.213416 :93D0A015:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:20:47.213416 :93D0A016:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:20:47.213417 :93D0A017:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:20:47.213417 :93D0A018:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:20:47.213910 :93D0A019:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:21:17.278403 :93D0AAE0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:21:47.311539 :93D0B489:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:21:47.301 2025-05-07 08:21:47.311541 :93D0B48A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11560 pso_num=25 pso_serial#=228 2025-05-07 08:21:47.311674 :93D0B48B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11560 osp_idx=23 osp_ver=77747 osp_pg=0 (spawn #156973) 2025-05-07 08:21:47.311674 :93D0B48C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:21:47.311675 :93D0B48D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=12 time=3876884468 2025-05-07 08:21:47.311675 :93D0B48E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:21:47.311675 :93D0B48F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:21:47.311675 :93D0B490:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:21:47.311676 :93D0B491:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:21:47.311676 :93D0B492:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:21:47.312140 :93D0B493:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:22:17.395395 :93D0BE23:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-05-07 08:22:42.383724 :93D0C62C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 05-07 08:22:42.372 2025-05-07 08:22:42.383726 :93D0C62D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11948 pso_num=25 pso_serial#=229 2025-05-07 08:22:42.383861 :93D0C62E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11948 osp_idx=23 osp_ver=77748 osp_pg=0 (spawn #156975) 2025-05-07 08:22:42.383861 :93D0C62F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-07 08:22:42.383862 :93D0C630:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=3876939546 2025-05-07 08:22:42.383862 :93D0C631:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-05-07 08:22:42.383862 :93D0C632:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-05-07 08:22:42.383862 :93D0C633:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-05-07 08:22:42.383862 :93D0C634:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-07 08:22:42.383863 :93D0C635:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-07 08:22:42.384339 :93D0C636:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-07 08:22:47.389336 :93D101F7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 11948, J001)