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


*** 2025-09-14 10:06:30.687
*** SESSION ID:(209.54893) 2025-09-14 10:06:30.687
 

*** 2025-09-14 10:06:30.687
Process diagnostic dump for ORACLE.EXE (W000), OS id=22352,
pid: 26, proc_ser: 7, sid: 209, sess_ser: 54893 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=93 seq_num=94 snap_id=1
    wait times: snap=4.944268 sec, exc=4.944268 sec, total=4.944268 sec
    wait times: max=5.000000 sec, heur=1 min 15 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.000024 sec since current wait
 0: 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.001157 sec, exc=5.001157 sec, total=5.001157 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 1: 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.007306 sec, exc=5.007306 sec, total=5.007306 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 2: 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.000722 sec, exc=5.000722 sec, total=5.000722 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 3: 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.014759 sec, exc=5.014759 sec, total=5.014759 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 4: 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.010153 sec, exc=5.010153 sec, total=5.010153 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=87 seq_num=88 snap_id=1
    wait times: snap=5.014783 sec, exc=5.014783 sec, total=5.014783 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=86 seq_num=87 snap_id=1
    wait times: snap=5.004633 sec, exc=5.004633 sec, total=5.004633 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=85 seq_num=86 snap_id=1
    wait times: snap=5.008300 sec, exc=5.008300 sec, total=5.008300 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=84 seq_num=85 snap_id=1
    wait times: snap=5.009545 sec, exc=5.009545 sec, total=5.009545 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=83 seq_num=84 snap_id=1
    wait times: snap=5.007056 sec, exc=5.007056 sec, total=5.007056 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
Sampled Session History of session 209 serial 54893
---------------------------------------------------
The sampled session history is constructed by sampling
the target session every 1 second. The sampling process
captures at each sample if the session is in a non-idle wait,
an idle wait, or not in a wait. If the session is in a
non-idle wait then one interval is shown for all the samples
the session was in the same non-idle wait. If the
session is in an idle wait or not in a wait for
consecutive samples then one interval is shown for all
the consecutive samples. Though we display these consecutive
samples  in a single interval the session may NOT be continuously
idle or not in a wait (the sampling process does not know).
 
The history is displayed in reverse chronological order.
 
sample interval: 1 sec, max history 120 sec
---------------------------------------------------
  [121 samples,                                            10:04:30 - 10:06:30]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-14 10:06:30.687
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 26 (osid: 22352, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-14 07:56:34.659209 :CD35F228:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=25500 pso_num=26 pso_serial#=248
2025-09-14 07:56:34.659329 :CD35F229:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=25500 osp_idx=24 osp_ver=844563 osp_pg=0 (spawn #590555)
2025-09-14 07:56:34.659330 :CD35F22A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 07:56:34.659330 :CD35F22B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=155 time=2222245955
2025-09-14 07:56:34.659331 :CD35F22C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 07:56:34.659331 :CD35F22D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 07:56:34.659332 :CD35F22E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 07:56:34.659332 :CD35F22F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 07:56:34.659332 :CD35F230:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 07:56:34.659805 :CD35F231:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 07:57:04.706417 :CD35FBB1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 07:57:34.757380 :CD360585:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 07:57:34.745
2025-09-14 07:57:34.757382 :CD360586:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24552 pso_num=26 pso_serial#=249
2025-09-14 07:57:34.757498 :CD360587:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24552 osp_idx=24 osp_ver=844564 osp_pg=0 (spawn #590557)
2025-09-14 07:57:34.757498 :CD360588:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 07:57:34.757499 :CD360589:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=156 time=2222306049
2025-09-14 07:57:34.757499 :CD36058A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 07:57:34.757500 :CD36058B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 07:57:34.757500 :CD36058C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 07:57:34.757500 :CD36058D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 07:57:34.757501 :CD36058E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 07:57:34.758009 :CD36058F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 07:58:04.843026 :CD360FBA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 07:58:34.963688 :CD361A23:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 07:58:34.959
2025-09-14 07:58:34.963690 :CD361A24:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19020 pso_num=26 pso_serial#=250
2025-09-14 07:58:34.963831 :CD361A25:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19020 osp_idx=24 osp_ver=844565 osp_pg=0 (spawn #590560)
2025-09-14 07:58:34.963832 :CD361A26:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 07:58:34.963832 :CD361A27:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=157 time=2222366268
2025-09-14 07:58:34.963832 :CD361A28:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 07:58:34.963833 :CD361A29:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 07:58:34.963833 :CD361A2A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 07:58:34.963833 :CD361A2B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 07:58:34.963833 :CD361A2C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 07:58:34.964344 :CD361A2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 07:58:34.964389 :CD361A36:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:00:33.513753 :CD3641FB:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:08:36.182665 :CD36EA2F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:18:37.091836 :CD37B2D1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 08:18:37.440237 :CD37B33D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 08:18:37.435
2025-09-14 08:18:37.440239 :CD37B33E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=25488 pso_num=26 pso_serial#=251
2025-09-14 08:18:37.440376 :CD37B33F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=25488 osp_idx=24 osp_ver=844566 osp_pg=0 (spawn #590607)
2025-09-14 08:18:37.440376 :CD37B340:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 08:18:37.440377 :CD37B341:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=158 time=2223568752
2025-09-14 08:18:37.440377 :CD37B342:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 08:18:37.440378 :CD37B343:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 08:18:37.440378 :CD37B344:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 08:18:37.440378 :CD37B345:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 08:18:37.440378 :CD37B346:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 08:18:37.440930 :CD37B347:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 08:18:37.440978 :CD37B34F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:28:38.281938 :CD38C6DD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 08:28:38.646014 :CD38C716:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 08:28:38.641
2025-09-14 08:28:38.646016 :CD38C717:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=25084 pso_num=26 pso_serial#=252
2025-09-14 08:28:38.646153 :CD38C718:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=25084 osp_idx=24 osp_ver=844567 osp_pg=0 (spawn #590635)
2025-09-14 08:28:38.646153 :CD38C719:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 08:28:38.646154 :CD38C71A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=159 time=2224169955
2025-09-14 08:28:38.646154 :CD38C71B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 08:28:38.646154 :CD38C71C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 08:28:38.646154 :CD38C71D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 08:28:38.646155 :CD38C71E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 08:28:38.646155 :CD38C71F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 08:28:38.646649 :CD38C720:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 08:28:38.646696 :CD38C727:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:38:39.524746 :CD39B277:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 08:38:39.861566 :CD39B27A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 08:38:39.852
2025-09-14 08:38:39.861568 :CD39B27B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=25136 pso_num=26 pso_serial#=253
2025-09-14 08:38:39.861710 :CD39B27C:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=25136 (legacy spawn)
2025-09-14 08:38:39.868258 :CD39B284:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-14 08:38:39.868461 :CD39B28E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-14 08:38:39.869303 :CD39B293:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 08:38:39.920530 :CD39B2CB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 08:38:39.915
2025-09-14 08:38:39.920532 :CD39B2CC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22828 pso_num=26 pso_serial#=254
2025-09-14 08:38:39.920699 :CD39B2CD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22828 osp_idx=24 osp_ver=844569 osp_pg=0 (spawn #590657)
2025-09-14 08:38:39.920700 :CD39B2CE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 08:38:39.920700 :CD39B2CF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=160 time=2224771221
2025-09-14 08:38:39.920701 :CD39B2D0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 08:38:39.920701 :CD39B2D1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 08:38:39.920702 :CD39B2D2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 08:38:39.920702 :CD39B2D3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 08:38:39.920702 :CD39B2D4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 08:38:39.921236 :CD39B2D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 08:38:39.921280 :CD39B2DC:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:48:40.817683 :CD3A7C47:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 08:48:41.197662 :CD3A7C7F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 08:48:41.193
2025-09-14 08:48:41.197664 :CD3A7C80:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=25516 pso_num=26 pso_serial#=255
2025-09-14 08:48:41.197857 :CD3A7C81:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=25516 osp_idx=24 osp_ver=844570 osp_pg=0 (spawn #590680)
2025-09-14 08:48:41.197858 :CD3A7C82:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 08:48:41.197858 :CD3A7C83:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=161 time=2225372502
2025-09-14 08:48:41.197859 :CD3A7C84:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 08:48:41.197860 :CD3A7C85:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 08:48:41.197860 :CD3A7C86:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 08:48:41.197861 :CD3A7C87:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 08:48:41.197861 :CD3A7C88:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 08:48:41.198419 :CD3A7C89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 08:48:41.198464 :CD3A7C8F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 08:58:42.037461 :CD3B4536:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 08:58:42.370206 :CD3B4570:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 08:58:42.365
2025-09-14 08:58:42.370209 :CD3B4571:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24856 pso_num=26 pso_serial#=1
2025-09-14 08:58:42.370401 :CD3B4572:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24856 osp_idx=24 osp_ver=844571 osp_pg=0 (spawn #590704)
2025-09-14 08:58:42.370402 :CD3B4573:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 08:58:42.370402 :CD3B4574:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=162 time=2225973674
2025-09-14 08:58:42.370402 :CD3B4575:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 08:58:42.370403 :CD3B4576:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 08:58:42.370403 :CD3B4577:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 08:58:42.370404 :CD3B4578:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 08:58:42.370404 :CD3B4579:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 08:58:42.370923 :CD3B457A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 08:58:42.370968 :CD3B4580:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 09:08:43.235211 :CD3C1870:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 09:08:43.646267 :CD3C18AC:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 09:08:43.641
2025-09-14 09:08:43.646270 :CD3C18AD:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7692 pso_num=26 pso_serial#=2
2025-09-14 09:08:43.646416 :CD3C18AE:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7692 osp_idx=24 osp_ver=844572 osp_pg=0 (spawn #590729)
2025-09-14 09:08:43.646417 :CD3C18AF:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 09:08:43.646417 :CD3C18B0:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=163 time=2226574955
2025-09-14 09:08:43.646418 :CD3C18B1:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 09:08:43.646418 :CD3C18B2:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 09:08:43.646418 :CD3C18B3:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 09:08:43.646418 :CD3C18B4:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 09:08:43.646419 :CD3C18B5:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 09:08:43.646910 :CD3C18B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 09:08:43.646959 :CD3C18BD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 09:18:44.479088 :CD3CE434:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 09:18:44.879867 :CD3CE479:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 09:18:44.874
2025-09-14 09:18:44.879869 :CD3CE47A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22092 pso_num=26 pso_serial#=3
2025-09-14 09:18:44.880007 :CD3CE47B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22092 osp_idx=24 osp_ver=844573 osp_pg=0 (spawn #590752)
2025-09-14 09:18:44.880008 :CD3CE47C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 09:18:44.880008 :CD3CE47D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=164 time=2227176190
2025-09-14 09:18:44.880009 :CD3CE47E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 09:18:44.880009 :CD3CE47F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 09:18:44.880010 :CD3CE480:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 09:18:44.880010 :CD3CE481:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 09:18:44.880010 :CD3CE482:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 09:18:44.880517 :CD3CE483:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 09:18:44.880564 :CD3CE489:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 09:28:45.695709 :CD3DAC3A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 09:28:46.178819 :CD3DAC75:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 09:28:46.174
2025-09-14 09:28:46.178821 :CD3DAC76:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=20856 pso_num=26 pso_serial#=4
2025-09-14 09:28:46.178961 :CD3DAC77:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=20856 osp_idx=24 osp_ver=844574 osp_pg=0 (spawn #590775)
2025-09-14 09:28:46.178962 :CD3DAC78:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 09:28:46.178962 :CD3DAC79:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=165 time=2227777487
2025-09-14 09:28:46.178963 :CD3DAC7A:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 09:28:46.178963 :CD3DAC7B:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 09:28:46.178963 :CD3DAC7C:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 09:28:46.178963 :CD3DAC7D:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 09:28:46.178964 :CD3DAC7E:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 09:28:46.179456 :CD3DAC7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 09:28:46.179500 :CD3DAC86:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 09:38:47.077127 :CD3E75A9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 09:38:47.613083 :CD3E75EE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 09:38:47.608
2025-09-14 09:38:47.613085 :CD3E75EF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24024 pso_num=26 pso_serial#=5
2025-09-14 09:38:47.613219 :CD3E75F0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24024 osp_idx=24 osp_ver=844575 osp_pg=0 (spawn #590797)
2025-09-14 09:38:47.613219 :CD3E75F1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 09:38:47.613220 :CD3E75F2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=166 time=2228378924
2025-09-14 09:38:47.613220 :CD3E75F3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 09:38:47.613221 :CD3E75F4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 09:38:47.613221 :CD3E75F5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 09:38:47.613221 :CD3E75F6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 09:38:47.613222 :CD3E75F7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 09:38:47.613749 :CD3E75F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 09:38:47.613798 :CD3E7600:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 09:48:48.466836 :CD3F3E54:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 09:48:48.979176 :CD3F3E8F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 09:48:48.974
2025-09-14 09:48:48.979179 :CD3F3E90:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24568 pso_num=26 pso_serial#=6
2025-09-14 09:48:48.979321 :CD3F3E91:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24568 osp_idx=24 osp_ver=844576 osp_pg=0 (spawn #590820)
2025-09-14 09:48:48.979322 :CD3F3E92:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 09:48:48.979322 :CD3F3E93:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=167 time=2228980283
2025-09-14 09:48:48.979323 :CD3F3E94:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 09:48:48.979323 :CD3F3E95:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 09:48:48.979323 :CD3F3E96:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 09:48:48.979324 :CD3F3E97:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 09:48:48.979324 :CD3F3E98:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 09:48:48.979891 :CD3F3E99:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 09:48:48.979937 :CD3F3EA1:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 09:58:49.783975 :CD400785:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-14 09:58:50.257651 :CD4007B8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-14 09:58:50.252
2025-09-14 09:58:50.257653 :CD4007B9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22352 pso_num=26 pso_serial#=7
2025-09-14 09:58:50.257798 :CD4007BA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22352 osp_idx=24 osp_ver=844577 osp_pg=0 (spawn #590843)
2025-09-14 09:58:50.257799 :CD4007BB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-14 09:58:50.257799 :CD4007BC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=168 time=2229581565
2025-09-14 09:58:50.257800 :CD4007BD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 09:58:50.257800 :CD4007BE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 09:58:50.257800 :CD4007BF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 09:58:50.257801 :CD4007C0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 09:58:50.257801 :CD4007C1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 09:58:50.258320 :CD4007C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-14 09:58:50.258369 :CD4007C8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-14 10:05:15.646368 :CD408A06:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 26 (osid: 22352, W000)