Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250809140602\orcl_w000_20576_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:35635M/63366M, Ph+PgF:41131M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 20576, image: ORACLE.EXE (W000) *** 2025-08-09 14:06:02.871 *** SESSION ID:(177.1777) 2025-08-09 14:06:02.871 *** 2025-08-09 14:06:02.871 Process diagnostic dump for ORACLE.EXE (W000), OS id=20576, pid: 22, proc_ser: 252, sid: 177, sess_ser: 1777 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=182 seq_num=183 snap_id=1 wait times: snap=3.850416 sec, exc=3.850416 sec, total=3.850416 sec wait times: max=5.000000 sec, heur=14 min 50 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.000022 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=181 seq_num=182 snap_id=1 wait times: snap=5.003335 sec, exc=5.003335 sec, total=5.003335 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=180 seq_num=181 snap_id=1 wait times: snap=5.008897 sec, exc=5.008897 sec, total=5.008897 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=179 seq_num=180 snap_id=1 wait times: snap=5.004642 sec, exc=5.004642 sec, total=5.004642 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=178 seq_num=179 snap_id=1 wait times: snap=5.014741 sec, exc=5.014741 sec, total=5.014741 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=177 seq_num=178 snap_id=1 wait times: snap=5.004655 sec, exc=5.004655 sec, total=5.004655 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=176 seq_num=177 snap_id=1 wait times: snap=5.004145 sec, exc=5.004145 sec, total=5.004145 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=175 seq_num=176 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.000028 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=174 seq_num=175 snap_id=1 wait times: snap=5.007490 sec, exc=5.007490 sec, total=5.007490 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=173 seq_num=174 snap_id=1 wait times: snap=5.000348 sec, exc=5.000348 sec, total=5.000348 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=172 seq_num=173 snap_id=1 wait times: snap=5.004264 sec, exc=5.004264 sec, total=5.004264 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 177 serial 1777 --------------------------------------------------- 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, 14:04:02 - 14:06:02] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-09 14:06:02.871 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 20576, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-09 11:50:52.016312 :BD69EFAB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 11:50:52.227524 :BD69EFC1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 11:50:52.219 2025-08-09 11:50:52.227527 :BD69EFC2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6024 pso_num=22 pso_serial#=237 2025-08-09 11:50:52.227709 :BD69EFC3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6024 (legacy spawn) 2025-08-09 11:50:52.234105 :BD69EFC4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 11:50:52.234288 :BD69EFCD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 11:50:52.235121 :BD69EFCE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 11:50:52.395873 :BD69F015:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 11:50:52.391 2025-08-09 11:50:52.395875 :BD69F016:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20928 pso_num=22 pso_serial#=238 2025-08-09 11:50:52.396008 :BD69F017:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20928 osp_idx=0 osp_ver=497305 osp_pg=0 (spawn #471081) 2025-08-09 11:50:52.396008 :BD69F018:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 11:50:52.396009 :BD69F019:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=3420921704 2025-08-09 11:50:52.396009 :BD69F01A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 11:50:52.396009 :BD69F01B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 11:50:52.396009 :BD69F01C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 11:50:52.396010 :BD69F01D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 11:50:52.396010 :BD69F01E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 11:50:52.396492 :BD69F01F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 11:50:52.396539 :BD69F026:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 11:51:11.884697 :BD69F62D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:00:53.561759 :BD6AB3CD:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:10:54.424397 :BD6B7DC8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 12:10:54.757371 :BD6B7E1D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 12:10:54.752 2025-08-09 12:10:54.757373 :BD6B7E1E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19224 pso_num=22 pso_serial#=239 2025-08-09 12:10:54.757514 :BD6B7E1F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19224 osp_idx=0 osp_ver=497306 osp_pg=0 (spawn #471128) 2025-08-09 12:10:54.757515 :BD6B7E20:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 12:10:54.757515 :BD6B7E21:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=3422124064 2025-08-09 12:10:54.757516 :BD6B7E22:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 12:10:54.757516 :BD6B7E23:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 12:10:54.757516 :BD6B7E24:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 12:10:54.757517 :BD6B7E25:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 12:10:54.757517 :BD6B7E26:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 12:10:54.758027 :BD6B7E27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 12:10:54.758072 :BD6B7E2D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:20:55.715160 :BD6C3FFE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 12:20:56.048001 :BD6C4047:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 12:20:56.043 2025-08-09 12:20:56.048004 :BD6C4048:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20864 pso_num=22 pso_serial#=240 2025-08-09 12:20:56.048138 :BD6C4049:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20864 osp_idx=0 osp_ver=497307 osp_pg=0 (spawn #471151) 2025-08-09 12:20:56.048139 :BD6C404A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 12:20:56.048140 :BD6C404B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=3422725361 2025-08-09 12:20:56.048140 :BD6C404C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 12:20:56.048140 :BD6C404D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 12:20:56.048141 :BD6C404E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 12:20:56.048141 :BD6C404F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 12:20:56.048141 :BD6C4050:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 12:20:56.048646 :BD6C4051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 12:20:56.048690 :BD6C4058:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:30:56.947301 :BD6D038E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 12:30:57.233400 :BD6D03DB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 12:30:57.228 2025-08-09 12:30:57.233401 :BD6D03DC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11712 pso_num=22 pso_serial#=241 2025-08-09 12:30:57.233535 :BD6D03DD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11712 osp_idx=0 osp_ver=497308 osp_pg=0 (spawn #471173) 2025-08-09 12:30:57.233535 :BD6D03DE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 12:30:57.233536 :BD6D03DF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=3423326533 2025-08-09 12:30:57.233536 :BD6D03E0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 12:30:57.233537 :BD6D03E1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 12:30:57.233537 :BD6D03E2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 12:30:57.233537 :BD6D03E3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 12:30:57.233538 :BD6D03E4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 12:30:57.234031 :BD6D03E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 12:30:57.234079 :BD6D03EC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:40:58.045912 :BD6DC656:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 12:40:58.488140 :BD6DC692:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 12:40:58.483 2025-08-09 12:40:58.488142 :BD6DC693:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12108 pso_num=22 pso_serial#=242 2025-08-09 12:40:58.488282 :BD6DC694:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12108 osp_idx=0 osp_ver=497309 osp_pg=0 (spawn #471196) 2025-08-09 12:40:58.488283 :BD6DC695:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 12:40:58.488283 :BD6DC696:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=3423927798 2025-08-09 12:40:58.488284 :BD6DC697:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 12:40:58.488284 :BD6DC698:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 12:40:58.488285 :BD6DC699:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 12:40:58.488285 :BD6DC69A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 12:40:58.488286 :BD6DC69B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 12:40:58.488790 :BD6DC69C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 12:40:58.488846 :BD6DC6A4:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:50:59.286789 :BD6E8882:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 12:50:59.373143 :BD6E8885:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 12:50:59.364 2025-08-09 12:50:59.373145 :BD6E8886:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19672 pso_num=22 pso_serial#=243 2025-08-09 12:50:59.373283 :BD6E8887:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19672 (legacy spawn) 2025-08-09 12:50:59.379762 :BD6E8888:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 12:50:59.379964 :BD6E888D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 12:50:59.380802 :BD6E8892:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 12:50:59.760366 :BD6E88D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 12:50:59.755 2025-08-09 12:50:59.760368 :BD6E88D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19724 pso_num=22 pso_serial#=244 2025-08-09 12:50:59.760502 :BD6E88DA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19724 osp_idx=0 osp_ver=497311 osp_pg=0 (spawn #471219) 2025-08-09 12:50:59.760503 :BD6E88DB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 12:50:59.760503 :BD6E88DC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=3424529064 2025-08-09 12:50:59.760504 :BD6E88DD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 12:50:59.760504 :BD6E88DE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 12:50:59.760504 :BD6E88DF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 12:50:59.760504 :BD6E88E0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 12:50:59.760504 :BD6E88E1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 12:50:59.760989 :BD6E88E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 12:50:59.761037 :BD6E88E9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:01:00.475796 :BD6F4EA6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:01:00.786919 :BD6F4EA9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:01:00.773 2025-08-09 13:01:00.786921 :BD6F4EAA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19756 pso_num=22 pso_serial#=245 2025-08-09 13:01:00.787056 :BD6F4EAB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19756 (legacy spawn) 2025-08-09 13:01:00.793543 :BD6F4EAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 13:01:00.793574 :BD6F4EAF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 13:01:00.793725 :BD6F4EB6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:01:00.794518 :BD6F4EC3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:01:00.803221 :BD6F4EC6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:01:00.788 2025-08-09 13:01:00.803223 :BD6F4EC7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20500 pso_num=22 pso_serial#=246 2025-08-09 13:01:00.803361 :BD6F4EC8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20500 (legacy spawn) 2025-08-09 13:01:00.809815 :BD6F4EC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-09 13:01:00.809985 :BD6F4ED1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:01:00.810800 :BD6F4ED3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:01:00.996034 :BD6F4F0B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:01:00.991 2025-08-09 13:01:00.996036 :BD6F4F0C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20744 pso_num=22 pso_serial#=247 2025-08-09 13:01:00.996172 :BD6F4F0D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20744 osp_idx=0 osp_ver=497314 osp_pg=0 (spawn #471243) 2025-08-09 13:01:00.996172 :BD6F4F0E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 13:01:00.996173 :BD6F4F0F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=3425130298 2025-08-09 13:01:00.996173 :BD6F4F10:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 13:01:00.996173 :BD6F4F11:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 13:01:00.996174 :BD6F4F12:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 13:01:00.996174 :BD6F4F13:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 13:01:00.996175 :BD6F4F14:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 13:01:00.996660 :BD6F4F15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 13:01:00.996707 :BD6F4F1B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:11:01.868201 :BD7017A6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:11:02.310480 :BD7017E6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:11:02.306 2025-08-09 13:11:02.310482 :BD7017E7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19820 pso_num=22 pso_serial#=248 2025-08-09 13:11:02.310620 :BD7017E8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19820 osp_idx=0 osp_ver=497315 osp_pg=0 (spawn #471267) 2025-08-09 13:11:02.310621 :BD7017E9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 13:11:02.310621 :BD7017EA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=3425731611 2025-08-09 13:11:02.310621 :BD7017EB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 13:11:02.310622 :BD7017EC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 13:11:02.310622 :BD7017ED:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 13:11:02.310622 :BD7017EE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 13:11:02.310622 :BD7017EF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 13:11:02.311097 :BD7017F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 13:11:02.311141 :BD7017F7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:21:03.157448 :BD70DDEF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:21:03.708982 :BD70DE32:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:21:03.704 2025-08-09 13:21:03.708984 :BD70DE33:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=580 pso_num=22 pso_serial#=249 2025-08-09 13:21:03.709119 :BD70DE34:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=580 osp_idx=0 osp_ver=497316 osp_pg=0 (spawn #471290) 2025-08-09 13:21:03.709120 :BD70DE35:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 13:21:03.709120 :BD70DE36:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=3426333017 2025-08-09 13:21:03.709120 :BD70DE37:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 13:21:03.709121 :BD70DE38:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 13:21:03.709121 :BD70DE39:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 13:21:03.709121 :BD70DE3A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 13:21:03.709122 :BD70DE3B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 13:21:03.709657 :BD70DE3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 13:21:03.709701 :BD70DE44:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:31:04.485094 :BD71A21F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:31:05.036732 :BD71A28B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:31:05.032 2025-08-09 13:31:05.036735 :BD71A28C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10356 pso_num=22 pso_serial#=250 2025-08-09 13:31:05.036878 :BD71A28D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10356 osp_idx=0 osp_ver=497317 osp_pg=0 (spawn #471312) 2025-08-09 13:31:05.036878 :BD71A28E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 13:31:05.036879 :BD71A28F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=3426934345 2025-08-09 13:31:05.036881 :BD71A290:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 13:31:05.036881 :BD71A291:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 13:31:05.036881 :BD71A292:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 13:31:05.036882 :BD71A293:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 13:31:05.036882 :BD71A294:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 13:31:05.037386 :BD71A295:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 13:31:05.037432 :BD71A29C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:41:05.835222 :BD7265C6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:41:06.240691 :BD726624:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:41:06.236 2025-08-09 13:41:06.240693 :BD726625:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20312 pso_num=22 pso_serial#=251 2025-08-09 13:41:06.240828 :BD726626:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20312 osp_idx=0 osp_ver=497318 osp_pg=0 (spawn #471335) 2025-08-09 13:41:06.240829 :BD726627:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 13:41:06.240829 :BD726628:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=3427535548 2025-08-09 13:41:06.240829 :BD726629:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 13:41:06.240830 :BD72662A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 13:41:06.240830 :BD72662B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 13:41:06.240830 :BD72662C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 13:41:06.240831 :BD72662D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 13:41:06.241336 :BD72662E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 13:41:06.241379 :BD726636:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:51:07.110570 :BD7328FB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-09 13:51:07.537224 :BD73293E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-09 13:51:07.532 2025-08-09 13:51:07.537226 :BD73293F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20576 pso_num=22 pso_serial#=252 2025-08-09 13:51:07.537367 :BD732940:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20576 osp_idx=0 osp_ver=497319 osp_pg=0 (spawn #471358) 2025-08-09 13:51:07.537368 :BD732941:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-09 13:51:07.537368 :BD732942:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=3428136845 2025-08-09 13:51:07.537369 :BD732943:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-09 13:51:07.537369 :BD732944:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-09 13:51:07.537369 :BD732945:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-09 13:51:07.537370 :BD732946:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-09 13:51:07.537370 :BD732947:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-09 13:51:07.537868 :BD732948:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-09 13:51:07.537911 :BD732950:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 13:51:12.644038 :BD732AF2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09 14:01:08.668438 :BD73EE46:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 20576, W000)