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


*** 2025-09-02 22:19:01.168
*** SESSION ID:(201.29015) 2025-09-02 22:19:01.168
 

*** 2025-09-02 22:19:01.168
Process diagnostic dump for ORACLE.EXE (W000), OS id=19120,
pid: 25, proc_ser: 147, sid: 201, sess_ser: 29015 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=38 seq_num=39 snap_id=1
    wait times: snap=1.816493 sec, exc=1.816493 sec, total=1.816493 sec
    wait times: max=5.000000 sec, heur=3 min 7 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.000025 sec since current wait
 0: 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.012848 sec, exc=5.012848 sec, total=5.012848 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 1: 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.014732 sec, exc=5.014732 sec, total=5.014732 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 2: 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.014741 sec, exc=5.014741 sec, total=5.014741 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000016 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=34 seq_num=35 snap_id=1
    wait times: snap=5.007223 sec, exc=5.007223 sec, total=5.007223 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=33 seq_num=34 snap_id=1
    wait times: snap=5.001237 sec, exc=5.001237 sec, total=5.001237 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=32 seq_num=33 snap_id=1
    wait times: snap=5.000488 sec, exc=5.000488 sec, total=5.000488 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=31 seq_num=32 snap_id=1
    wait times: snap=5.004036 sec, exc=5.004036 sec, total=5.004036 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000044 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=30 seq_num=31 snap_id=1
    wait times: snap=5.014725 sec, exc=5.014725 sec, total=5.014725 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=29 seq_num=30 snap_id=1
    wait times: snap=5.008679 sec, exc=5.008679 sec, total=5.008679 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=28 seq_num=29 snap_id=1
    wait times: snap=5.000570 sec, exc=5.000570 sec, total=5.000570 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000033 sec of elapsed time
Sampled Session History of session 201 serial 29015
---------------------------------------------------
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,                                            22:17:01 - 22:19:01]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-02 22:19:01.168
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 25 (osid: 19120, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-02 19:45:35.999979 :C81D2D6A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 19:45:36.535967 :C81D2DC1:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 19:45:36.531
2025-09-02 19:45:36.535970 :C81D2DC2:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22476 pso_num=25 pso_serial#=134
2025-09-02 19:45:36.536105 :C81D2DC3:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22476 osp_idx=23 osp_ver=279627 osp_pg=0 (spawn #552180)
2025-09-02 19:45:36.536106 :C81D2DC4:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 19:45:36.536106 :C81D2DC5:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=89 time=1227987846
2025-09-02 19:45:36.536107 :C81D2DC6:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 19:45:36.536107 :C81D2DC7:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 19:45:36.536108 :C81D2DC8:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 19:45:36.536108 :C81D2DC9:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 19:45:36.536108 :C81D2DCA:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 19:45:36.536627 :C81D2DCB: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-09-02 19:45:36.536682 :C81D2DD5: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-09-02 19:55:37.310316 :C81DF36D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 19:55:37.815040 :C81DF3B6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 19:55:37.810
2025-09-02 19:55:37.815042 :C81DF3B7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19388 pso_num=25 pso_serial#=135
2025-09-02 19:55:37.815181 :C81DF3B8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19388 osp_idx=23 osp_ver=279628 osp_pg=0 (spawn #552203)
2025-09-02 19:55:37.815181 :C81DF3B9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 19:55:37.815182 :C81DF3BA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=90 time=1228589112
2025-09-02 19:55:37.815182 :C81DF3BB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 19:55:37.815182 :C81DF3BC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 19:55:37.815183 :C81DF3BD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 19:55:37.815183 :C81DF3BE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 19:55:37.815183 :C81DF3BF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 19:55:37.815694 :C81DF3C0: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-09-02 19:55:37.815740 :C81DF3C7: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-09-02 20:01:11.044459 :C81E6343: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-09-02 20:05:38.926379 :C81EBC82: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-09-02 20:15:39.670100 :C81F8ACD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 20:15:39.955725 :C81F8B15:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:15:39.951
2025-09-02 20:15:39.955728 :C81F8B16:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22920 pso_num=25 pso_serial#=136
2025-09-02 20:15:39.955905 :C81F8B17:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22920 osp_idx=23 osp_ver=279629 osp_pg=0 (spawn #552250)
2025-09-02 20:15:39.955906 :C81F8B18:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 20:15:39.955906 :C81F8B19:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=91 time=1229791268
2025-09-02 20:15:39.955907 :C81F8B1A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 20:15:39.955907 :C81F8B1B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 20:15:39.955907 :C81F8B1C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 20:15:39.955908 :C81F8B1D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 20:15:39.955908 :C81F8B1E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 20:15:39.956413 :C81F8B1F: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-09-02 20:15:39.956459 :C81F8B27: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-09-02 20:25:40.801453 :C82050B1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 20:25:41.181516 :C82050FD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:25:41.176
2025-09-02 20:25:41.181519 :C82050FE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20860 pso_num=25 pso_serial#=137
2025-09-02 20:25:41.181668 :C82050FF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20860 osp_idx=23 osp_ver=279630 osp_pg=0 (spawn #552273)
2025-09-02 20:25:41.181668 :C8205100:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 20:25:41.181669 :C8205101:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=92 time=1230392487
2025-09-02 20:25:41.181670 :C8205102:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 20:25:41.181671 :C8205103:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 20:25:41.181671 :C8205104:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 20:25:41.181671 :C8205105:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 20:25:41.181672 :C8205106:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 20:25:41.182199 :C8205107: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-09-02 20:25:41.182246 :C820510F: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-09-02 20:35:42.018019 :C8211721:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 20:35:42.460319 :C8211792:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:35:42.455
2025-09-02 20:35:42.460321 :C8211793:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22520 pso_num=25 pso_serial#=138
2025-09-02 20:35:42.460463 :C8211794:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22520 osp_idx=23 osp_ver=279631 osp_pg=0 (spawn #552295)
2025-09-02 20:35:42.460464 :C8211795:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 20:35:42.460464 :C8211796:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=93 time=1230993768
2025-09-02 20:35:42.460465 :C8211797:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 20:35:42.460465 :C8211798:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 20:35:42.460466 :C8211799:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 20:35:42.460466 :C821179A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 20:35:42.460466 :C821179B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 20:35:42.460965 :C821179C: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-09-02 20:35:42.461011 :C82117A5: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-09-02 20:45:43.238150 :C821DD1C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 20:45:43.602319 :C821DD9F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:45:43.597
2025-09-02 20:45:43.602322 :C821DDA0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18716 pso_num=25 pso_serial#=139
2025-09-02 20:45:43.602461 :C821DDA1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18716 osp_idx=23 osp_ver=279632 osp_pg=0 (spawn #552318)
2025-09-02 20:45:43.602462 :C821DDA2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 20:45:43.602462 :C821DDA3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=94 time=1231594908
2025-09-02 20:45:43.602462 :C821DDA4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 20:45:43.602463 :C821DDA5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 20:45:43.602463 :C821DDA6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 20:45:43.602463 :C821DDA7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 20:45:43.602464 :C821DDA8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 20:45:43.602968 :C821DDA9: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-09-02 20:45:43.603017 :C821DDB1: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-09-02 20:55:44.497596 :C822A369:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 20:55:44.840591 :C822A3DA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 20:55:44.835
2025-09-02 20:55:44.840593 :C822A3DB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22268 pso_num=25 pso_serial#=140
2025-09-02 20:55:44.840729 :C822A3DC:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22268 osp_idx=23 osp_ver=279633 osp_pg=0 (spawn #552342)
2025-09-02 20:55:44.840730 :C822A3DD:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 20:55:44.840731 :C822A3DE:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=95 time=1232196143
2025-09-02 20:55:44.840731 :C822A3DF:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 20:55:44.840732 :C822A3E0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 20:55:44.840732 :C822A3E1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 20:55:44.840732 :C822A3E2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 20:55:44.840732 :C822A3E3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 20:55:44.841239 :C822A3E4: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-09-02 20:55:44.841285 :C822A3ED: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-09-02 21:05:45.700833 :C8237233:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 21:05:46.018029 :C82372B0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:05:46.013
2025-09-02 21:05:46.018031 :C82372B1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22740 pso_num=25 pso_serial#=141
2025-09-02 21:05:46.018168 :C82372B2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22740 osp_idx=23 osp_ver=279634 osp_pg=0 (spawn #552367)
2025-09-02 21:05:46.018168 :C82372B3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 21:05:46.018169 :C82372B4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=96 time=1232797315
2025-09-02 21:05:46.018170 :C82372B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 21:05:46.018171 :C82372B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 21:05:46.018171 :C82372B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 21:05:46.018171 :C82372B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 21:05:46.018172 :C82372B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 21:05:46.018666 :C82372BA: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-09-02 21:05:46.018711 :C82372C2: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-09-02 21:15:46.819992 :C8243AF6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 21:15:47.246638 :C8243B3C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:15:47.242
2025-09-02 21:15:47.246641 :C8243B3D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21904 pso_num=25 pso_serial#=142
2025-09-02 21:15:47.246782 :C8243B3E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21904 osp_idx=23 osp_ver=279635 osp_pg=0 (spawn #552390)
2025-09-02 21:15:47.246783 :C8243B3F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 21:15:47.246783 :C8243B40:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=97 time=1233398549
2025-09-02 21:15:47.246784 :C8243B41:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 21:15:47.246784 :C8243B42:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 21:15:47.246784 :C8243B43:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 21:15:47.246785 :C8243B44:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 21:15:47.246785 :C8243B45:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 21:15:47.247294 :C8243B46: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-09-02 21:15:47.247344 :C8243B50: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-09-02 21:25:48.116194 :C82500F3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 21:25:48.402203 :C8250156:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:25:48.397
2025-09-02 21:25:48.402205 :C8250157:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23408 pso_num=25 pso_serial#=143
2025-09-02 21:25:48.402345 :C8250158:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23408 osp_idx=23 osp_ver=279636 osp_pg=0 (spawn #552413)
2025-09-02 21:25:48.402345 :C8250159:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 21:25:48.402346 :C825015A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=98 time=1233999705
2025-09-02 21:25:48.402346 :C825015B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 21:25:48.402347 :C825015C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 21:25:48.402347 :C825015D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 21:25:48.402348 :C825015E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 21:25:48.402348 :C825015F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 21:25:48.402841 :C8250160: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-09-02 21:25:48.402884 :C8250168: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-09-02 21:35:49.247364 :C825C7FB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 21:35:49.533372 :C825C834:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:35:49.528
2025-09-02 21:35:49.533374 :C825C835:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22964 pso_num=25 pso_serial#=144
2025-09-02 21:35:49.533513 :C825C836:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22964 osp_idx=23 osp_ver=279637 osp_pg=0 (spawn #552435)
2025-09-02 21:35:49.533514 :C825C837:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 21:35:49.533514 :C825C838:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=99 time=1234600830
2025-09-02 21:35:49.533515 :C825C839:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 21:35:49.533515 :C825C83A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 21:35:49.533515 :C825C83B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 21:35:49.533516 :C825C83C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 21:35:49.533516 :C825C83D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 21:35:49.534022 :C825C83E: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-09-02 21:35:49.534068 :C825C846: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-09-02 21:45:50.293993 :C8268E57:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 21:45:50.579925 :C8268E98:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:45:50.575
2025-09-02 21:45:50.579928 :C8268E99:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20172 pso_num=25 pso_serial#=145
2025-09-02 21:45:50.580065 :C8268E9A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20172 osp_idx=23 osp_ver=279638 osp_pg=0 (spawn #552458)
2025-09-02 21:45:50.580065 :C8268E9B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 21:45:50.580066 :C8268E9C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=100 time=1235201877
2025-09-02 21:45:50.580066 :C8268E9D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 21:45:50.580067 :C8268E9E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 21:45:50.580067 :C8268E9F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 21:45:50.580067 :C8268EA0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 21:45:50.580067 :C8268EA1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 21:45:50.580577 :C8268EA2: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-09-02 21:45:50.580620 :C8268EAA: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-09-02 21:55:51.384575 :C8275441:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 21:55:51.639439 :C8275476:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 21:55:51.634
2025-09-02 21:55:51.639441 :C8275477:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22844 pso_num=25 pso_serial#=146
2025-09-02 21:55:51.639585 :C8275478:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=22844 osp_idx=23 osp_ver=279639 osp_pg=0 (spawn #552481)
2025-09-02 21:55:51.639585 :C8275479:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 21:55:51.639586 :C827547A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=101 time=1235802940
2025-09-02 21:55:51.639586 :C827547B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 21:55:51.639587 :C827547C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 21:55:51.639587 :C827547D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 21:55:51.639587 :C827547E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 21:55:51.639587 :C827547F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 21:55:51.640078 :C8275480: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-09-02 21:55:51.640121 :C827548A: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-09-02 22:01:11.858482 :C827CAF3: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-09-02 22:05:52.925697 :C82834AA: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-09-02 22:15:53.683185 :C828FAED:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-09-02 22:15:54.088645 :C828FB26:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-02 22:15:54.083
2025-09-02 22:15:54.088647 :C828FB27:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19120 pso_num=25 pso_serial#=147
2025-09-02 22:15:54.088789 :C828FB28:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19120 osp_idx=23 osp_ver=279640 osp_pg=0 (spawn #552532)
2025-09-02 22:15:54.088789 :C828FB29:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-02 22:15:54.088790 :C828FB2A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=102 time=1237005393
2025-09-02 22:15:54.088790 :C828FB2B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-09-02 22:15:54.088790 :C828FB2C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-09-02 22:15:54.088791 :C828FB2D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-09-02 22:15:54.088791 :C828FB2E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-02 22:15:54.088791 :C828FB2F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-02 22:15:54.089295 :C828FB30: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-09-02 22:15:54.089344 :C828FB38: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: 19120, W000)