Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250627080245\orcl_w000_13896_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:36315M/63366M, Ph+PgF:42431M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 13896, image: ORACLE.EXE (W000) *** 2025-06-27 08:02:45.577 *** SESSION ID:(177.52553) 2025-06-27 08:02:45.577 *** 2025-06-27 08:02:45.577 Process diagnostic dump for ORACLE.EXE (W000), OS id=13896, pid: 22, proc_ser: 116, sid: 177, sess_ser: 52553 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=4.513480 sec, exc=4.513480 sec, total=4.513480 sec wait times: max=5.000000 sec, heur=2 min 9 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=25 seq_num=26 snap_id=1 wait times: snap=5.008491 sec, exc=5.008491 sec, total=5.008491 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=5.014722 sec, exc=5.014722 sec, total=5.014722 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=5.014732 sec, exc=5.014732 sec, total=5.014732 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=21 seq_num=22 snap_id=1 wait times: snap=5.004614 sec, exc=5.004614 sec, total=5.004614 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=20 seq_num=21 snap_id=1 wait times: snap=5.008341 sec, exc=5.008341 sec, total=5.008341 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=19 seq_num=20 snap_id=1 wait times: snap=5.000239 sec, exc=5.000239 sec, total=5.000239 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=18 seq_num=19 snap_id=1 wait times: snap=5.011151 sec, exc=5.011151 sec, total=5.011151 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=17 seq_num=18 snap_id=1 wait times: snap=5.004616 sec, exc=5.004616 sec, total=5.004616 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time Sampled Session History of session 177 serial 52553 --------------------------------------------------- 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, 08:00:45 - 08:02:45] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-27 08:02:45.577 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 13896, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-27 05:30:17.359160 :AA314E9F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 05:30:17.359160 :AA314EA0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 05:30:17.359665 :AA314EA1: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-06-27 05:30:17.359713 :AA314EA9: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-06-27 05:40:18.163235 :AA321574:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 05:40:18.573824 :AA3215E1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 05:40:18.569 2025-06-27 05:40:18.573826 :AA3215E2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18276 pso_num=22 pso_serial#=103 2025-06-27 05:40:18.573967 :AA3215E3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18276 osp_idx=0 osp_ver=352330 osp_pg=0 (spawn #326894) 2025-06-27 05:40:18.573968 :AA3215E4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 05:40:18.573968 :AA3215E5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=3978561766 2025-06-27 05:40:18.573969 :AA3215E6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 05:40:18.573969 :AA3215E7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 05:40:18.573969 :AA3215E8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 05:40:18.573970 :AA3215E9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 05:40:18.573970 :AA3215EA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 05:40:18.574478 :AA3215EB: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-06-27 05:40:18.574539 :AA3215F1: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-06-27 05:50:19.394836 :AA32DD16:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 05:50:19.790000 :AA32DD76:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 05:50:19.785 2025-06-27 05:50:19.790002 :AA32DD77:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7536 pso_num=22 pso_serial#=104 2025-06-27 05:50:19.790141 :AA32DD78:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7536 osp_idx=0 osp_ver=352331 osp_pg=0 (spawn #326916) 2025-06-27 05:50:19.790143 :AA32DD79:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 05:50:19.790143 :AA32DD7A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=3979162985 2025-06-27 05:50:19.790143 :AA32DD7B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 05:50:19.790144 :AA32DD7C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 05:50:19.790144 :AA32DD7D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 05:50:19.790144 :AA32DD7E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 05:50:19.790145 :AA32DD7F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 05:50:19.790635 :AA32DD80: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-06-27 05:50:19.790680 :AA32DD88: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-06-27 06:00:20.688508 :AA33A7DC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 06:00:21.068002 :AA33A840:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 06:00:21.063 2025-06-27 06:00:21.068004 :AA33A841:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17596 pso_num=22 pso_serial#=105 2025-06-27 06:00:21.068161 :AA33A842:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17596 osp_idx=0 osp_ver=352332 osp_pg=0 (spawn #326941) 2025-06-27 06:00:21.068162 :AA33A843:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 06:00:21.068162 :AA33A844:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=3979764250 2025-06-27 06:00:21.068162 :AA33A845:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 06:00:21.068163 :AA33A846:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 06:00:21.068163 :AA33A847:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 06:00:21.068163 :AA33A848:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 06:00:21.068163 :AA33A849:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 06:00:21.068687 :AA33A84A: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-06-27 06:00:21.068732 :AA33A852: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-06-27 06:10:21.935982 :AA3476A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 06:10:22.299992 :AA3476FE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 06:10:22.295 2025-06-27 06:10:22.299994 :AA3476FF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17956 pso_num=22 pso_serial#=106 2025-06-27 06:10:22.300163 :AA347700:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17956 osp_idx=0 osp_ver=352333 osp_pg=0 (spawn #326965) 2025-06-27 06:10:22.300164 :AA347701:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 06:10:22.300164 :AA347702:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=3980365485 2025-06-27 06:10:22.300165 :AA347703:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 06:10:22.300165 :AA347704:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 06:10:22.300166 :AA347705:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 06:10:22.300167 :AA347706:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 06:10:22.300167 :AA347707:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 06:10:22.300696 :AA347708: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-06-27 06:10:22.300742 :AA347710: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-06-27 06:20:23.205374 :AA353FEB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 06:20:23.538919 :AA354052:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 06:20:23.534 2025-06-27 06:20:23.538921 :AA354053:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17696 pso_num=22 pso_serial#=107 2025-06-27 06:20:23.539053 :AA354054:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17696 osp_idx=0 osp_ver=352334 osp_pg=0 (spawn #326987) 2025-06-27 06:20:23.539053 :AA354055:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 06:20:23.539054 :AA354056:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=3980966735 2025-06-27 06:20:23.539054 :AA354057:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 06:20:23.539055 :AA354058:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 06:20:23.539055 :AA354059:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 06:20:23.539055 :AA35405A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 06:20:23.539056 :AA35405B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 06:20:23.539534 :AA35405C: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-06-27 06:20:23.539580 :AA354064: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-06-27 06:30:24.341300 :AA36087C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 06:30:24.799104 :AA3608DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 06:30:24.794 2025-06-27 06:30:24.799105 :AA3608DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9672 pso_num=22 pso_serial#=108 2025-06-27 06:30:24.799237 :AA3608DC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9672 osp_idx=0 osp_ver=352335 osp_pg=0 (spawn #327010) 2025-06-27 06:30:24.799238 :AA3608DD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 06:30:24.799238 :AA3608DE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=3981567985 2025-06-27 06:30:24.799238 :AA3608DF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 06:30:24.799239 :AA3608E0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 06:30:24.799239 :AA3608E1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 06:30:24.799239 :AA3608E2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 06:30:24.799240 :AA3608E3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 06:30:24.799737 :AA3608E4: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-06-27 06:30:24.799780 :AA3608EB: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-06-27 06:40:25.600855 :AA36D060:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 06:40:25.996073 :AA36D0BE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 06:40:25.991 2025-06-27 06:40:25.996075 :AA36D0BF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17088 pso_num=22 pso_serial#=109 2025-06-27 06:40:25.996214 :AA36D0C0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17088 osp_idx=0 osp_ver=352336 osp_pg=0 (spawn #327033) 2025-06-27 06:40:25.996215 :AA36D0C1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 06:40:25.996215 :AA36D0C2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=3982169188 2025-06-27 06:40:25.996216 :AA36D0C3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 06:40:25.996216 :AA36D0C4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 06:40:25.996216 :AA36D0C5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 06:40:25.996217 :AA36D0C6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 06:40:25.996217 :AA36D0C7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 06:40:25.996728 :AA36D0C8: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-06-27 06:40:25.996782 :AA36D0CF: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-06-27 06:50:26.783876 :AA379765:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 06:50:27.226180 :AA3797C4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 06:50:27.221 2025-06-27 06:50:27.226183 :AA3797C5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9264 pso_num=22 pso_serial#=110 2025-06-27 06:50:27.226313 :AA3797C6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9264 osp_idx=0 osp_ver=352337 osp_pg=0 (spawn #327055) 2025-06-27 06:50:27.226314 :AA3797C7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 06:50:27.226314 :AA3797C8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=3982770422 2025-06-27 06:50:27.226315 :AA3797C9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 06:50:27.226315 :AA3797CA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 06:50:27.226315 :AA3797CB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 06:50:27.226316 :AA3797CC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 06:50:27.226316 :AA3797CD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 06:50:27.226901 :AA3797CE: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-06-27 06:50:27.226956 :AA3797D5: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-06-27 07:00:28.073972 :AA38617B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 07:00:28.516096 :AA3861E3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 07:00:28.511 2025-06-27 07:00:28.516098 :AA3861E4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7880 pso_num=22 pso_serial#=111 2025-06-27 07:00:28.516239 :AA3861E5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7880 osp_idx=0 osp_ver=352338 osp_pg=0 (spawn #327080) 2025-06-27 07:00:28.516240 :AA3861E6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:00:28.516240 :AA3861E7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=3983371704 2025-06-27 07:00:28.516240 :AA3861E8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:00:28.516241 :AA3861E9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:00:28.516241 :AA3861EA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:00:28.516242 :AA3861EB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:00:28.516243 :AA3861EC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:00:28.516731 :AA3861ED: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-06-27 07:00:28.516784 :AA3861F6: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-06-27 07:10:29.304157 :AA393211:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 07:10:29.746041 :AA39326A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 07:10:29.741 2025-06-27 07:10:29.746043 :AA39326B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13592 pso_num=22 pso_serial#=112 2025-06-27 07:10:29.746175 :AA39326C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13592 osp_idx=0 osp_ver=352339 osp_pg=0 (spawn #327105) 2025-06-27 07:10:29.746175 :AA39326D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:10:29.746176 :AA39326E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=3983972938 2025-06-27 07:10:29.746176 :AA39326F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:10:29.746176 :AA393270:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:10:29.746177 :AA393271:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:10:29.746177 :AA393272:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:10:29.746178 :AA393273:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:10:29.746754 :AA393274: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-06-27 07:10:29.746802 :AA39327C: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-06-27 07:16:01.477564 :AA399FC1: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-06-27 07:20:31.008709 :AA39F97A: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-06-27 07:30:31.784807 :AA3AC09B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 07:30:32.274183 :AA3AC10E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 07:30:32.269 2025-06-27 07:30:32.274185 :AA3AC10F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17752 pso_num=22 pso_serial#=113 2025-06-27 07:30:32.274329 :AA3AC110:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17752 osp_idx=0 osp_ver=352340 osp_pg=0 (spawn #327149) 2025-06-27 07:30:32.274330 :AA3AC111:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:30:32.274330 :AA3AC112:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=3985175469 2025-06-27 07:30:32.274331 :AA3AC113:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:30:32.274331 :AA3AC114:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:30:32.274332 :AA3AC115:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:30:32.274332 :AA3AC116:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:30:32.274333 :AA3AC117:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:30:32.274851 :AA3AC118: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-06-27 07:30:32.274898 :AA3AC11E: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-06-27 07:40:33.072096 :AA3B886A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 07:40:33.514118 :AA3B88C9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 07:40:33.509 2025-06-27 07:40:33.514120 :AA3B88CA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1216 pso_num=22 pso_serial#=114 2025-06-27 07:40:33.514281 :AA3B88CB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1216 osp_idx=0 osp_ver=352341 osp_pg=0 (spawn #327172) 2025-06-27 07:40:33.514282 :AA3B88CC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:40:33.514282 :AA3B88CD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=11 time=3985776704 2025-06-27 07:40:33.514283 :AA3B88CE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:40:33.514283 :AA3B88CF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:40:33.514284 :AA3B88D0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:40:33.514284 :AA3B88D1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:40:33.514284 :AA3B88D2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:40:33.514780 :AA3B88D3: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-06-27 07:40:33.514824 :AA3B88DC: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-06-27 07:50:34.315536 :AA3C5242:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 07:50:34.742061 :AA3C5299:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 07:50:34.737 2025-06-27 07:50:34.742063 :AA3C529A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15136 pso_num=22 pso_serial#=115 2025-06-27 07:50:34.742199 :AA3C529B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15136 osp_idx=0 osp_ver=352342 osp_pg=0 (spawn #327194) 2025-06-27 07:50:34.742200 :AA3C529C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:50:34.742201 :AA3C529D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=12 time=3986377938 2025-06-27 07:50:34.742201 :AA3C529E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:50:34.742201 :AA3C529F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:50:34.742202 :AA3C52A0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:50:34.742202 :AA3C52A1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:50:34.742202 :AA3C52A2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:50:34.742712 :AA3C52A3: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-06-27 07:50:34.742758 :AA3C52AA: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-06-27 08:00:35.540699 :AA3D1B6B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-27 08:00:35.891056 :AA3D1BC5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-27 08:00:35.886 2025-06-27 08:00:35.891058 :AA3D1BC6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13896 pso_num=22 pso_serial#=116 2025-06-27 08:00:35.891193 :AA3D1BC7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13896 osp_idx=0 osp_ver=352343 osp_pg=0 (spawn #327219) 2025-06-27 08:00:35.891194 :AA3D1BC8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 08:00:35.891194 :AA3D1BC9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=13 time=3986979079 2025-06-27 08:00:35.891195 :AA3D1BCA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 08:00:35.891195 :AA3D1BCB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 08:00:35.891196 :AA3D1BCC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 08:00:35.891196 :AA3D1BCD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 08:00:35.891196 :AA3D1BCE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 08:00:35.891686 :AA3D1BCF: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-06-27 08:00:35.891730 :AA3D1BD7: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 13896, W000)