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


*** 2025-06-25 22:00:06.850
*** SESSION ID:(177.52007) 2025-06-25 22:00:06.850
 

*** 2025-06-25 22:00:06.850
Process diagnostic dump for ORACLE.EXE (W000), OS id=13460,
pid: 22, proc_ser: 108, sid: 177, sess_ser: 52007 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=45 seq_num=46 snap_id=1
    wait times: snap=0.678655 sec, exc=0.678655 sec, total=0.678655 sec
    wait times: max=5.000000 sec, heur=3 min 40 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=44 seq_num=45 snap_id=1
    wait times: snap=5.000288 sec, exc=5.000288 sec, total=5.000288 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=43 seq_num=44 snap_id=1
    wait times: snap=5.007841 sec, exc=5.007841 sec, total=5.007841 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=42 seq_num=43 snap_id=1
    wait times: snap=5.003121 sec, exc=5.003121 sec, total=5.003121 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=41 seq_num=42 snap_id=1
    wait times: snap=5.009202 sec, exc=5.009202 sec, total=5.009202 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=40 seq_num=41 snap_id=1
    wait times: snap=5.007231 sec, exc=5.007231 sec, total=5.007231 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=39 seq_num=40 snap_id=1
    wait times: snap=5.001244 sec, exc=5.001244 sec, total=5.001244 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=38 seq_num=39 snap_id=1
    wait times: snap=5.000315 sec, exc=5.000315 sec, total=5.000315 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=37 seq_num=38 snap_id=1
    wait times: snap=5.009195 sec, exc=5.009195 sec, total=5.009195 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=36 seq_num=37 snap_id=1
    wait times: snap=5.009324 sec, exc=5.009324 sec, total=5.009324 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=35 seq_num=36 snap_id=1
    wait times: snap=5.014718 sec, exc=5.014718 sec, total=5.014718 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 52007
---------------------------------------------------
The sampled session history is constructed by sampling
the target session every 1 second. The sampling process
captures at each sample if the session is in a non-idle wait,
an idle wait, or not in a wait. If the session is in a
non-idle wait then one interval is shown for all the samples
the session was in the same non-idle wait. If the
session is in an idle wait or not in a wait for
consecutive samples then one interval is shown for all
the consecutive samples. Though we display these consecutive
samples  in a single interval the session may NOT be continuously
idle or not in a wait (the sampling process does not know).
 
The history is displayed in reverse chronological order.
 
sample interval: 1 sec, max history 120 sec
---------------------------------------------------
  [121 samples,                                            21:58:06 - 22:00:06]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-25 22:00:06.850
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 13460, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-25 19:26:07.722550 :A98F738C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 19:26:07.723033 :A98F738D: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-25 19:26:07.723079 :A98F7395: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-25 19:36:08.551044 :A99046B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 19:36:08.915235 :A99046EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 19:36:08.916
2025-06-25 19:36:08.915237 :A99046ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17148 pso_num=22 pso_serial#=95
2025-06-25 19:36:08.915421 :A99046EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17148 osp_idx=0 osp_ver=352067 osp_pg=0 (spawn #322158)
2025-06-25 19:36:08.915422 :A99046EF: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-25 19:36:08.915423 :A99046F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=60 time=3855912110
2025-06-25 19:36:08.915424 :A99046F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 19:36:08.915424 :A99046F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 19:36:08.915424 :A99046F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 19:36:08.915425 :A99046F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 19:36:08.915425 :A99046F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 19:36:08.915952 :A99046F6: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-25 19:36:08.915993 :A99046FE: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-25 19:46:09.789211 :A9910E77:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 19:46:10.200369 :A9910EC1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 19:46:10.195
2025-06-25 19:46:10.200371 :A9910EC2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16392 pso_num=22 pso_serial#=96
2025-06-25 19:46:10.200511 :A9910EC3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16392 osp_idx=0 osp_ver=352068 osp_pg=0 (spawn #322178)
2025-06-25 19:46:10.200511 :A9910EC4: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-25 19:46:10.200512 :A9910EC5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=61 time=3856513391
2025-06-25 19:46:10.200512 :A9910EC6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 19:46:10.200512 :A9910EC7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 19:46:10.200512 :A9910EC8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 19:46:10.200513 :A9910EC9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 19:46:10.200513 :A9910ECA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 19:46:10.201011 :A9910ECB: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-25 19:46:10.201059 :A9910ED2: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-25 19:56:11.142935 :A991D6F0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 19:56:11.429021 :A991D725:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 19:56:11.424
2025-06-25 19:56:11.429023 :A991D726:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17380 pso_num=22 pso_serial#=97
2025-06-25 19:56:11.429162 :A991D727:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17380 osp_idx=0 osp_ver=352069 osp_pg=0 (spawn #322201)
2025-06-25 19:56:11.429163 :A991D728: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-25 19:56:11.429163 :A991D729:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=62 time=3857114625
2025-06-25 19:56:11.429163 :A991D72A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 19:56:11.429164 :A991D72B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 19:56:11.429164 :A991D72C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 19:56:11.429164 :A991D72D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 19:56:11.429165 :A991D72E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 19:56:11.429661 :A991D72F: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-25 19:56:11.429706 :A991D736: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-25 20:06:12.170211 :A992A710:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 20:06:12.612411 :A992A76C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 20:06:12.607
2025-06-25 20:06:12.612413 :A992A76D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11076 pso_num=22 pso_serial#=98
2025-06-25 20:06:12.612551 :A992A76E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11076 osp_idx=0 osp_ver=352070 osp_pg=0 (spawn #322227)
2025-06-25 20:06:12.612552 :A992A76F: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-25 20:06:12.612552 :A992A770:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=63 time=3857715797
2025-06-25 20:06:12.612553 :A992A771:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 20:06:12.612554 :A992A772:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 20:06:12.612555 :A992A773:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 20:06:12.612555 :A992A774:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 20:06:12.612555 :A992A775:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 20:06:12.613040 :A992A776: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-25 20:06:12.613100 :A992A780: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-25 20:16:13.503495 :A993717A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 20:16:13.898843 :A99371CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 20:16:13.894
2025-06-25 20:16:13.898845 :A99371CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6104 pso_num=22 pso_serial#=99
2025-06-25 20:16:13.898981 :A99371CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6104 osp_idx=0 osp_ver=352071 osp_pg=0 (spawn #322249)
2025-06-25 20:16:13.898982 :A99371CD: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-25 20:16:13.898982 :A99371CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=64 time=3858317094
2025-06-25 20:16:13.898983 :A99371CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 20:16:13.898983 :A99371D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 20:16:13.898983 :A99371D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 20:16:13.898984 :A99371D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 20:16:13.898984 :A99371D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 20:16:13.899471 :A99371D4: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-25 20:16:13.899516 :A99371DB: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-25 20:26:14.763631 :A994398F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 20:26:15.096484 :A99439C6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 20:26:15.092
2025-06-25 20:26:15.096487 :A99439C7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14864 pso_num=22 pso_serial#=100
2025-06-25 20:26:15.096639 :A99439C8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14864 osp_idx=0 osp_ver=352072 osp_pg=0 (spawn #322272)
2025-06-25 20:26:15.096640 :A99439C9: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-25 20:26:15.096640 :A99439CA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=3858918282
2025-06-25 20:26:15.096641 :A99439CB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 20:26:15.096642 :A99439CC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 20:26:15.096642 :A99439CD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 20:26:15.096642 :A99439CE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 20:26:15.096643 :A99439CF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 20:26:15.097199 :A99439D0: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-25 20:26:15.097245 :A99439D8: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-25 20:30:06.900876 :A994860C: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-25 20:36:16.278261 :A9950206: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-25 20:46:17.146443 :A995C998:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 20:46:17.385487 :A995C9CE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 20:46:17.380
2025-06-25 20:46:17.385489 :A995C9CF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16104 pso_num=22 pso_serial#=101
2025-06-25 20:46:17.385629 :A995C9D0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16104 osp_idx=0 osp_ver=352073 osp_pg=0 (spawn #322316)
2025-06-25 20:46:17.385630 :A995C9D1: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-25 20:46:17.385630 :A995C9D2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=3860120579
2025-06-25 20:46:17.385631 :A995C9D3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 20:46:17.385631 :A995C9D4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 20:46:17.385631 :A995C9D5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 20:46:17.385632 :A995C9D6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 20:46:17.385632 :A995C9D7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 20:46:17.386124 :A995C9D8: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-25 20:46:17.386172 :A995C9E2: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-25 20:56:18.230115 :A9969139:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 20:56:18.640961 :A9969191:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 20:56:18.636
2025-06-25 20:56:18.640963 :A9969192:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18204 pso_num=22 pso_serial#=102
2025-06-25 20:56:18.641106 :A9969193:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18204 osp_idx=0 osp_ver=352074 osp_pg=0 (spawn #322339)
2025-06-25 20:56:18.641107 :A9969194: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-25 20:56:18.641107 :A9969195:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=67 time=3860721829
2025-06-25 20:56:18.641108 :A9969196:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 20:56:18.641108 :A9969197:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 20:56:18.641108 :A9969198:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 20:56:18.641109 :A9969199:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 20:56:18.641109 :A996919A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 20:56:18.641611 :A996919B: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-25 20:56:18.641658 :A99691A3: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-25 21:06:19.482361 :A9976297:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 21:06:19.846722 :A99762E7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 21:06:19.841
2025-06-25 21:06:19.846724 :A99762E8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17896 pso_num=22 pso_serial#=103
2025-06-25 21:06:19.846872 :A99762E9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17896 osp_idx=0 osp_ver=352075 osp_pg=0 (spawn #322365)
2025-06-25 21:06:19.846872 :A99762EA: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-25 21:06:19.846873 :A99762EB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=68 time=3861323032
2025-06-25 21:06:19.846873 :A99762EC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 21:06:19.846873 :A99762ED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 21:06:19.846873 :A99762EE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 21:06:19.846874 :A99762EF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 21:06:19.846874 :A99762F0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 21:06:19.847392 :A99762F1: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-25 21:06:19.847443 :A99762F8: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-25 21:16:20.765924 :A9982CDE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 21:16:21.130046 :A9982D24:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 21:16:21.125
2025-06-25 21:16:21.130048 :A9982D25:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13916 pso_num=22 pso_serial#=104
2025-06-25 21:16:21.130186 :A9982D26:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13916 osp_idx=0 osp_ver=352076 osp_pg=0 (spawn #322387)
2025-06-25 21:16:21.130186 :A9982D27: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-25 21:16:21.130187 :A9982D28:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=69 time=3861924313
2025-06-25 21:16:21.130187 :A9982D29:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 21:16:21.130188 :A9982D2A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 21:16:21.130188 :A9982D2B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 21:16:21.130189 :A9982D2C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 21:16:21.130189 :A9982D2D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 21:16:21.130697 :A9982D2E: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-25 21:16:21.130750 :A9982D38: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-25 21:26:22.049130 :A998F41E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 21:26:22.366329 :A998F45C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 21:26:22.361
2025-06-25 21:26:22.366331 :A998F45D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13760 pso_num=22 pso_serial#=105
2025-06-25 21:26:22.366464 :A998F45E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13760 osp_idx=0 osp_ver=352077 osp_pg=0 (spawn #322410)
2025-06-25 21:26:22.366464 :A998F45F: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-25 21:26:22.366465 :A998F460:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=70 time=3862525563
2025-06-25 21:26:22.366465 :A998F461:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 21:26:22.366465 :A998F462:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 21:26:22.366466 :A998F463:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 21:26:22.366466 :A998F464:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 21:26:22.366466 :A998F465:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 21:26:22.366962 :A998F466: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-25 21:26:22.367007 :A998F46E: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-25 21:36:23.256998 :A999BBCF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 21:36:23.511645 :A999BC19:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 21:36:23.507
2025-06-25 21:36:23.511648 :A999BC1A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16548 pso_num=22 pso_serial#=106
2025-06-25 21:36:23.511784 :A999BC1B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16548 osp_idx=0 osp_ver=352078 osp_pg=0 (spawn #322433)
2025-06-25 21:36:23.511785 :A999BC1C: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-25 21:36:23.511785 :A999BC1D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=71 time=3863126704
2025-06-25 21:36:23.511786 :A999BC1E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 21:36:23.511786 :A999BC1F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 21:36:23.511786 :A999BC20:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 21:36:23.511787 :A999BC21:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 21:36:23.511788 :A999BC22:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 21:36:23.512261 :A999BC23: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-25 21:36:23.512305 :A999BC2C: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-25 21:46:24.345901 :A99A8344:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 21:46:24.753091 :A99A837D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 21:46:24.748
2025-06-25 21:46:24.753093 :A99A837E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12180 pso_num=22 pso_serial#=107
2025-06-25 21:46:24.753229 :A99A837F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12180 osp_idx=0 osp_ver=352079 osp_pg=0 (spawn #322455)
2025-06-25 21:46:24.753230 :A99A8380: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-25 21:46:24.753230 :A99A8381:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=72 time=3863727938
2025-06-25 21:46:24.753231 :A99A8382:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 21:46:24.753231 :A99A8383:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 21:46:24.753232 :A99A8384:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 21:46:24.753232 :A99A8385:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 21:46:24.753233 :A99A8386:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 21:46:24.753723 :A99A8387: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-25 21:46:24.753771 :A99A8391: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-25 21:56:25.558560 :A99B4A9E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-25 21:56:25.922596 :A99B4AE7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-25 21:56:25.918
2025-06-25 21:56:25.922599 :A99B4AE8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13460 pso_num=22 pso_serial#=108
2025-06-25 21:56:25.922731 :A99B4AE9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13460 osp_idx=0 osp_ver=352080 osp_pg=0 (spawn #322478)
2025-06-25 21:56:25.922732 :A99B4AEA: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-25 21:56:25.922732 :A99B4AEB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=73 time=3864329110
2025-06-25 21:56:25.922732 :A99B4AEC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-25 21:56:25.922733 :A99B4AED:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-25 21:56:25.922733 :A99B4AEE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-25 21:56:25.922733 :A99B4AEF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-25 21:56:25.922734 :A99B4AF0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-25 21:56:25.923233 :A99B4AF1: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-25 21:56:25.923280 :A99B4AF9: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: 13460, W000)