Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250803100815\orcl_w000_17016_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:35798M/63366M, Ph+PgF:41451M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 22
Windows thread id: 17016, image: ORACLE.EXE (W000)


*** 2025-08-03 10:08:15.809
*** SESSION ID:(177.19831) 2025-08-03 10:08:15.809
 

*** 2025-08-03 10:08:15.809
Process diagnostic dump for ORACLE.EXE (W000), OS id=17016,
pid: 22, proc_ser: 226, sid: 177, sess_ser: 19831 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=59 seq_num=60 snap_id=1
    wait times: snap=3.338139 sec, exc=3.338139 sec, total=3.338139 sec
    wait times: max=5.000000 sec, heur=4 min 53 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.000026 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=58 seq_num=59 snap_id=1
    wait times: snap=5.000327 sec, exc=5.000327 sec, total=5.000327 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=57 seq_num=58 snap_id=1
    wait times: snap=5.014745 sec, exc=5.014745 sec, total=5.014745 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 2: 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.004659 sec, exc=5.004659 sec, total=5.004659 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000038 sec of elapsed time
 3: 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.001718 sec, exc=5.001718 sec, total=5.001718 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 4: 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.001257 sec, exc=5.001257 sec, total=5.001257 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 5: 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.000389 sec, exc=5.000389 sec, total=5.000389 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 6: 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.000416 sec, exc=5.000416 sec, total=5.000416 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 7: 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.005260 sec, exc=5.005260 sec, total=5.005260 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 8: 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.010157 sec, exc=5.010157 sec, total=5.010157 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 9: 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.014734 sec, exc=5.014734 sec, total=5.014734 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 19831
---------------------------------------------------
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,                                            10:06:15 - 10:08:15]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-03 10:08:15.809
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 17016, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-03 07:33:03.870352 :BAAD6769:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 07:33:04.375015 :BAAD67A2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 07:33:04.370
2025-08-03 07:33:04.375017 :BAAD67A3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7628 pso_num=22 pso_serial#=213
2025-08-03 07:33:04.375156 :BAAD67A4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7628 osp_idx=0 osp_ver=477135 osp_pg=0 (spawn #450477)
2025-08-03 07:33:04.375156 :BAAD67A5: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-03 07:33:04.375157 :BAAD67A6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=119 time=2887053689
2025-08-03 07:33:04.375157 :BAAD67A7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 07:33:04.375158 :BAAD67A8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 07:33:04.375158 :BAAD67A9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 07:33:04.375158 :BAAD67AA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 07:33:04.375159 :BAAD67AB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 07:33:04.375654 :BAAD67AC: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-03 07:33:04.375702 :BAAD67B6: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-03 07:43:05.199603 :BAAE32DF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 07:43:05.563642 :BAAE3325:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 07:43:05.559
2025-08-03 07:43:05.563644 :BAAE3326:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18896 pso_num=22 pso_serial#=214
2025-08-03 07:43:05.563778 :BAAE3327:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18896 osp_idx=0 osp_ver=477136 osp_pg=0 (spawn #450499)
2025-08-03 07:43:05.563779 :BAAE3328: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-03 07:43:05.563779 :BAAE3329:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=120 time=2887654876
2025-08-03 07:43:05.563779 :BAAE332A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 07:43:05.563780 :BAAE332B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 07:43:05.563780 :BAAE332C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 07:43:05.563780 :BAAE332D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 07:43:05.563781 :BAAE332E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 07:43:05.564268 :BAAE332F: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-03 07:43:05.564323 :BAAE3336: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-03 07:49:26.481303 :BAAEB366: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-03 07:53:06.804898 :BAAEFDB8: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-03 08:03:07.626644 :BAAFC9B0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 08:03:07.912437 :BAAFC9EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 08:03:07.907
2025-08-03 08:03:07.912438 :BAAFC9EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18648 pso_num=22 pso_serial#=215
2025-08-03 08:03:07.912582 :BAAFC9F0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18648 osp_idx=0 osp_ver=477137 osp_pg=0 (spawn #450548)
2025-08-03 08:03:07.912583 :BAAFC9F1: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-03 08:03:07.912584 :BAAFC9F2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=121 time=2888857220
2025-08-03 08:03:07.912585 :BAAFC9F3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 08:03:07.912585 :BAAFC9F4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 08:03:07.912586 :BAAFC9F5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 08:03:07.912586 :BAAFC9F6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 08:03:07.912586 :BAAFC9F7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 08:03:07.913087 :BAAFC9F8: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-03 08:03:07.913133 :BAAFCA00: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-03 08:13:08.764605 :BAB09CCA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 08:13:09.154301 :BAB09D0F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 08:13:09.149
2025-08-03 08:13:09.154303 :BAB09D10:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19432 pso_num=22 pso_serial#=216
2025-08-03 08:13:09.154443 :BAB09D11:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19432 osp_idx=0 osp_ver=477138 osp_pg=0 (spawn #450571)
2025-08-03 08:13:09.154444 :BAB09D12: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-03 08:13:09.154444 :BAB09D13:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=122 time=2889458454
2025-08-03 08:13:09.154445 :BAB09D14:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 08:13:09.154446 :BAB09D15:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 08:13:09.154446 :BAB09D16:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 08:13:09.154446 :BAB09D17:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 08:13:09.154447 :BAB09D18:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 08:13:09.154953 :BAB09D19: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-03 08:13:09.155002 :BAB09D23: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-03 08:23:09.965894 :BAB16717:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 08:23:10.298861 :BAB16768:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 08:23:10.294
2025-08-03 08:23:10.298863 :BAB16769:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20220 pso_num=22 pso_serial#=217
2025-08-03 08:23:10.299009 :BAB1676A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20220 osp_idx=0 osp_ver=477139 osp_pg=0 (spawn #450594)
2025-08-03 08:23:10.299010 :BAB1676B: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-03 08:23:10.299010 :BAB1676C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=123 time=2890059611
2025-08-03 08:23:10.299011 :BAB1676D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 08:23:10.299011 :BAB1676E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 08:23:10.299011 :BAB1676F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 08:23:10.299012 :BAB16770:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 08:23:10.299012 :BAB16771:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 08:23:10.299517 :BAB16772: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-03 08:23:10.299562 :BAB1677A: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-03 08:33:11.122227 :BAB29D11:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 08:33:11.537247 :BAB29D4F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 08:33:11.532
2025-08-03 08:33:11.537249 :BAB29D50:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18416 pso_num=22 pso_serial#=218
2025-08-03 08:33:11.537390 :BAB29D51:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18416 osp_idx=0 osp_ver=477140 osp_pg=0 (spawn #450622)
2025-08-03 08:33:11.537391 :BAB29D52: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-03 08:33:11.537392 :BAB29D53:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=124 time=2890660845
2025-08-03 08:33:11.537392 :BAB29D54:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 08:33:11.537392 :BAB29D55:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 08:33:11.537393 :BAB29D56:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 08:33:11.537393 :BAB29D57:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 08:33:11.537393 :BAB29D58:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 08:33:11.537908 :BAB29D59: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-03 08:33:11.537953 :BAB29D60: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-03 08:43:12.418450 :BAB36725:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 08:43:12.745973 :BAB36768:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 08:43:12.740
2025-08-03 08:43:12.745975 :BAB36769:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7436 pso_num=22 pso_serial#=219
2025-08-03 08:43:12.746113 :BAB3676A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7436 osp_idx=0 osp_ver=477141 osp_pg=0 (spawn #450644)
2025-08-03 08:43:12.746113 :BAB3676B: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-03 08:43:12.746114 :BAB3676C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=125 time=2891262048
2025-08-03 08:43:12.746114 :BAB3676D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 08:43:12.746114 :BAB3676E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 08:43:12.746115 :BAB3676F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 08:43:12.746115 :BAB36770:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 08:43:12.746115 :BAB36771:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 08:43:12.746636 :BAB36772: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-03 08:43:12.746686 :BAB36779: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-03 08:53:13.544658 :BAB43438:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 08:53:13.908764 :BAB4347A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 08:53:13.904
2025-08-03 08:53:13.908766 :BAB4347B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20476 pso_num=22 pso_serial#=220
2025-08-03 08:53:13.908907 :BAB4347C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20476 osp_idx=0 osp_ver=477142 osp_pg=0 (spawn #450667)
2025-08-03 08:53:13.908908 :BAB4347D: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-03 08:53:13.908908 :BAB4347E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=126 time=2891863220
2025-08-03 08:53:13.908908 :BAB4347F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 08:53:13.908908 :BAB43480:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 08:53:13.908909 :BAB43481:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 08:53:13.908909 :BAB43482:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 08:53:13.908909 :BAB43483:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 08:53:13.909397 :BAB43484: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-03 08:53:13.909446 :BAB4348C: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-03 09:03:14.724951 :BAB4FFC9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 09:03:15.044624 :BAB5000B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 09:03:15.044
2025-08-03 09:03:15.044628 :BAB5000C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18108 pso_num=22 pso_serial#=221
2025-08-03 09:03:15.044779 :BAB5000D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18108 osp_idx=0 osp_ver=477143 osp_pg=0 (spawn #450692)
2025-08-03 09:03:15.044780 :BAB5000E: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-03 09:03:15.044780 :BAB5000F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=127 time=2892464345
2025-08-03 09:03:15.044781 :BAB50010:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 09:03:15.044781 :BAB50011:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 09:03:15.044781 :BAB50012:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 09:03:15.044782 :BAB50013:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 09:03:15.044782 :BAB50014:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 09:03:15.045302 :BAB50015: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-03 09:03:15.045346 :BAB5001B: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-03 09:13:15.913202 :BAB5D75F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 09:13:16.214800 :BAB5D7B0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 09:13:16.209
2025-08-03 09:13:16.214802 :BAB5D7B1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20056 pso_num=22 pso_serial#=222
2025-08-03 09:13:16.214943 :BAB5D7B2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20056 osp_idx=0 osp_ver=477144 osp_pg=0 (spawn #450715)
2025-08-03 09:13:16.214944 :BAB5D7B3: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-03 09:13:16.214944 :BAB5D7B4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=128 time=2893065517
2025-08-03 09:13:16.214945 :BAB5D7B5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 09:13:16.214945 :BAB5D7B6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 09:13:16.214945 :BAB5D7B7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 09:13:16.214946 :BAB5D7B8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 09:13:16.214946 :BAB5D7B9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 09:13:16.215431 :BAB5D7BA: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-03 09:13:16.215476 :BAB5D7C3: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-03 09:23:17.032241 :BAB6A37F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 09:23:17.380795 :BAB6A3D4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 09:23:17.375
2025-08-03 09:23:17.380797 :BAB6A3D5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20360 pso_num=22 pso_serial#=223
2025-08-03 09:23:17.380970 :BAB6A3D6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20360 osp_idx=0 osp_ver=477145 osp_pg=0 (spawn #450738)
2025-08-03 09:23:17.380971 :BAB6A3D7: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-03 09:23:17.380972 :BAB6A3D8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=129 time=2893666689
2025-08-03 09:23:17.380972 :BAB6A3D9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 09:23:17.380973 :BAB6A3DA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 09:23:17.380973 :BAB6A3DB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 09:23:17.380974 :BAB6A3DC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 09:23:17.380974 :BAB6A3DD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 09:23:17.381534 :BAB6A3DE: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-03 09:23:17.381582 :BAB6A3E5: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-03 09:33:18.168723 :BAB77061:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 09:33:18.470306 :BAB770B5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 09:33:18.465
2025-08-03 09:33:18.470308 :BAB770B6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19224 pso_num=22 pso_serial#=224
2025-08-03 09:33:18.470444 :BAB770B7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19224 osp_idx=0 osp_ver=477146 osp_pg=0 (spawn #450761)
2025-08-03 09:33:18.470445 :BAB770B8: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-03 09:33:18.470445 :BAB770B9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=130 time=2894267783
2025-08-03 09:33:18.470445 :BAB770BA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 09:33:18.470445 :BAB770BB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 09:33:18.470446 :BAB770BC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 09:33:18.470446 :BAB770BD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 09:33:18.470446 :BAB770BE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 09:33:18.470947 :BAB770BF: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-03 09:33:18.470993 :BAB770C6: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-03 09:43:19.332352 :BAB83D45:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 09:43:19.759223 :BAB83D89:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 09:43:19.754
2025-08-03 09:43:19.759225 :BAB83D8A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20052 pso_num=22 pso_serial#=225
2025-08-03 09:43:19.759375 :BAB83D8B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20052 osp_idx=0 osp_ver=477147 osp_pg=0 (spawn #450783)
2025-08-03 09:43:19.759376 :BAB83D8C: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-03 09:43:19.759377 :BAB83D8D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=131 time=2894869064
2025-08-03 09:43:19.759377 :BAB83D8E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 09:43:19.759377 :BAB83D8F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 09:43:19.759378 :BAB83D90:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 09:43:19.759378 :BAB83D91:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 09:43:19.759379 :BAB83D92:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 09:43:19.759910 :BAB83D93: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-03 09:43:19.759965 :BAB83D99: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-03 09:52:51.559517 :BAB8FF02: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-03 09:53:20.961007 :BAB90910: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-03 10:03:21.715021 :BAB9DD3D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-03 10:03:22.079055 :BAB9DD80:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-03 10:03:22.074
2025-08-03 10:03:22.079058 :BAB9DD81:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17016 pso_num=22 pso_serial#=226
2025-08-03 10:03:22.079193 :BAB9DD82:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17016 osp_idx=0 osp_ver=477148 osp_pg=0 (spawn #450830)
2025-08-03 10:03:22.079193 :BAB9DD83: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-03 10:03:22.079194 :BAB9DD84:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=132 time=2896071392
2025-08-03 10:03:22.079195 :BAB9DD85:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-03 10:03:22.079195 :BAB9DD86:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-03 10:03:22.079195 :BAB9DD87:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-03 10:03:22.079196 :BAB9DD88:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-03 10:03:22.079196 :BAB9DD89:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-03 10:03:22.079701 :BAB9DD8A: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-03 10:03:22.079747 :BAB9DD92: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: 17016, W000)