Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629080356\orcl_j001_10112_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:36541M/63366M, Ph+PgF:42621M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 10112, image: ORACLE.EXE (J001) *** 2025-06-29 08:03:57.027 *** SESSION ID:(201.61501) 2025-06-29 08:03:57.027 *** 2025-06-29 08:03:57.027 Process diagnostic dump for ORACLE.EXE (J001), OS id=10112, pid: 25, proc_ser: 32, sid: 201, sess_ser: 61501 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10112 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.258146 sec, exc=0.258146 sec, total=0.258146 sec wait times: max=0.500000 sec, heur=12.306812 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.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=21 seq_num=22 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 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=17 seq_num=18 snap_id=1 wait times: snap=0.510109 sec, exc=0.510109 sec, total=0.510109 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 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.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.510107 sec, exc=0.510107 sec, total=0.510107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time Sampled Session History of session 201 serial 61501 --------------------------------------------------- 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 --------------------------------------------------- [13 samples, 08:03:44 - 08:03:57] idle wait at each sample [session created at: 08:03:44] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 08:03:57.027 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 10112, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 07:50:13.564777 :AB1FB7B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:50:43.538197 :AB1FC167:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:50:43.527 2025-06-29 07:50:43.538200 :AB1FC168:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17632 pso_num=25 pso_serial#=18 2025-06-29 07:50:43.538347 :AB1FC169:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17632 osp_idx=23 osp_ver=175570 osp_pg=0 (spawn #333880) 2025-06-29 07:50:43.538348 :AB1FC16A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:50:43.538348 :AB1FC16B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=112 time=4159186719 2025-06-29 07:50:43.538348 :AB1FC16C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:50:43.538349 :AB1FC16D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:50:43.538349 :AB1FC16E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:50:43.538349 :AB1FC16F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:50:43.538350 :AB1FC170:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:50:43.538872 :AB1FC171: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-06-29 07:51:13.631523 :AB1FCB09:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:51:43.609508 :AB1FD4D9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:51:43.599 2025-06-29 07:51:43.609510 :AB1FD4DA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=6992 pso_num=25 pso_serial#=19 2025-06-29 07:51:43.609636 :AB1FD4DB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=6992 osp_idx=23 osp_ver=175571 osp_pg=0 (spawn #333882) 2025-06-29 07:51:43.609636 :AB1FD4DC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:51:43.609637 :AB1FD4DD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=113 time=4159246797 2025-06-29 07:51:43.609637 :AB1FD4DE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:51:43.609637 :AB1FD4DF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:51:43.609638 :AB1FD4E0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:51:43.609638 :AB1FD4E1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:51:43.609638 :AB1FD4E2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:51:43.610195 :AB1FD4E3: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-06-29 07:52:13.685632 :AB1FDE78:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:52:43.712321 :AB1FE840:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:52:43.701 2025-06-29 07:52:43.712323 :AB1FE841:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14660 pso_num=25 pso_serial#=20 2025-06-29 07:52:43.712453 :AB1FE842:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14660 osp_idx=23 osp_ver=175572 osp_pg=0 (spawn #333884) 2025-06-29 07:52:43.712453 :AB1FE843:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:52:43.712455 :AB1FE844:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=114 time=4159306891 2025-06-29 07:52:43.712455 :AB1FE845:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:52:43.712456 :AB1FE846:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:52:43.712456 :AB1FE847:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:52:43.712456 :AB1FE848:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:52:43.712457 :AB1FE849:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:52:43.712953 :AB1FE84A: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-06-29 07:53:13.808397 :AB1FF1F6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:53:43.805517 :AB1FFB96:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:53:43.794 2025-06-29 07:53:43.805519 :AB1FFB97:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11488 pso_num=25 pso_serial#=21 2025-06-29 07:53:43.805653 :AB1FFB98:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11488 osp_idx=23 osp_ver=175573 osp_pg=0 (spawn #333886) 2025-06-29 07:53:43.805654 :AB1FFB99:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:53:43.805654 :AB1FFB9A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=115 time=4159366985 2025-06-29 07:53:43.805655 :AB1FFB9B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:53:43.805655 :AB1FFB9C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:53:43.805656 :AB1FFB9D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:53:43.805656 :AB1FFB9E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:53:43.805656 :AB1FFB9F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:53:43.806139 :AB1FFBA0: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-06-29 07:54:13.885929 :AB200551:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:54:43.884385 :AB2010E5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:54:43.873 2025-06-29 07:54:43.884387 :AB2010E6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15208 pso_num=25 pso_serial#=22 2025-06-29 07:54:43.884533 :AB2010E7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15208 osp_idx=23 osp_ver=175574 osp_pg=0 (spawn #333888) 2025-06-29 07:54:43.884533 :AB2010E8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:54:43.884533 :AB2010E9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=116 time=4159427063 2025-06-29 07:54:43.884533 :AB2010EA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:54:43.884534 :AB2010EB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:54:43.884534 :AB2010EC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:54:43.884534 :AB2010ED:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:54:43.884535 :AB2010EE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:54:43.885005 :AB2010EF: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-06-29 07:55:13.953701 :AB201B9B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:55:43.977907 :AB20256C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:55:43.967 2025-06-29 07:55:43.977909 :AB20256D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18300 pso_num=25 pso_serial#=23 2025-06-29 07:55:43.978052 :AB20256E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18300 osp_idx=23 osp_ver=175575 osp_pg=0 (spawn #333890) 2025-06-29 07:55:43.978053 :AB20256F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:55:43.978053 :AB202570:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=117 time=4159487157 2025-06-29 07:55:43.978054 :AB202571:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:55:43.978054 :AB202572:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:55:43.978055 :AB202573:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:55:43.978055 :AB202574:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:55:43.978055 :AB202575:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:55:43.978555 :AB202576: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-06-29 07:56:14.053647 :AB202ED1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:56:44.093427 :AB2038AD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:56:44.082 2025-06-29 07:56:44.093430 :AB2038AE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17728 pso_num=25 pso_serial#=24 2025-06-29 07:56:44.093566 :AB2038AF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17728 osp_idx=23 osp_ver=175576 osp_pg=0 (spawn #333893) 2025-06-29 07:56:44.093567 :AB2038B0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:56:44.093567 :AB2038B1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=118 time=4159547282 2025-06-29 07:56:44.093567 :AB2038B2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:56:44.093568 :AB2038B3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:56:44.093568 :AB2038B4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:56:44.093569 :AB2038B5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:56:44.093569 :AB2038B6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:56:44.094036 :AB2038B7: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-06-29 07:57:14.219724 :AB20424C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:57:44.194972 :AB204C05:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:57:44.183 2025-06-29 07:57:44.194975 :AB204C06:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16340 pso_num=25 pso_serial#=25 2025-06-29 07:57:44.195102 :AB204C07:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16340 osp_idx=23 osp_ver=175577 osp_pg=0 (spawn #333895) 2025-06-29 07:57:44.195102 :AB204C08:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:57:44.195103 :AB204C09:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=119 time=4159607375 2025-06-29 07:57:44.195103 :AB204C0A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:57:44.195104 :AB204C0B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:57:44.195104 :AB204C0C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:57:44.195105 :AB204C0D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:57:44.195105 :AB204C0E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:57:44.195660 :AB204C0F: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-06-29 07:58:14.270663 :AB2055FD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:58:44.297896 :AB205FED:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:58:44.286 2025-06-29 07:58:44.297899 :AB205FEE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15964 pso_num=25 pso_serial#=26 2025-06-29 07:58:44.298045 :AB205FEF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15964 osp_idx=23 osp_ver=175578 osp_pg=0 (spawn #333897) 2025-06-29 07:58:44.298046 :AB205FF0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:58:44.298046 :AB205FF1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=120 time=4159667485 2025-06-29 07:58:44.298047 :AB205FF2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:58:44.298047 :AB205FF3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:58:44.298047 :AB205FF4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:58:44.298047 :AB205FF5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:58:44.298048 :AB205FF6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:58:44.298556 :AB205FF7: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-06-29 07:59:09.308391 :AB20681C: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-06-29 07:59:39.378487 :AB207225:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 07:59:44.360098 :AB2073BD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 07:59:44.349 2025-06-29 07:59:44.360100 :AB2073BE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9264 pso_num=25 pso_serial#=27 2025-06-29 07:59:44.360240 :AB2073BF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9264 osp_idx=23 osp_ver=175579 osp_pg=0 (spawn #333901) 2025-06-29 07:59:44.360241 :AB2073C0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:59:44.360241 :AB2073C1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=121 time=4159727547 2025-06-29 07:59:44.360241 :AB2073C2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:59:44.360242 :AB2073C3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:59:44.360242 :AB2073C4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:59:44.360243 :AB2073C5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:59:44.360243 :AB2073C6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:59:44.360714 :AB2073C7: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-06-29 08:00:14.443208 :AB207DE7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 08:00:21.937687 :AB208114:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 08:00:21.923 2025-06-29 08:00:21.937689 :AB208115:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8812 pso_num=25 pso_serial#=28 2025-06-29 08:00:21.937821 :AB208116:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8812 osp_idx=23 osp_ver=175580 osp_pg=0 (spawn #333903) 2025-06-29 08:00:21.937822 :AB208117:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:00:21.937822 :AB208118:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=4159765125 2025-06-29 08:00:21.937823 :AB208119:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:00:21.937823 :AB20811A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:00:21.937823 :AB20811B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:00:21.937824 :AB20811C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:00:21.937824 :AB20811D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:00:21.938302 :AB20811E: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-06-29 08:00:21.938345 :AB208124: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-06-29 08:00:21.945918 :AB208132:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:21.946997 :AB20813A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:21.947484 :AB208142:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:21.947920 :AB20814A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:21.948422 :AB208152:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:21.950875 :AB20815A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:21.951912 :AB208162:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:22.002617 :AB20816A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:22.003514 :AB208172:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:22.003756 :AB20817A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:22.003968 :AB208182:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 08:00:22.005823 :AB20818A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 08:00:44.451417 :AB208905:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 08:00:44.440 2025-06-29 08:00:44.451419 :AB208906:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=700 pso_num=25 pso_serial#=29 2025-06-29 08:00:44.451551 :AB208907:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=700 osp_idx=23 osp_ver=175581 osp_pg=0 (spawn #333905) 2025-06-29 08:00:44.451552 :AB208908:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:00:44.451552 :AB208909:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=4159787641 2025-06-29 08:00:44.451553 :AB20890A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:00:44.451553 :AB20890B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:00:44.451554 :AB20890C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:00:44.451554 :AB20890D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:00:44.451554 :AB20890E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:00:44.452053 :AB20890F: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-06-29 08:01:14.549928 :AB20936A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 08:01:44.547025 :AB209D35:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 08:01:44.535 2025-06-29 08:01:44.547027 :AB209D36:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13260 pso_num=25 pso_serial#=30 2025-06-29 08:01:44.547163 :AB209D37:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13260 osp_idx=23 osp_ver=175582 osp_pg=0 (spawn #333907) 2025-06-29 08:01:44.547163 :AB209D38:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:01:44.547164 :AB209D39:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=4159847735 2025-06-29 08:01:44.547164 :AB209D3A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:01:44.547164 :AB209D3B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:01:44.547165 :AB209D3C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:01:44.547165 :AB209D3D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:01:44.547165 :AB209D3E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:01:44.547685 :AB209D3F: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-06-29 08:02:14.609845 :AB20A67C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 08:02:44.623283 :AB20B034:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 08:02:44.612 2025-06-29 08:02:44.623285 :AB20B035:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7628 pso_num=25 pso_serial#=31 2025-06-29 08:02:44.623425 :AB20B036:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7628 osp_idx=23 osp_ver=175583 osp_pg=0 (spawn #333909) 2025-06-29 08:02:44.623425 :AB20B037:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:02:44.623426 :AB20B038:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=4159907813 2025-06-29 08:02:44.623426 :AB20B039:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:02:44.623426 :AB20B03A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:02:44.623427 :AB20B03B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:02:44.623427 :AB20B03C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:02:44.623427 :AB20B03D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:02:44.623896 :AB20B03E: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-06-29 08:03:14.708672 :AB20BA34:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 08:03:44.727350 :AB20C402:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 08:03:44.716 2025-06-29 08:03:44.727352 :AB20C403:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10112 pso_num=25 pso_serial#=32 2025-06-29 08:03:44.727465 :AB20C404:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10112 osp_idx=23 osp_ver=175584 osp_pg=0 (spawn #333911) 2025-06-29 08:03:44.727466 :AB20C405:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:03:44.727466 :AB20C406:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=4159967907 2025-06-29 08:03:44.727467 :AB20C407:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:03:44.727467 :AB20C408:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:03:44.727468 :AB20C409:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:03:44.727468 :AB20C40A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:03:44.727468 :AB20C40B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:03:44.727938 :AB20C40C: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 10112, J001)