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


*** 2025-08-10 08:01:20.019
*** SESSION ID:(201.56733) 2025-08-10 08:01:20.019
 

*** 2025-08-10 08:01:20.019
Process diagnostic dump for ORACLE.EXE (W000), OS id=21100,
pid: 25, proc_ser: 117, sid: 201, sess_ser: 56733 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=97 seq_num=98 snap_id=1
    wait times: snap=2.171829 sec, exc=2.171829 sec, total=2.171829 sec
    wait times: max=5.000000 sec, heur=8 min 2 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.000031 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=96 seq_num=97 snap_id=1
    wait times: snap=5.004610 sec, exc=5.004610 sec, total=5.004610 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=95 seq_num=96 snap_id=1
    wait times: snap=5.004610 sec, exc=5.004610 sec, total=5.004610 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=94 seq_num=95 snap_id=1
    wait times: snap=5.009743 sec, exc=5.009743 sec, total=5.009743 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=93 seq_num=94 snap_id=1
    wait times: snap=5.003197 sec, exc=5.003197 sec, total=5.003197 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000040 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=92 seq_num=93 snap_id=1
    wait times: snap=5.000468 sec, exc=5.000468 sec, total=5.000468 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=91 seq_num=92 snap_id=1
    wait times: snap=5.001421 sec, exc=5.001421 sec, total=5.001421 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000037 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=90 seq_num=91 snap_id=1
    wait times: snap=5.008042 sec, exc=5.008042 sec, total=5.008042 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=89 seq_num=90 snap_id=1
    wait times: snap=5.014729 sec, exc=5.014729 sec, total=5.014729 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000030 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=88 seq_num=89 snap_id=1
    wait times: snap=5.001690 sec, exc=5.001690 sec, total=5.001690 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=87 seq_num=88 snap_id=1
    wait times: snap=5.001121 sec, exc=5.001121 sec, total=5.001121 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
Sampled Session History of session 201 serial 56733
---------------------------------------------------
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,                                            07:59:20 - 08:01:20]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-10 08:01:20.019
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 25 (osid: 21100, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-10 05:33:00.727222 :BDBBA181:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20112 (legacy spawn)
2025-08-10 05:33:00.733651 :BDBBA182:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-10 05:33:00.733836 :BDBBA18B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-08-10 05:33:00.734678 :BDBBA18C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 05:33:00.754853 :BDBBA1C4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 05:33:00.750
2025-08-10 05:33:00.754855 :BDBBA1C5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1876 pso_num=25 pso_serial#=104
2025-08-10 05:33:00.754990 :BDBBA1C6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=1876 osp_idx=23 osp_ver=233796 osp_pg=0 (spawn #473538)
2025-08-10 05:33:00.754991 :BDBBA1C7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 05:33:00.754991 :BDBBA1C8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=252 time=3484650064
2025-08-10 05:33:00.754991 :BDBBA1C9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 05:33:00.754992 :BDBBA1CA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 05:33:00.754992 :BDBBA1CB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 05:33:00.754992 :BDBBA1CC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 05:33:00.754993 :BDBBA1CD:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 05:33:00.755473 :BDBBA1CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 05:33:00.755516 :BDBBA1D5:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 05:43:01.557782 :BDBC63B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 05:43:01.968517 :BDBC6413:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 05:43:01.963
2025-08-10 05:43:01.968519 :BDBC6414:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17956 pso_num=25 pso_serial#=105
2025-08-10 05:43:01.968663 :BDBC6415:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17956 osp_idx=23 osp_ver=233797 osp_pg=0 (spawn #473561)
2025-08-10 05:43:01.968663 :BDBC6416:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 05:43:01.968664 :BDBC6417:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=253 time=3485251267
2025-08-10 05:43:01.968664 :BDBC6418:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 05:43:01.968664 :BDBC6419:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 05:43:01.968665 :BDBC641A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 05:43:01.968665 :BDBC641B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 05:43:01.968665 :BDBC641C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 05:43:01.969167 :BDBC641D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 05:43:01.969212 :BDBC6425:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 05:53:02.894853 :BDBD2573:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 05:53:03.181828 :BDBD25DD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 05:53:03.176
2025-08-10 05:53:03.181831 :BDBD25DE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21140 pso_num=25 pso_serial#=106
2025-08-10 05:53:03.181970 :BDBD25DF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21140 osp_idx=23 osp_ver=233798 osp_pg=0 (spawn #473584)
2025-08-10 05:53:03.181971 :BDBD25E0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 05:53:03.181971 :BDBD25E1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=254 time=3485852486
2025-08-10 05:53:03.181972 :BDBD25E2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 05:53:03.181972 :BDBD25E3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 05:53:03.181973 :BDBD25E4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 05:53:03.181973 :BDBD25E5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 05:53:03.181973 :BDBD25E6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 05:53:03.182471 :BDBD25E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 05:53:03.182514 :BDBD25EF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 06:03:03.979677 :BDBDF481:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 06:03:04.343917 :BDBDF4DB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 06:03:04.339
2025-08-10 06:03:04.343919 :BDBDF4DC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7692 pso_num=25 pso_serial#=107
2025-08-10 06:03:04.344048 :BDBDF4DD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7692 osp_idx=23 osp_ver=233799 osp_pg=0 (spawn #473614)
2025-08-10 06:03:04.344049 :BDBDF4DE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 06:03:04.344050 :BDBDF4DF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=255 time=3486453658
2025-08-10 06:03:04.344050 :BDBDF4E0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 06:03:04.344051 :BDBDF4E1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 06:03:04.344052 :BDBDF4E2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 06:03:04.344053 :BDBDF4E3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 06:03:04.344053 :BDBDF4E4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 06:03:04.344539 :BDBDF4E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 06:03:04.344589 :BDBDF4EF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 06:04:32.544890 :BDBE1171:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
2025-08-10 06:13:05.561116 :BDBEC129:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
2025-08-10 06:23:06.425361 :BDBF86D3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 06:23:06.757987 :BDBF870E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 06:23:06.753
2025-08-10 06:23:06.757989 :BDBF870F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10676 pso_num=25 pso_serial#=108
2025-08-10 06:23:06.758128 :BDBF8710:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10676 osp_idx=23 osp_ver=233800 osp_pg=0 (spawn #473660)
2025-08-10 06:23:06.758128 :BDBF8711:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 06:23:06.758129 :BDBF8712:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=0 time=3487656064
2025-08-10 06:23:06.758129 :BDBF8713:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 06:23:06.758129 :BDBF8714:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 06:23:06.758129 :BDBF8715:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 06:23:06.758130 :BDBF8716:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 06:23:06.758130 :BDBF8717:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 06:23:06.758623 :BDBF8718:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 06:23:06.758670 :BDBF871F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 06:33:07.521817 :BDC04917:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 06:33:07.870334 :BDC04957:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 06:33:07.866
2025-08-10 06:33:07.870335 :BDC04958:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21004 pso_num=25 pso_serial#=109
2025-08-10 06:33:07.870477 :BDC04959:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21004 osp_idx=23 osp_ver=233801 osp_pg=0 (spawn #473682)
2025-08-10 06:33:07.870477 :BDC0495A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 06:33:07.870478 :BDC0495B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=1 time=3488257173
2025-08-10 06:33:07.870478 :BDC0495C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 06:33:07.870479 :BDC0495D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 06:33:07.870479 :BDC0495E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 06:33:07.870479 :BDC0495F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 06:33:07.870480 :BDC04960:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 06:33:07.870967 :BDC04961:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 06:33:07.871015 :BDC0496A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 06:43:08.801844 :BDC10C53:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 06:43:09.082296 :BDC10C9E:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 06:43:09.077
2025-08-10 06:43:09.082298 :BDC10C9F:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16876 pso_num=25 pso_serial#=110
2025-08-10 06:43:09.082433 :BDC10CA0:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16876 osp_idx=23 osp_ver=233802 osp_pg=0 (spawn #473705)
2025-08-10 06:43:09.082433 :BDC10CA1:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 06:43:09.082434 :BDC10CA2:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=2 time=3488858392
2025-08-10 06:43:09.082434 :BDC10CA3:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 06:43:09.082434 :BDC10CA4:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 06:43:09.082435 :BDC10CA5:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 06:43:09.082435 :BDC10CA6:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 06:43:09.082435 :BDC10CA7:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 06:43:09.082983 :BDC10CA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 06:43:09.083029 :BDC10CB0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 06:53:09.915855 :BDC1CE2D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 06:53:10.170538 :BDC1CE7D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 06:53:10.165
2025-08-10 06:53:10.170540 :BDC1CE7E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18624 pso_num=25 pso_serial#=111
2025-08-10 06:53:10.170682 :BDC1CE7F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18624 osp_idx=23 osp_ver=233803 osp_pg=0 (spawn #473728)
2025-08-10 06:53:10.170683 :BDC1CE80:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 06:53:10.170683 :BDC1CE81:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=3 time=3489459470
2025-08-10 06:53:10.170683 :BDC1CE82:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 06:53:10.170684 :BDC1CE83:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 06:53:10.170684 :BDC1CE84:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 06:53:10.170684 :BDC1CE85:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 06:53:10.170685 :BDC1CE86:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 06:53:10.171212 :BDC1CE87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 06:53:10.171259 :BDC1CE8F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 07:03:11.019472 :BDC29354:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 07:03:11.321016 :BDC2939F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 07:03:11.316
2025-08-10 07:03:11.321018 :BDC293A0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20384 pso_num=25 pso_serial#=112
2025-08-10 07:03:11.321179 :BDC293A1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20384 osp_idx=23 osp_ver=233804 osp_pg=0 (spawn #473752)
2025-08-10 07:03:11.321180 :BDC293A2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 07:03:11.321180 :BDC293A3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=4 time=3490060626
2025-08-10 07:03:11.321181 :BDC293A4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 07:03:11.321181 :BDC293A5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 07:03:11.321182 :BDC293A6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 07:03:11.321184 :BDC293A7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 07:03:11.321185 :BDC293A8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 07:03:11.321695 :BDC293A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 07:03:11.321741 :BDC293B2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 07:13:12.154581 :BDC35AD9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 07:13:12.440691 :BDC35B13:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 07:13:12.436
2025-08-10 07:13:12.440693 :BDC35B14:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18368 pso_num=25 pso_serial#=113
2025-08-10 07:13:12.440829 :BDC35B15:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18368 osp_idx=23 osp_ver=233805 osp_pg=0 (spawn #473776)
2025-08-10 07:13:12.440830 :BDC35B16:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 07:13:12.440830 :BDC35B17:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=5 time=3490661751
2025-08-10 07:13:12.440831 :BDC35B18:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 07:13:12.440831 :BDC35B19:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 07:13:12.440831 :BDC35B1A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 07:13:12.440832 :BDC35B1B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 07:13:12.440832 :BDC35B1C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 07:13:12.441324 :BDC35B1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 07:13:12.441370 :BDC35B24:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 07:23:13.311928 :BDC41F33:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 07:23:13.707191 :BDC41F6C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 07:23:13.702
2025-08-10 07:23:13.707192 :BDC41F6D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11796 pso_num=25 pso_serial#=114
2025-08-10 07:23:13.707330 :BDC41F6E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11796 osp_idx=23 osp_ver=233806 osp_pg=0 (spawn #473799)
2025-08-10 07:23:13.707331 :BDC41F6F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 07:23:13.707331 :BDC41F70:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=6 time=3491263017
2025-08-10 07:23:13.707332 :BDC41F71:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 07:23:13.707332 :BDC41F72:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 07:23:13.707332 :BDC41F73:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 07:23:13.707333 :BDC41F74:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 07:23:13.707333 :BDC41F75:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 07:23:13.707825 :BDC41F76:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 07:23:13.707870 :BDC41F7D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 07:33:14.460580 :BDC4E1C5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 07:33:14.845400 :BDC4E1FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 07:33:14.841
2025-08-10 07:33:14.845402 :BDC4E1FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18400 pso_num=25 pso_serial#=115
2025-08-10 07:33:14.845552 :BDC4E1FC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18400 osp_idx=23 osp_ver=233807 osp_pg=0 (spawn #473821)
2025-08-10 07:33:14.845553 :BDC4E1FD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 07:33:14.845553 :BDC4E1FE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=7 time=3491864158
2025-08-10 07:33:14.845554 :BDC4E1FF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 07:33:14.845554 :BDC4E200:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 07:33:14.845554 :BDC4E201:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 07:33:14.845554 :BDC4E202:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 07:33:14.845555 :BDC4E203:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 07:33:14.846052 :BDC4E204:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 07:33:14.846096 :BDC4E20C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 07:43:15.790584 :BDC5A571:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 07:43:16.029745 :BDC5A5AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 07:43:16.024
2025-08-10 07:43:16.029747 :BDC5A5AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21164 pso_num=25 pso_serial#=116
2025-08-10 07:43:16.029883 :BDC5A5AD:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21164 osp_idx=23 osp_ver=233808 osp_pg=0 (spawn #473844)
2025-08-10 07:43:16.029884 :BDC5A5AE:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 07:43:16.029884 :BDC5A5AF:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=8 time=3492465329
2025-08-10 07:43:16.029885 :BDC5A5B0:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 07:43:16.029886 :BDC5A5B1:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 07:43:16.029887 :BDC5A5B2:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 07:43:16.029887 :BDC5A5B3:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 07:43:16.029887 :BDC5A5B4:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 07:43:16.030368 :BDC5A5B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 07:43:16.030412 :BDC5A5BE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-10 07:53:16.809351 :BDC66753:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-08-10 07:53:17.204540 :BDC6678D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-10 07:53:17.200
2025-08-10 07:53:17.204542 :BDC6678E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21100 pso_num=25 pso_serial#=117
2025-08-10 07:53:17.204676 :BDC6678F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21100 osp_idx=23 osp_ver=233809 osp_pg=0 (spawn #473867)
2025-08-10 07:53:17.204676 :BDC66790:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-10 07:53:17.204677 :BDC66791:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=9 time=3493066517
2025-08-10 07:53:17.204677 :BDC66792:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 07:53:17.204677 :BDC66793:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 07:53:17.204677 :BDC66794:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 07:53:17.204677 :BDC66795:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 07:53:17.204678 :BDC66796:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 07:53:17.205168 :BDC66797:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-10 07:53:17.205220 :BDC6679D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 25 (osid: 21100, W000)