Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250610222337\orcl_w000_16172_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:36784M/63366M, Ph+PgF:43364M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16172, image: ORACLE.EXE (W000) *** 2025-06-10 22:23:37.631 *** SESSION ID:(201.12865) 2025-06-10 22:23:37.631 *** 2025-06-10 22:23:37.631 Process diagnostic dump for ORACLE.EXE (W000), OS id=16172, pid: 25, proc_ser: 207, sid: 201, sess_ser: 12865 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.039449 sec, exc=0.039449 sec, total=0.039449 sec wait times: max=5.000000 sec, heur=1 min 15 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.000047 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=5.007239 sec, exc=5.007239 sec, total=5.007239 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=5.005761 sec, exc=5.005761 sec, total=5.005761 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=5.014749 sec, exc=5.014749 sec, total=5.014749 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=5.010145 sec, exc=5.010145 sec, total=5.010145 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=5.014715 sec, exc=5.014715 sec, total=5.014715 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=5.001712 sec, exc=5.001712 sec, total=5.001712 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=5.001255 sec, exc=5.001255 sec, total=5.001255 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=5.003582 sec, exc=5.003582 sec, total=5.003582 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=5.001677 sec, exc=5.001677 sec, total=5.001677 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time Sampled Session History of session 201 serial 12865 --------------------------------------------------- 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 --------------------------------------------------- [76 samples, 22:22:22 - 22:23:37] idle wait at each sample [session created at: 22:22:22] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-10 22:23:37.631 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16172, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-10 19:52:04.881334 :A2F3CC34:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 19:52:04.881832 :A2F3CC35:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 19:52:04.881875 :A2F3CC3C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 20:02:05.667212 :A2F48F21:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 20:02:06.093791 :A2F48F67:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 20:02:06.089 2025-06-10 20:02:06.093792 :A2F48F68:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14340 pso_num=25 pso_serial#=194 2025-06-10 20:02:06.093928 :A2F48F69:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14340 osp_idx=23 osp_ver=152796 osp_pg=0 (spawn #272202) 2025-06-10 20:02:06.093929 :A2F48F6A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 20:02:06.093929 :A2F48F6B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=122 time=2561469282 2025-06-10 20:02:06.093930 :A2F48F6C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 20:02:06.093930 :A2F48F6D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 20:02:06.093930 :A2F48F6E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 20:02:06.093931 :A2F48F6F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 20:02:06.093931 :A2F48F70:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 20:02:06.094421 :A2F48F71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 20:02:06.094465 :A2F48F7A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 20:12:06.943823 :A2F55A32:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 20:12:07.354793 :A2F55A68:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 20:12:07.350 2025-06-10 20:12:07.354796 :A2F55A69:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15820 pso_num=25 pso_serial#=195 2025-06-10 20:12:07.354929 :A2F55A6A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15820 osp_idx=23 osp_ver=152797 osp_pg=0 (spawn #272226) 2025-06-10 20:12:07.354930 :A2F55A6B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 20:12:07.354930 :A2F55A6C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=123 time=2562070547 2025-06-10 20:12:07.354931 :A2F55A6D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 20:12:07.354931 :A2F55A6E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 20:12:07.354932 :A2F55A6F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 20:12:07.354932 :A2F55A70:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 20:12:07.354933 :A2F55A71:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 20:12:07.355423 :A2F55A72:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 20:12:07.355468 :A2F55A7A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 20:22:08.157737 :A2F61C0B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 20:22:08.600639 :A2F61C60:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 20:22:08.595 2025-06-10 20:22:08.600640 :A2F61C61:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16612 pso_num=25 pso_serial#=196 2025-06-10 20:22:08.600794 :A2F61C62:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16612 osp_idx=23 osp_ver=152798 osp_pg=0 (spawn #272249) 2025-06-10 20:22:08.600795 :A2F61C63:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 20:22:08.600795 :A2F61C64:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=124 time=2562671797 2025-06-10 20:22:08.600796 :A2F61C65:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 20:22:08.600796 :A2F61C66:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 20:22:08.600796 :A2F61C67:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 20:22:08.600797 :A2F61C68:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 20:22:08.600797 :A2F61C69:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 20:22:08.601380 :A2F61C6A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 20:22:08.601450 :A2F61C74:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 20:32:09.387517 :A2F6E177:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 20:32:09.767210 :A2F6E1AC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 20:32:09.763 2025-06-10 20:32:09.767212 :A2F6E1AD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14884 pso_num=25 pso_serial#=197 2025-06-10 20:32:09.767348 :A2F6E1AE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14884 osp_idx=23 osp_ver=152799 osp_pg=0 (spawn #272271) 2025-06-10 20:32:09.767349 :A2F6E1AF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 20:32:09.767350 :A2F6E1B0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=125 time=2563272954 2025-06-10 20:32:09.767350 :A2F6E1B1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 20:32:09.767351 :A2F6E1B2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 20:32:09.767351 :A2F6E1B3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 20:32:09.767352 :A2F6E1B4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 20:32:09.767352 :A2F6E1B5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 20:32:09.767839 :A2F6E1B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 20:32:09.767883 :A2F6E1BE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 20:42:10.603883 :A2F7A725:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 20:42:10.956874 :A2F7A764:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 20:42:10.952 2025-06-10 20:42:10.956876 :A2F7A765:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4804 pso_num=25 pso_serial#=198 2025-06-10 20:42:10.957019 :A2F7A766:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4804 osp_idx=23 osp_ver=152800 osp_pg=0 (spawn #272294) 2025-06-10 20:42:10.957020 :A2F7A767:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 20:42:10.957020 :A2F7A768:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=126 time=2563874141 2025-06-10 20:42:10.957021 :A2F7A769:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 20:42:10.957021 :A2F7A76A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 20:42:10.957022 :A2F7A76B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 20:42:10.957022 :A2F7A76C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 20:42:10.957022 :A2F7A76D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 20:42:10.957528 :A2F7A76E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 20:42:10.957577 :A2F7A775:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 20:52:11.873697 :A2F86CBB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 20:52:12.128493 :A2F86D05:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 20:52:12.123 2025-06-10 20:52:12.128495 :A2F86D06:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14856 pso_num=25 pso_serial#=199 2025-06-10 20:52:12.128673 :A2F86D07:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14856 osp_idx=23 osp_ver=152801 osp_pg=0 (spawn #272317) 2025-06-10 20:52:12.128674 :A2F86D08:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 20:52:12.128674 :A2F86D09:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=127 time=2564475313 2025-06-10 20:52:12.128675 :A2F86D0A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 20:52:12.128675 :A2F86D0B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 20:52:12.128676 :A2F86D0C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 20:52:12.128677 :A2F86D0D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 20:52:12.128677 :A2F86D0E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 20:52:12.129198 :A2F86D0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 20:52:12.129241 :A2F86D16:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 21:02:12.968851 :A2F9334F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 21:02:13.270318 :A2F93389:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 21:02:13.265 2025-06-10 21:02:13.270320 :A2F9338A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16560 pso_num=25 pso_serial#=200 2025-06-10 21:02:13.270453 :A2F9338B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16560 osp_idx=23 osp_ver=152802 osp_pg=0 (spawn #272341) 2025-06-10 21:02:13.270453 :A2F9338C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 21:02:13.270454 :A2F9338D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=128 time=2565076454 2025-06-10 21:02:13.270454 :A2F9338E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:02:13.270455 :A2F9338F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:02:13.270455 :A2F93390:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:02:13.270456 :A2F93391:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:02:13.270456 :A2F93392:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:02:13.270948 :A2F93393:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 21:02:13.270994 :A2F9339A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 21:12:14.154928 :A2F9FF97:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 21:12:14.435276 :A2F9FFD9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 21:12:14.430 2025-06-10 21:12:14.435278 :A2F9FFDA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15536 pso_num=25 pso_serial#=201 2025-06-10 21:12:14.435417 :A2F9FFDB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15536 osp_idx=23 osp_ver=152803 osp_pg=0 (spawn #272365) 2025-06-10 21:12:14.435418 :A2F9FFDC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 21:12:14.435418 :A2F9FFDD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=129 time=2565677625 2025-06-10 21:12:14.435419 :A2F9FFDE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:12:14.435419 :A2F9FFDF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:12:14.435419 :A2F9FFE0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:12:14.435420 :A2F9FFE1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:12:14.435420 :A2F9FFE2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:12:14.435901 :A2F9FFE3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 21:12:14.435946 :A2F9FFEC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 21:22:15.207335 :A2FAC185:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 21:22:15.540336 :A2FAC1C5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 21:22:15.536 2025-06-10 21:22:15.540339 :A2FAC1C6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15348 pso_num=25 pso_serial#=202 2025-06-10 21:22:15.540481 :A2FAC1C7:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15348 osp_idx=23 osp_ver=152804 osp_pg=0 (spawn #272388) 2025-06-10 21:22:15.540482 :A2FAC1C8:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 21:22:15.540484 :A2FAC1C9:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=130 time=2566278735 2025-06-10 21:22:15.540484 :A2FAC1CA:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:22:15.540484 :A2FAC1CB:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:22:15.540485 :A2FAC1CC:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:22:15.540485 :A2FAC1CD:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:22:15.540485 :A2FAC1CE:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:22:15.540986 :A2FAC1CF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 21:22:15.541036 :A2FAC1D6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 21:30:36.655422 :A2FB634A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 21:32:16.740499 :A2FB83F1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-10 21:42:17.637276 :A2FC4735:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 21:42:17.923135 :A2FC4770:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 21:42:17.918 2025-06-10 21:42:17.923137 :A2FC4771:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16380 pso_num=25 pso_serial#=203 2025-06-10 21:42:17.923275 :A2FC4772:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16380 osp_idx=23 osp_ver=152805 osp_pg=0 (spawn #272432) 2025-06-10 21:42:17.923275 :A2FC4773:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 21:42:17.923276 :A2FC4774:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=131 time=2567481110 2025-06-10 21:42:17.923276 :A2FC4775:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:42:17.923277 :A2FC4776:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:42:17.923277 :A2FC4777:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:42:17.923278 :A2FC4778:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:42:17.923278 :A2FC4779:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:42:17.923770 :A2FC477A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 21:42:17.923812 :A2FC4782:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 21:52:18.869977 :A2FD0957:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 21:52:19.124665 :A2FD09A4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 21:52:19.120 2025-06-10 21:52:19.124667 :A2FD09A5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16704 pso_num=25 pso_serial#=204 2025-06-10 21:52:19.124805 :A2FD09A6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16704 osp_idx=23 osp_ver=152806 osp_pg=0 (spawn #272455) 2025-06-10 21:52:19.124805 :A2FD09A7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 21:52:19.124806 :A2FD09A8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=132 time=2568082313 2025-06-10 21:52:19.124806 :A2FD09A9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 21:52:19.124806 :A2FD09AA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 21:52:19.124807 :A2FD09AB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 21:52:19.124807 :A2FD09AC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 21:52:19.124807 :A2FD09AD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 21:52:19.125295 :A2FD09AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 21:52:19.125339 :A2FD09B6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 22:02:19.991824 :A2FDD918:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 22:02:20.262172 :A2FDD978:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 22:02:20.258 2025-06-10 22:02:20.262174 :A2FDD979:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14140 pso_num=25 pso_serial#=205 2025-06-10 22:02:20.262308 :A2FDD97A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14140 osp_idx=23 osp_ver=152807 osp_pg=0 (spawn #272485) 2025-06-10 22:02:20.262309 :A2FDD97B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:02:20.262309 :A2FDD97C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=133 time=2568683454 2025-06-10 22:02:20.262310 :A2FDD97D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:02:20.262310 :A2FDD97E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:02:20.262310 :A2FDD97F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:02:20.262311 :A2FDD980:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:02:20.262311 :A2FDD981:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:02:20.262862 :A2FDD982:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 22:02:20.262907 :A2FDD989:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 22:12:21.066228 :A2FEA499:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 22:12:21.352171 :A2FEA4CE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 22:12:21.348 2025-06-10 22:12:21.352173 :A2FEA4CF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16008 pso_num=25 pso_serial#=206 2025-06-10 22:12:21.352311 :A2FEA4D0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16008 osp_idx=23 osp_ver=152808 osp_pg=0 (spawn #272509) 2025-06-10 22:12:21.352312 :A2FEA4D1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:12:21.352312 :A2FEA4D2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=134 time=2569284547 2025-06-10 22:12:21.352312 :A2FEA4D3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:12:21.352312 :A2FEA4D4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:12:21.352313 :A2FEA4D5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:12:21.352313 :A2FEA4D6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:12:21.352313 :A2FEA4D7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:12:21.352801 :A2FEA4D8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 22:12:21.352846 :A2FEA4E2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-10 22:22:22.211513 :A2FF68EF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-10 22:22:22.513159 :A2FF6928:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-10 22:22:22.508 2025-06-10 22:22:22.513161 :A2FF6929:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16172 pso_num=25 pso_serial#=207 2025-06-10 22:22:22.513299 :A2FF692A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16172 osp_idx=23 osp_ver=152809 osp_pg=0 (spawn #272532) 2025-06-10 22:22:22.513300 :A2FF692B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-10 22:22:22.513300 :A2FF692C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=135 time=2569885704 2025-06-10 22:22:22.513301 :A2FF692D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-10 22:22:22.513301 :A2FF692E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-10 22:22:22.513301 :A2FF692F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-10 22:22:22.513302 :A2FF6930:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-10 22:22:22.513302 :A2FF6931:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-10 22:22:22.513784 :A2FF6932:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-10 22:22:22.513828 :A2FF693A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 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 25 (osid: 16172, W000)