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


*** 2025-06-21 10:05:36.766
*** SESSION ID:(201.35861) 2025-06-21 10:05:36.766
 

*** 2025-06-21 10:05:36.766
Process diagnostic dump for ORACLE.EXE (W000), OS id=15096,
pid: 25, proc_ser: 109, sid: 201, sess_ser: 35861 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=33 seq_num=34 snap_id=1
    wait times: snap=4.906957 sec, exc=4.906957 sec, total=4.906957 sec
    wait times: max=5.000000 sec, heur=2 min 45 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.000019 sec since current wait
 0: 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.002821 sec, exc=5.002821 sec, total=5.002821 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 1: 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.000285 sec, exc=5.000285 sec, total=5.000285 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=30 seq_num=31 snap_id=1
    wait times: snap=5.007886 sec, exc=5.007886 sec, total=5.007886 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 3: 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.003055 sec, exc=5.003055 sec, total=5.003055 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 4: 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.009162 sec, exc=5.009162 sec, total=5.009162 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000049 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=27 seq_num=28 snap_id=1
    wait times: snap=5.010116 sec, exc=5.010116 sec, total=5.010116 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000068 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=26 seq_num=27 snap_id=1
    wait times: snap=5.001671 sec, exc=5.001671 sec, total=5.001671 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000075 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=25 seq_num=26 snap_id=1
    wait times: snap=5.001231 sec, exc=5.001231 sec, total=5.001231 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=24 seq_num=25 snap_id=1
    wait times: snap=5.000408 sec, exc=5.000408 sec, total=5.000408 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=23 seq_num=24 snap_id=1
    wait times: snap=5.006952 sec, exc=5.006952 sec, total=5.006952 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
Sampled Session History of session 201 serial 35861
---------------------------------------------------
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:03:36 - 10:05:36]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-21 10:05:36.766
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 25 (osid: 15096, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-21 07:42:33.444969 :A792C9C4:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=13508 (legacy spawn)
2025-06-21 07:42:33.451535 :A792C9EC: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-06-21 07:42:33.451690 :A792C9F5: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-06-21 07:42:33.452453 :A792C9F6:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 07:42:33.720412 :A792CA5C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 07:42:33.716
2025-06-21 07:42:33.720414 :A792CA5D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12664 pso_num=25 pso_serial#=96
2025-06-21 07:42:33.720551 :A792CA5E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12664 osp_idx=23 osp_ver=163663 osp_pg=0 (spawn #307169)
2025-06-21 07:42:33.720552 :A792CA5F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 07:42:33.720552 :A792CA60:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=153 time=3467496907
2025-06-21 07:42:33.720553 :A792CA61:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 07:42:33.720553 :A792CA62:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 07:42:33.720553 :A792CA63:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 07:42:33.720554 :A792CA64:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 07:42:33.720554 :A792CA65:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 07:42:33.721068 :A792CA66: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-06-21 07:42:33.721115 :A792CA6D: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-06-21 07:52:34.668402 :A793928C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 07:52:34.901905 :A79392D6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 07:52:34.896
2025-06-21 07:52:34.901908 :A79392D7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9064 pso_num=25 pso_serial#=97
2025-06-21 07:52:34.902047 :A79392D8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9064 osp_idx=23 osp_ver=163664 osp_pg=0 (spawn #307192)
2025-06-21 07:52:34.902047 :A79392D9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 07:52:34.902048 :A79392DA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=154 time=3468098094
2025-06-21 07:52:34.902048 :A79392DB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 07:52:34.902049 :A79392DC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 07:52:34.902049 :A79392DD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 07:52:34.902049 :A79392DE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 07:52:34.902050 :A79392DF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 07:52:34.902583 :A79392E0: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-06-21 07:52:34.902629 :A79392EA: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-06-21 08:02:35.824821 :A7945D22:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 08:02:36.173237 :A7945D5F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 08:02:36.172
2025-06-21 08:02:36.173241 :A7945D60:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16600 pso_num=25 pso_serial#=98
2025-06-21 08:02:36.173387 :A7945D61:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16600 osp_idx=23 osp_ver=163665 osp_pg=0 (spawn #307217)
2025-06-21 08:02:36.173388 :A7945D62:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 08:02:36.173389 :A7945D63:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=155 time=3468699360
2025-06-21 08:02:36.173389 :A7945D64:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 08:02:36.173389 :A7945D65:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 08:02:36.173390 :A7945D66:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 08:02:36.173390 :A7945D67:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 08:02:36.173390 :A7945D68:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 08:02:36.173891 :A7945D69: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-06-21 08:02:36.173931 :A7945D72: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-06-21 08:12:37.155475 :A7952E1B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 08:12:37.441718 :A7952E60:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 08:12:37.437
2025-06-21 08:12:37.441720 :A7952E61:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16760 pso_num=25 pso_serial#=99
2025-06-21 08:12:37.441873 :A7952E62:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16760 osp_idx=23 osp_ver=163666 osp_pg=0 (spawn #307240)
2025-06-21 08:12:37.441874 :A7952E63:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 08:12:37.441874 :A7952E64:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=156 time=3469300625
2025-06-21 08:12:37.441875 :A7952E65:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 08:12:37.441875 :A7952E66:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 08:12:37.441876 :A7952E67:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 08:12:37.441876 :A7952E68:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 08:12:37.441876 :A7952E69:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 08:12:37.442418 :A7952E6A: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-06-21 08:12:37.442463 :A7952E70: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-06-21 08:22:38.333192 :A795F619:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 08:22:38.634746 :A795F652:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 08:22:38.630
2025-06-21 08:22:38.634748 :A795F653:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17424 pso_num=25 pso_serial#=100
2025-06-21 08:22:38.634885 :A795F654:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17424 osp_idx=23 osp_ver=163667 osp_pg=0 (spawn #307263)
2025-06-21 08:22:38.634886 :A795F655:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 08:22:38.634886 :A795F656:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=157 time=3469901829
2025-06-21 08:22:38.634887 :A795F657:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 08:22:38.634887 :A795F658:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 08:22:38.634887 :A795F659:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 08:22:38.634888 :A795F65A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 08:22:38.634888 :A795F65B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 08:22:38.635438 :A795F65C: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-06-21 08:22:38.635483 :A795F664: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-06-21 08:26:42.185125 :A796477C: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-06-21 08:32:39.855247 :A7970D00: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-06-21 08:42:40.786983 :A797EFEC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 08:42:41.150937 :A797F02A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 08:42:41.146
2025-06-21 08:42:41.150939 :A797F02B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16864 pso_num=25 pso_serial#=101
2025-06-21 08:42:41.151075 :A797F02C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16864 osp_idx=23 osp_ver=163668 osp_pg=0 (spawn #307312)
2025-06-21 08:42:41.151075 :A797F02D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 08:42:41.151076 :A797F02E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=158 time=3471104344
2025-06-21 08:42:41.151076 :A797F02F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 08:42:41.151076 :A797F030:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 08:42:41.151077 :A797F031:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 08:42:41.151077 :A797F032:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 08:42:41.151077 :A797F033:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 08:42:41.151616 :A797F034: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-06-21 08:42:41.151665 :A797F03C: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-06-21 08:52:42.069318 :A798B883:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 08:52:42.433261 :A798B8BD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 08:52:42.428
2025-06-21 08:52:42.433263 :A798B8BE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15516 pso_num=25 pso_serial#=102
2025-06-21 08:52:42.433403 :A798B8BF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15516 osp_idx=23 osp_ver=163669 osp_pg=0 (spawn #307335)
2025-06-21 08:52:42.433404 :A798B8C0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 08:52:42.433404 :A798B8C1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=159 time=3471705625
2025-06-21 08:52:42.433405 :A798B8C2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 08:52:42.433405 :A798B8C3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 08:52:42.433405 :A798B8C4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 08:52:42.433406 :A798B8C5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 08:52:42.433406 :A798B8C6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 08:52:42.433893 :A798B8C7: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-06-21 08:52:42.433940 :A798B8D1: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-06-21 09:02:43.322322 :A79980A1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 09:02:43.708047 :A79980E6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 09:02:43.707
2025-06-21 09:02:43.708049 :A79980E7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10888 pso_num=25 pso_serial#=103
2025-06-21 09:02:43.708181 :A79980E8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10888 osp_idx=23 osp_ver=163670 osp_pg=0 (spawn #307361)
2025-06-21 09:02:43.708181 :A79980E9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 09:02:43.708182 :A79980EA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=160 time=3472306891
2025-06-21 09:02:43.708182 :A79980EB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 09:02:43.708182 :A79980EC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 09:02:43.708183 :A79980ED:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 09:02:43.708183 :A79980EE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 09:02:43.708183 :A79980EF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 09:02:43.708659 :A79980F0: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-06-21 09:02:43.708699 :A79980F9: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-06-21 09:12:44.570355 :A79A56F9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 09:12:45.043610 :A79A5756:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 09:12:45.038
2025-06-21 09:12:45.043613 :A79A5757:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17576 pso_num=25 pso_serial#=104
2025-06-21 09:12:45.043748 :A79A5758:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17576 osp_idx=23 osp_ver=163671 osp_pg=0 (spawn #307384)
2025-06-21 09:12:45.043749 :A79A5759:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 09:12:45.043749 :A79A575A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=161 time=3472908235
2025-06-21 09:12:45.043750 :A79A575B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 09:12:45.043750 :A79A575C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 09:12:45.043751 :A79A575D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 09:12:45.043751 :A79A575E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 09:12:45.043751 :A79A575F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 09:12:45.044243 :A79A5760: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-06-21 09:12:45.044287 :A79A5768: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-06-21 09:22:45.839963 :A79B2028:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 09:22:46.313097 :A79B209A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 09:22:46.308
2025-06-21 09:22:46.313098 :A79B209B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16372 pso_num=25 pso_serial#=105
2025-06-21 09:22:46.313234 :A79B209C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16372 osp_idx=23 osp_ver=163672 osp_pg=0 (spawn #307407)
2025-06-21 09:22:46.313235 :A79B209D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 09:22:46.313235 :A79B209E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=162 time=3473509500
2025-06-21 09:22:46.313236 :A79B209F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 09:22:46.313236 :A79B20A0:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 09:22:46.313237 :A79B20A1:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 09:22:46.313237 :A79B20A2:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 09:22:46.313237 :A79B20A3:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 09:22:46.313705 :A79B20A4: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-06-21 09:22:46.313750 :A79B20AC: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-06-21 09:32:47.145974 :A79BE51F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 09:32:47.603784 :A79BE5C3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 09:32:47.599
2025-06-21 09:32:47.603786 :A79BE5C4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17380 pso_num=25 pso_serial#=106
2025-06-21 09:32:47.603925 :A79BE5C5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17380 osp_idx=23 osp_ver=163673 osp_pg=0 (spawn #307429)
2025-06-21 09:32:47.603926 :A79BE5C6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 09:32:47.603927 :A79BE5C7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=163 time=3474110797
2025-06-21 09:32:47.603927 :A79BE5C8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 09:32:47.603927 :A79BE5C9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 09:32:47.603928 :A79BE5CA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 09:32:47.603928 :A79BE5CB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 09:32:47.603928 :A79BE5CC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 09:32:47.604447 :A79BE5CD: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-06-21 09:32:47.604493 :A79BE5D5: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-06-21 09:42:48.457616 :A79CAAEB:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 09:42:48.868425 :A79CAB83:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 09:42:48.863
2025-06-21 09:42:48.868427 :A79CAB84:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15584 pso_num=25 pso_serial#=107
2025-06-21 09:42:48.868564 :A79CAB85:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15584 osp_idx=23 osp_ver=163674 osp_pg=0 (spawn #307452)
2025-06-21 09:42:48.868565 :A79CAB86:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 09:42:48.868565 :A79CAB87:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=164 time=3474712063
2025-06-21 09:42:48.868565 :A79CAB88:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 09:42:48.868566 :A79CAB89:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 09:42:48.868566 :A79CAB8A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 09:42:48.868566 :A79CAB8B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 09:42:48.868567 :A79CAB8C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 09:42:48.869067 :A79CAB8D: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-06-21 09:42:48.869112 :A79CAB94: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-06-21 09:52:49.678261 :A79D70C1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 09:52:50.283650 :A79D7172:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 09:52:50.279
2025-06-21 09:52:50.283652 :A79D7173:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8776 pso_num=25 pso_serial#=108
2025-06-21 09:52:50.283795 :A79D7174:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8776 osp_idx=23 osp_ver=163675 osp_pg=0 (spawn #307475)
2025-06-21 09:52:50.283796 :A79D7175:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 09:52:50.283796 :A79D7176:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=165 time=3475313469
2025-06-21 09:52:50.283797 :A79D7177:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 09:52:50.283797 :A79D7178:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 09:52:50.283797 :A79D7179:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 09:52:50.283798 :A79D717A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 09:52:50.283798 :A79D717B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 09:52:50.284302 :A79D717C: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-06-21 09:52:50.284349 :A79D7184: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-06-21 10:02:51.171208 :A79E3834:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-21 10:02:51.686496 :A79E38AF:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-21 10:02:51.681
2025-06-21 10:02:51.686498 :A79E38B0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15096 pso_num=25 pso_serial#=109
2025-06-21 10:02:51.686640 :A79E38B1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15096 osp_idx=23 osp_ver=163676 osp_pg=0 (spawn #307500)
2025-06-21 10:02:51.686641 :A79E38B2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-21 10:02:51.686642 :A79E38B3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=166 time=3475914875
2025-06-21 10:02:51.686642 :A79E38B4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-21 10:02:51.686642 :A79E38B5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-21 10:02:51.686643 :A79E38B6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-21 10:02:51.686643 :A79E38B7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-21 10:02:51.686643 :A79E38B8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-21 10:02:51.687154 :A79E38B9: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-06-21 10:02:51.687198 :A79E38C1: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: 15096, W000)