Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250711220006\orcl_w000_17796_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:36423M/63366M, Ph+PgF:42490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17796, image: ORACLE.EXE (W000) *** 2025-07-11 22:00:06.153 *** SESSION ID:(201.28231) 2025-07-11 22:00:06.153 *** 2025-07-11 22:00:06.153 Process diagnostic dump for ORACLE.EXE (W000), OS id=17796, pid: 25, proc_ser: 148, sid: 201, sess_ser: 28231 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=0.999418 sec, exc=0.999418 sec, total=0.999418 sec wait times: max=5.000000 sec, heur=4 min 41 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.000028 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.010149 sec, exc=5.010149 sec, total=5.010149 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.014754 sec, exc=5.014754 sec, total=5.014754 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=54 seq_num=55 snap_id=1 wait times: snap=5.004653 sec, exc=5.004653 sec, total=5.004653 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=53 seq_num=54 snap_id=1 wait times: snap=5.007035 sec, exc=5.007035 sec, total=5.007035 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=5.003225 sec, exc=5.003225 sec, total=5.003225 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=51 seq_num=52 snap_id=1 wait times: snap=5.012725 sec, exc=5.012725 sec, total=5.012725 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=50 seq_num=51 snap_id=1 wait times: snap=5.014244 sec, exc=5.014244 sec, total=5.014244 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=5.005506 sec, exc=5.005506 sec, total=5.005506 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=48 seq_num=49 snap_id=1 wait times: snap=5.004653 sec, exc=5.004653 sec, total=5.004653 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=5.004617 sec, exc=5.004617 sec, total=5.004617 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time Sampled Session History of session 201 serial 28231 --------------------------------------------------- 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, 21:58:06 - 22:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-11 22:00:06.153 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17796, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-11 19:15:03.727412 :B0AABC75: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-07-11 19:25:04.729470 :B0AB85AA:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 19:25:05.031135 :B0AB85EE:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 19:25:05.026 2025-07-11 19:25:05.031137 :B0AB85EF:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18404 pso_num=25 pso_serial#=135 2025-07-11 19:25:05.031276 :B0AB85F0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18404 osp_idx=23 osp_ver=190732 osp_pg=0 (spawn #375463) 2025-07-11 19:25:05.031277 :B0AB85F1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 19:25:05.031277 :B0AB85F2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=32 time=942680923 2025-07-11 19:25:05.031278 :B0AB85F3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 19:25:05.031278 :B0AB85F4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 19:25:05.031278 :B0AB85F5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 19:25:05.031279 :B0AB85F6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 19:25:05.031279 :B0AB85F7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 19:25:05.031790 :B0AB85F8: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-07-11 19:25:05.031834 :B0AB85FF: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-07-11 19:35:05.945330 :B0AC501D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 19:35:06.309491 :B0AC5057:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 19:35:06.304 2025-07-11 19:35:06.309494 :B0AC5058:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18340 pso_num=25 pso_serial#=136 2025-07-11 19:35:06.309685 :B0AC5059:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18340 osp_idx=23 osp_ver=190733 osp_pg=0 (spawn #375486) 2025-07-11 19:35:06.309685 :B0AC505A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 19:35:06.309686 :B0AC505B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=33 time=943282204 2025-07-11 19:35:06.309686 :B0AC505C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 19:35:06.309687 :B0AC505D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 19:35:06.309687 :B0AC505E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 19:35:06.309687 :B0AC505F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 19:35:06.309688 :B0AC5060:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 19:35:06.310213 :B0AC5061: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-07-11 19:35:06.310259 :B0AC506B: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-07-11 19:38:03.045068 :B0AC8B5A: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-07-11 19:45:07.621768 :B0AD1986: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-07-11 19:55:08.579553 :B0ADE127:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 19:55:08.943687 :B0ADE15D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 19:55:08.938 2025-07-11 19:55:08.943689 :B0ADE15E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18208 pso_num=25 pso_serial#=137 2025-07-11 19:55:08.943828 :B0ADE15F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18208 osp_idx=23 osp_ver=190734 osp_pg=0 (spawn #375532) 2025-07-11 19:55:08.943829 :B0ADE160:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 19:55:08.943830 :B0ADE161:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=34 time=944484845 2025-07-11 19:55:08.943830 :B0ADE162:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 19:55:08.943830 :B0ADE163:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 19:55:08.943831 :B0ADE164:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 19:55:08.943831 :B0ADE165:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 19:55:08.943831 :B0ADE166:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 19:55:08.944317 :B0ADE167: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-07-11 19:55:08.944364 :B0ADE16E: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-07-11 20:05:09.998104 :B0AEAD03:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 20:05:10.377808 :B0AEAD40:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 20:05:10.373 2025-07-11 20:05:10.377810 :B0AEAD41:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12204 pso_num=25 pso_serial#=138 2025-07-11 20:05:10.377944 :B0AEAD42:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12204 osp_idx=23 osp_ver=190735 osp_pg=0 (spawn #375557) 2025-07-11 20:05:10.377945 :B0AEAD43:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 20:05:10.377946 :B0AEAD44:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=35 time=945086267 2025-07-11 20:05:10.377946 :B0AEAD45:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 20:05:10.377946 :B0AEAD46:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 20:05:10.377947 :B0AEAD47:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 20:05:10.377947 :B0AEAD48:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 20:05:10.377947 :B0AEAD49:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 20:05:10.378493 :B0AEAD4A: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-07-11 20:05:10.378542 :B0AEAD53: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-07-11 20:15:11.355231 :B0AF7D7D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 20:15:11.658714 :B0AF7DC3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 20:15:11.653 2025-07-11 20:15:11.658716 :B0AF7DC4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7184 pso_num=25 pso_serial#=139 2025-07-11 20:15:11.658850 :B0AF7DC5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7184 osp_idx=23 osp_ver=190736 osp_pg=0 (spawn #375580) 2025-07-11 20:15:11.658852 :B0AF7DC6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 20:15:11.658853 :B0AF7DC7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=36 time=945687548 2025-07-11 20:15:11.658853 :B0AF7DC8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 20:15:11.658853 :B0AF7DC9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 20:15:11.658853 :B0AF7DCA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 20:15:11.658854 :B0AF7DCB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 20:15:11.658854 :B0AF7DCC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 20:15:11.659382 :B0AF7DCD: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-07-11 20:15:11.659425 :B0AF7DD5: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-07-11 20:25:12.636334 :B0B045C0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 20:25:13.000376 :B0B045F6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 20:25:12.995 2025-07-11 20:25:13.000378 :B0B045F7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1400 pso_num=25 pso_serial#=140 2025-07-11 20:25:13.000515 :B0B045F8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1400 osp_idx=23 osp_ver=190737 osp_pg=0 (spawn #375603) 2025-07-11 20:25:13.000516 :B0B045F9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 20:25:13.000517 :B0B045FA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=37 time=946288892 2025-07-11 20:25:13.000517 :B0B045FB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 20:25:13.000517 :B0B045FC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 20:25:13.000518 :B0B045FD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 20:25:13.000518 :B0B045FE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 20:25:13.000518 :B0B045FF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 20:25:13.001074 :B0B04600: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-07-11 20:25:13.001133 :B0B0460A: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-07-11 20:35:13.877086 :B0B10E28:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 20:35:14.320165 :B0B10E67:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 20:35:14.314 2025-07-11 20:35:14.320167 :B0B10E68:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17888 pso_num=25 pso_serial#=141 2025-07-11 20:35:14.320324 :B0B10E69:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17888 osp_idx=23 osp_ver=190738 osp_pg=0 (spawn #375625) 2025-07-11 20:35:14.320325 :B0B10E6A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 20:35:14.320325 :B0B10E6B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=38 time=946890220 2025-07-11 20:35:14.320326 :B0B10E6C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 20:35:14.320326 :B0B10E6D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 20:35:14.320327 :B0B10E6E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 20:35:14.320327 :B0B10E6F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 20:35:14.320327 :B0B10E70:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 20:35:14.320932 :B0B10E71: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-07-11 20:35:14.321002 :B0B10E7B: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-07-11 20:45:15.205243 :B0B1D699:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 20:45:15.621387 :B0B1D6DF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 20:45:15.616 2025-07-11 20:45:15.621390 :B0B1D6E0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7280 pso_num=25 pso_serial#=142 2025-07-11 20:45:15.621525 :B0B1D6E1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7280 osp_idx=23 osp_ver=190739 osp_pg=0 (spawn #375648) 2025-07-11 20:45:15.621526 :B0B1D6E2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 20:45:15.621526 :B0B1D6E3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=39 time=947491517 2025-07-11 20:45:15.621527 :B0B1D6E4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 20:45:15.621527 :B0B1D6E5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 20:45:15.621527 :B0B1D6E6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 20:45:15.621528 :B0B1D6E7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 20:45:15.621528 :B0B1D6E8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 20:45:15.622058 :B0B1D6E9: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-07-11 20:45:15.622102 :B0B1D6F2: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-07-11 20:55:16.553546 :B0B29F32:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 20:55:16.964583 :B0B29F75:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 20:55:16.959 2025-07-11 20:55:16.964585 :B0B29F76:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8028 pso_num=25 pso_serial#=143 2025-07-11 20:55:16.964726 :B0B29F77:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8028 osp_idx=23 osp_ver=190740 osp_pg=0 (spawn #375671) 2025-07-11 20:55:16.964727 :B0B29F78:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 20:55:16.964727 :B0B29F79:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=40 time=948092861 2025-07-11 20:55:16.964728 :B0B29F7A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 20:55:16.964728 :B0B29F7B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 20:55:16.964728 :B0B29F7C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 20:55:16.964729 :B0B29F7D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 20:55:16.964729 :B0B29F7E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 20:55:16.965234 :B0B29F7F: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-07-11 20:55:16.965277 :B0B29F86: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-07-11 21:05:17.899077 :B0B36C6D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 21:05:18.231920 :B0B36CA3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 21:05:18.227 2025-07-11 21:05:18.231922 :B0B36CA4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=4432 pso_num=25 pso_serial#=144 2025-07-11 21:05:18.232058 :B0B36CA5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=4432 osp_idx=23 osp_ver=190741 osp_pg=0 (spawn #375696) 2025-07-11 21:05:18.232058 :B0B36CA6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:05:18.232059 :B0B36CA7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=41 time=948694126 2025-07-11 21:05:18.232059 :B0B36CA8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:05:18.232059 :B0B36CA9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:05:18.232059 :B0B36CAA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:05:18.232060 :B0B36CAB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:05:18.232060 :B0B36CAC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:05:18.232561 :B0B36CAD: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-07-11 21:05:18.232607 :B0B36CB4: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-07-11 21:15:19.201838 :B0B43AB8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 21:15:19.628459 :B0B43AF9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 21:15:19.623 2025-07-11 21:15:19.628461 :B0B43AFA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16248 pso_num=25 pso_serial#=145 2025-07-11 21:15:19.628598 :B0B43AFB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16248 osp_idx=23 osp_ver=190742 osp_pg=0 (spawn #375719) 2025-07-11 21:15:19.628599 :B0B43AFC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:15:19.628599 :B0B43AFD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=42 time=949295517 2025-07-11 21:15:19.628600 :B0B43AFE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:15:19.628600 :B0B43AFF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:15:19.628601 :B0B43B00:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:15:19.628601 :B0B43B01:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:15:19.628601 :B0B43B02:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:15:19.629097 :B0B43B03: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-07-11 21:15:19.629141 :B0B43B0B: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-07-11 21:25:20.533495 :B0B50095:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 21:25:20.860897 :B0B500CF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 21:25:20.856 2025-07-11 21:25:20.860899 :B0B500D0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9416 pso_num=25 pso_serial#=146 2025-07-11 21:25:20.861045 :B0B500D1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9416 osp_idx=23 osp_ver=190743 osp_pg=0 (spawn #375742) 2025-07-11 21:25:20.861046 :B0B500D2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:25:20.861047 :B0B500D3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=43 time=949896751 2025-07-11 21:25:20.861047 :B0B500D4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:25:20.861048 :B0B500D5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:25:20.861048 :B0B500D6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:25:20.861048 :B0B500D7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:25:20.861049 :B0B500D8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:25:20.861552 :B0B500D9: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-07-11 21:25:20.861600 :B0B500E0: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-07-11 21:35:21.731242 :B0B5C734:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 21:35:22.095288 :B0B5C776:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 21:35:22.090 2025-07-11 21:35:22.095290 :B0B5C777:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12732 pso_num=25 pso_serial#=147 2025-07-11 21:35:22.095425 :B0B5C778:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12732 osp_idx=23 osp_ver=190744 osp_pg=0 (spawn #375764) 2025-07-11 21:35:22.095426 :B0B5C779:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:35:22.095426 :B0B5C77A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=44 time=950497986 2025-07-11 21:35:22.095427 :B0B5C77B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:35:22.095427 :B0B5C77C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:35:22.095427 :B0B5C77D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:35:22.095428 :B0B5C77E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:35:22.095428 :B0B5C77F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:35:22.095936 :B0B5C780: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-07-11 21:35:22.095979 :B0B5C788: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-07-11 21:43:03.776169 :B0B65EC9: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-07-11 21:45:23.378882 :B0B68D9B: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-07-11 21:55:24.286185 :B0B753AC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-07-11 21:55:24.712649 :B0B753EF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 07-11 21:55:24.707 2025-07-11 21:55:24.712651 :B0B753F0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17796 pso_num=25 pso_serial#=148 2025-07-11 21:55:24.712784 :B0B753F1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17796 osp_idx=23 osp_ver=190745 osp_pg=0 (spawn #375809) 2025-07-11 21:55:24.712785 :B0B753F2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 21:55:24.712785 :B0B753F3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=45 time=951700611 2025-07-11 21:55:24.712786 :B0B753F4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 21:55:24.712786 :B0B753F5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 21:55:24.712787 :B0B753F6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 21:55:24.712787 :B0B753F7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 21:55:24.712787 :B0B753F8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 21:55:24.713325 :B0B753F9: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-07-11 21:55:24.713368 :B0B75401: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: 17796, W000)