Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250830220443\orcl_w000_19728_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:35117M/63366M, Ph+PgF:40119M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 19728, image: ORACLE.EXE (W000) *** 2025-08-30 22:04:44.059 *** SESSION ID:(193.3751) 2025-08-30 22:04:44.059 *** 2025-08-30 22:04:44.059 Process diagnostic dump for ORACLE.EXE (W000), OS id=19728, pid: 24, proc_ser: 239, sid: 193, sess_ser: 3751 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=90 seq_num=91 snap_id=1 wait times: snap=2.109362 sec, exc=2.109362 sec, total=2.109362 sec wait times: max=5.000000 sec, heur=7 min 27 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=5.014738 sec, exc=5.014738 sec, total=5.014738 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=87 seq_num=88 snap_id=1 wait times: snap=5.009072 sec, exc=5.009072 sec, total=5.009072 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000034 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.011880 sec, exc=5.011880 sec, total=5.011880 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.014719 sec, exc=5.014719 sec, total=5.014719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.010135 sec, exc=5.010135 sec, total=5.010135 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=83 seq_num=84 snap_id=1 wait times: snap=5.009718 sec, exc=5.009718 sec, total=5.009718 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.003232 sec, exc=5.003232 sec, total=5.003232 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=81 seq_num=82 snap_id=1 wait times: snap=5.000308 sec, exc=5.000308 sec, total=5.000308 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=80 seq_num=81 snap_id=1 wait times: snap=5.014753 sec, exc=5.014753 sec, total=5.014753 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time Sampled Session History of session 193 serial 3751 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 22:02:44 - 22:04:44] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.015000 sec (max dump time=30.000000 sec) *** 2025-08-30 22:04:44.075 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 19728, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-30 19:47:00.423613 :C6C7CFE7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 19:47:00.818948 :C6C7D01B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 19:47:00.814 2025-08-30 19:47:00.818950 :C6C7D01C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20728 pso_num=24 pso_serial#=225 2025-08-30 19:47:00.819086 :C6C7D01D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20728 osp_idx=22 osp_ver=822355 osp_pg=0 (spawn #542181) 2025-08-30 19:47:00.819087 :C6C7D01E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 19:47:00.819087 :C6C7D01F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=36 time=968872127 2025-08-30 19:47:00.819088 :C6C7D020:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 19:47:00.819089 :C6C7D021:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 19:47:00.819089 :C6C7D022:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 19:47:00.819089 :C6C7D023:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 19:47:00.819090 :C6C7D024:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 19:47:00.819583 :C6C7D025:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 19:47:00.819631 :C6C7D02F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 19:57:01.726402 :C6C89874:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 19:57:01.965442 :C6C898B8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 19:57:01.960 2025-08-30 19:57:01.965444 :C6C898B9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23020 pso_num=24 pso_serial#=226 2025-08-30 19:57:01.965582 :C6C898BA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23020 osp_idx=22 osp_ver=822356 osp_pg=0 (spawn #542203) 2025-08-30 19:57:01.965583 :C6C898BB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 19:57:01.965584 :C6C898BC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=37 time=969473268 2025-08-30 19:57:01.965584 :C6C898BD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 19:57:01.965585 :C6C898BE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 19:57:01.965585 :C6C898BF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 19:57:01.965585 :C6C898C0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 19:57:01.965586 :C6C898C1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 19:57:01.966086 :C6C898C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 19:57:01.966136 :C6C898CA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 20:07:02.771691 :C6C969CD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 20:07:03.042130 :C6C96A02:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 20:07:03.037 2025-08-30 20:07:03.042132 :C6C96A03:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22884 pso_num=24 pso_serial#=227 2025-08-30 20:07:03.042270 :C6C96A04:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22884 osp_idx=22 osp_ver=822357 osp_pg=0 (spawn #542229) 2025-08-30 20:07:03.042270 :C6C96A05:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 20:07:03.042271 :C6C96A06:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=38 time=970074346 2025-08-30 20:07:03.042271 :C6C96A07:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 20:07:03.042271 :C6C96A08:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 20:07:03.042271 :C6C96A09:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 20:07:03.042272 :C6C96A0A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 20:07:03.042272 :C6C96A0B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 20:07:03.042777 :C6C96A0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 20:07:03.042821 :C6C96A13:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 20:17:03.800343 :C6CA3563:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 20:17:04.227320 :C6CA35A5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 20:17:04.222 2025-08-30 20:17:04.227322 :C6CA35A6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20016 pso_num=24 pso_serial#=228 2025-08-30 20:17:04.227460 :C6CA35A7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20016 osp_idx=22 osp_ver=822358 osp_pg=0 (spawn #542252) 2025-08-30 20:17:04.227461 :C6CA35A8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 20:17:04.227461 :C6CA35A9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=39 time=970675533 2025-08-30 20:17:04.227462 :C6CA35AA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 20:17:04.227462 :C6CA35AB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 20:17:04.227462 :C6CA35AC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 20:17:04.227463 :C6CA35AD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 20:17:04.227463 :C6CA35AE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 20:17:04.227964 :C6CA35AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 20:17:04.228012 :C6CA35B6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 20:17:19.241312 :C6CA3ABB:db_trace:ksl2.c@2477:kslwtbctx(): [10005:24:193] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-08-30 20:17:19.241353 :C6CA3AC0:db_trace:ksl2.c@2611:kslwtectx(): [10005:24:193] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-08-30 20:17:19.241355 :C6CA3AC1:db_trace:ksl2.c@2619:kslwtectx(): [10005:24:193] KSL WAIT END wait times (usecs) - snap=39, exc=39, tot=39 2025-08-30 20:27:05.047455 :C6CAFD68:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 20:27:05.317868 :C6CAFD9D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 20:27:05.313 2025-08-30 20:27:05.317870 :C6CAFD9E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19604 pso_num=24 pso_serial#=229 2025-08-30 20:27:05.318015 :C6CAFD9F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19604 osp_idx=22 osp_ver=822359 osp_pg=0 (spawn #542274) 2025-08-30 20:27:05.318016 :C6CAFDA0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 20:27:05.318016 :C6CAFDA1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=40 time=971276627 2025-08-30 20:27:05.318016 :C6CAFDA2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 20:27:05.318017 :C6CAFDA3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 20:27:05.318017 :C6CAFDA4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 20:27:05.318017 :C6CAFDA5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 20:27:05.318018 :C6CAFDA6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 20:27:05.318527 :C6CAFDA7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 20:27:05.318579 :C6CAFDB1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 20:37:06.270302 :C6CBC521:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 20:37:06.587560 :C6CBC555:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 20:37:06.582 2025-08-30 20:37:06.587563 :C6CBC556:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23140 pso_num=24 pso_serial#=230 2025-08-30 20:37:06.587698 :C6CBC557:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23140 osp_idx=22 osp_ver=822360 osp_pg=0 (spawn #542297) 2025-08-30 20:37:06.587699 :C6CBC558:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 20:37:06.587700 :C6CBC559:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=41 time=971877893 2025-08-30 20:37:06.587701 :C6CBC55A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 20:37:06.587701 :C6CBC55B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 20:37:06.587702 :C6CBC55C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 20:37:06.587702 :C6CBC55D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 20:37:06.587702 :C6CBC55E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 20:37:06.588213 :C6CBC55F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 20:37:06.588260 :C6CBC567:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 20:47:07.426525 :C6CC8C28:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 20:47:07.837412 :C6CC8C5E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 20:47:07.833 2025-08-30 20:47:07.837414 :C6CC8C5F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23040 pso_num=24 pso_serial#=231 2025-08-30 20:47:07.837551 :C6CC8C60:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23040 osp_idx=22 osp_ver=822361 osp_pg=0 (spawn #542320) 2025-08-30 20:47:07.837551 :C6CC8C61:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 20:47:07.837552 :C6CC8C62:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=42 time=972479143 2025-08-30 20:47:07.837552 :C6CC8C63:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 20:47:07.837552 :C6CC8C64:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 20:47:07.837552 :C6CC8C65:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 20:47:07.837553 :C6CC8C66:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 20:47:07.837553 :C6CC8C67:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 20:47:07.838045 :C6CC8C68:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 20:47:07.838090 :C6CC8C6F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 20:57:08.738741 :C6CD5452:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 20:57:09.081783 :C6CD548A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 20:57:09.076 2025-08-30 20:57:09.081785 :C6CD548B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17416 pso_num=24 pso_serial#=232 2025-08-30 20:57:09.081926 :C6CD548C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17416 osp_idx=22 osp_ver=822362 osp_pg=0 (spawn #542343) 2025-08-30 20:57:09.081927 :C6CD548D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 20:57:09.081927 :C6CD548E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=43 time=973080393 2025-08-30 20:57:09.081927 :C6CD548F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 20:57:09.081928 :C6CD5490:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 20:57:09.081928 :C6CD5491:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 20:57:09.081930 :C6CD5492:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 20:57:09.081930 :C6CD5493:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 20:57:09.082437 :C6CD5494:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 20:57:09.082483 :C6CD549C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:05:20.078687 :C6CDF9B2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:07:10.261032 :C6CE27E1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:17:10.996591 :C6CEEF32:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:17:11.423150 :C6CEEF88:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:17:11.418 2025-08-30 21:17:11.423153 :C6CEEF89:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22956 pso_num=24 pso_serial#=233 2025-08-30 21:17:11.423341 :C6CEEF8A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22956 osp_idx=22 osp_ver=822363 osp_pg=0 (spawn #542391) 2025-08-30 21:17:11.423342 :C6CEEF8B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 21:17:11.423342 :C6CEEF8C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=44 time=974282721 2025-08-30 21:17:11.423343 :C6CEEF8D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 21:17:11.423343 :C6CEEF8E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 21:17:11.423343 :C6CEEF8F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 21:17:11.423343 :C6CEEF90:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 21:17:11.423344 :C6CEEF91:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 21:17:11.423871 :C6CEEF92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 21:17:11.423918 :C6CEEF98:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:27:12.179999 :C6CFB853:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:27:12.590888 :C6CFB888:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:27:12.586 2025-08-30 21:27:12.590891 :C6CFB889:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22144 pso_num=24 pso_serial#=234 2025-08-30 21:27:12.591026 :C6CFB88A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22144 osp_idx=22 osp_ver=822364 osp_pg=0 (spawn #542413) 2025-08-30 21:27:12.591026 :C6CFB88B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 21:27:12.591027 :C6CFB88C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=45 time=974883893 2025-08-30 21:27:12.591027 :C6CFB88D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 21:27:12.591027 :C6CFB88E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 21:27:12.591027 :C6CFB88F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 21:27:12.591028 :C6CFB890:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 21:27:12.591028 :C6CFB891:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 21:27:12.591517 :C6CFB892:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 21:27:12.591562 :C6CFB89A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:37:13.392825 :C6D08180:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:37:13.757093 :C6D081C2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:37:13.752 2025-08-30 21:37:13.757095 :C6D081C3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20640 pso_num=24 pso_serial#=235 2025-08-30 21:37:13.757240 :C6D081C4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20640 osp_idx=22 osp_ver=822365 osp_pg=0 (spawn #542436) 2025-08-30 21:37:13.757241 :C6D081C5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 21:37:13.757241 :C6D081C6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=46 time=975485065 2025-08-30 21:37:13.757242 :C6D081C7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 21:37:13.757242 :C6D081C8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 21:37:13.757242 :C6D081C9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 21:37:13.757243 :C6D081CA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 21:37:13.757243 :C6D081CB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 21:37:13.757769 :C6D081CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 21:37:13.757817 :C6D081D2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:47:14.671854 :C6D14A4C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:47:15.030535 :C6D14A89:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:47:15.025 2025-08-30 21:47:15.030537 :C6D14A8A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22456 pso_num=24 pso_serial#=236 2025-08-30 21:47:15.030677 :C6D14A8B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22456 osp_idx=22 osp_ver=822366 osp_pg=0 (spawn #542459) 2025-08-30 21:47:15.030678 :C6D14A8C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 21:47:15.030678 :C6D14A8D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=47 time=976086330 2025-08-30 21:47:15.030679 :C6D14A8E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 21:47:15.030679 :C6D14A8F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 21:47:15.030679 :C6D14A90:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 21:47:15.030680 :C6D14A91:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 21:47:15.030680 :C6D14A92:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 21:47:15.031193 :C6D14A93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 21:47:15.031239 :C6D14A9A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-08-30 21:57:15.898202 :C6D21263:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:57:15.989331 :C6D21266:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:57:15.976 2025-08-30 21:57:15.989333 :C6D21267:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21992 pso_num=24 pso_serial#=237 2025-08-30 21:57:15.989481 :C6D21268:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21992 (legacy spawn) 2025-08-30 21:57:16.008891 :C6D21269:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 21:57:16.009065 :C6D21272:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-30 21:57:16.009901 :C6D21273:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:57:16.018606 :C6D21276:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:57:16.007 2025-08-30 21:57:16.018608 :C6D21277:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23128 pso_num=24 pso_serial#=238 2025-08-30 21:57:16.018744 :C6D21278:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23128 (legacy spawn) 2025-08-30 21:57:16.025332 :C6D21279:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-30 21:57:16.025500 :C6D21282:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-30 21:57:16.026326 :C6D21283:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-30 21:57:16.387319 :C6D212C2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-30 21:57:16.382 2025-08-30 21:57:16.387322 :C6D212C3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19728 pso_num=24 pso_serial#=239 2025-08-30 21:57:16.387463 :C6D212C4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19728 osp_idx=22 osp_ver=822369 osp_pg=0 (spawn #542481) 2025-08-30 21:57:16.387463 :C6D212C5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-30 21:57:16.387464 :C6D212C6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=48 time=976687690 2025-08-30 21:57:16.387464 :C6D212C7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-30 21:57:16.387465 :C6D212C8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-30 21:57:16.387465 :C6D212C9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-30 21:57:16.387466 :C6D212CA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-30 21:57:16.387466 :C6D212CB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-30 21:57:16.387970 :C6D212CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-30 21:57:16.388012 :C6D212D4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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 24 (osid: 19728, W000)