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


*** 2025-07-20 22:07:01.873
*** SESSION ID:(209.59089) 2025-07-20 22:07:01.873
 

*** 2025-07-20 22:07:01.873
Process diagnostic dump for ORACLE.EXE (W000), OS id=11336,
pid: 26, proc_ser: 92, sid: 209, sess_ser: 59089 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=35 seq_num=36 snap_id=1
    wait times: snap=3.998290 sec, exc=3.998290 sec, total=3.998290 sec
    wait times: max=5.000000 sec, heur=2 min 54 sec
    wait counts: calls=1 os=1
    in_wait=1 iflags=0x5a8
Wait State:
  fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000022 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=34 seq_num=35 snap_id=1
    wait times: snap=5.004617 sec, exc=5.004617 sec, total=5.004617 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 1: 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.009751 sec, exc=5.009751 sec, total=5.009751 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 2: 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.003251 sec, exc=5.003251 sec, total=5.003251 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 3: 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.000440 sec, exc=5.000440 sec, total=5.000440 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 4: 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.014170 sec, exc=5.014170 sec, total=5.014170 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 5: 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.009208 sec, exc=5.009208 sec, total=5.009208 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 6: 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.004613 sec, exc=5.004613 sec, total=5.004613 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 7: 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.004605 sec, exc=5.004605 sec, total=5.004605 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 8: 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.015291 sec, exc=5.015291 sec, total=5.015291 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 9: 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.003348 sec, exc=5.003348 sec, total=5.003348 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
Sampled Session History of session 209 serial 59089
---------------------------------------------------
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:05:01 - 22:07:01]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-20 22:07:01.873
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 26 (osid: 11336, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-20 19:43:48.349897 :B4B04AE7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 19:43:48.864513 :B4B04B29:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 19:43:48.859
2025-07-20 19:43:48.864515 :B4B04B2A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4492 pso_num=26 pso_serial#=79
2025-07-20 19:43:48.864653 :B4B04B2B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=4492 osp_idx=24 osp_ver=650329 osp_pg=0 (spawn #405517)
2025-07-20 19:43:48.864653 :B4B04B2C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 19:43:48.864654 :B4B04B2D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=185 time=1721404751
2025-07-20 19:43:48.864654 :B4B04B2E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 19:43:48.864655 :B4B04B2F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 19:43:48.864655 :B4B04B30:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 19:43:48.864655 :B4B04B31:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 19:43:48.864656 :B4B04B32:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 19:43:48.865152 :B4B04B33: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-07-20 19:43:48.865202 :B4B04B3D: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-07-20 19:53:49.813980 :B4B11390:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 19:53:50.068884 :B4B113B6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 19:53:50.064
2025-07-20 19:53:50.068887 :B4B113B7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18932 pso_num=26 pso_serial#=80
2025-07-20 19:53:50.069029 :B4B113B8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18932 osp_idx=24 osp_ver=650330 osp_pg=0 (spawn #405541)
2025-07-20 19:53:50.069030 :B4B113B9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 19:53:50.069030 :B4B113BA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=186 time=1722005970
2025-07-20 19:53:50.069031 :B4B113BB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 19:53:50.069032 :B4B113BC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 19:53:50.069032 :B4B113BD:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 19:53:50.069032 :B4B113BE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 19:53:50.069032 :B4B113BF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 19:53:50.069532 :B4B113C0: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-07-20 19:53:50.069577 :B4B113C8: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-07-20 20:03:50.967037 :B4B1DEB1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 20:03:51.393602 :B4B1DECB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 20:03:51.388
2025-07-20 20:03:51.393604 :B4B1DECC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13500 pso_num=26 pso_serial#=81
2025-07-20 20:03:51.393745 :B4B1DECD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13500 osp_idx=24 osp_ver=650331 osp_pg=0 (spawn #405566)
2025-07-20 20:03:51.393745 :B4B1DECE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 20:03:51.393746 :B4B1DECF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=187 time=1722607283
2025-07-20 20:03:51.393746 :B4B1DED0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 20:03:51.393746 :B4B1DED1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 20:03:51.393747 :B4B1DED2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 20:03:51.393747 :B4B1DED3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 20:03:51.393748 :B4B1DED4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 20:03:51.394237 :B4B1DED5: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-07-20 20:03:51.394280 :B4B1DEDD: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-07-20 20:13:52.258181 :B4B2AF8B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 20:13:52.715971 :B4B2AFB1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 20:13:52.711
2025-07-20 20:13:52.715973 :B4B2AFB2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19288 pso_num=26 pso_serial#=82
2025-07-20 20:13:52.716118 :B4B2AFB3:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19288 osp_idx=24 osp_ver=650332 osp_pg=0 (spawn #405589)
2025-07-20 20:13:52.716120 :B4B2AFB4:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 20:13:52.716121 :B4B2AFB5:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=188 time=1723208611
2025-07-20 20:13:52.716122 :B4B2AFB6:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 20:13:52.716122 :B4B2AFB7:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 20:13:52.716123 :B4B2AFB8:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 20:13:52.716123 :B4B2AFB9:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 20:13:52.716123 :B4B2AFBA:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 20:13:52.716622 :B4B2AFBB: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-07-20 20:13:52.716666 :B4B2AFC3: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-07-20 20:23:53.645442 :B4B37B0A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 20:23:54.071932 :B4B37B24:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 20:23:54.067
2025-07-20 20:23:54.071935 :B4B37B25:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18736 pso_num=26 pso_serial#=83
2025-07-20 20:23:54.072079 :B4B37B26:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18736 osp_idx=24 osp_ver=650333 osp_pg=0 (spawn #405612)
2025-07-20 20:23:54.072079 :B4B37B27:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 20:23:54.072079 :B4B37B28:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=189 time=1723809970
2025-07-20 20:23:54.072080 :B4B37B29:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 20:23:54.072080 :B4B37B2A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 20:23:54.072080 :B4B37B2B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 20:23:54.072081 :B4B37B2C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 20:23:54.072081 :B4B37B2D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 20:23:54.072574 :B4B37B2E: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-07-20 20:23:54.072618 :B4B37B36: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-07-20 20:33:54.976670 :B4B442B9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 20:33:55.420177 :B4B442DF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 20:33:55.414
2025-07-20 20:33:55.420179 :B4B442E0:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16104 pso_num=26 pso_serial#=84
2025-07-20 20:33:55.420353 :B4B442E1:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16104 osp_idx=24 osp_ver=650334 osp_pg=0 (spawn #405634)
2025-07-20 20:33:55.420354 :B4B442E2:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 20:33:55.420354 :B4B442E3:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=190 time=1724411314
2025-07-20 20:33:55.420355 :B4B442E4:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 20:33:55.420355 :B4B442E5:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 20:33:55.420355 :B4B442E6:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 20:33:55.420356 :B4B442E7:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 20:33:55.420356 :B4B442E8:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 20:33:55.420984 :B4B442E9: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-07-20 20:33:55.421055 :B4B442F3: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-07-20 20:43:56.356592 :B4B50B6A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 20:43:56.684055 :B4B50B97:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 20:43:56.679
2025-07-20 20:43:56.684057 :B4B50B98:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16476 pso_num=26 pso_serial#=85
2025-07-20 20:43:56.684199 :B4B50B99:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16476 osp_idx=24 osp_ver=650335 osp_pg=0 (spawn #405657)
2025-07-20 20:43:56.684200 :B4B50B9A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 20:43:56.684201 :B4B50B9B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=191 time=1725012579
2025-07-20 20:43:56.684201 :B4B50B9C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 20:43:56.684202 :B4B50B9D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 20:43:56.684202 :B4B50B9E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 20:43:56.684203 :B4B50B9F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 20:43:56.684203 :B4B50BA0:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 20:43:56.684704 :B4B50BA1: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-07-20 20:43:56.684750 :B4B50BA9: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-07-20 20:53:57.594528 :B4B5D2F8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 20:53:58.036860 :B4B5D331:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 20:53:58.032
2025-07-20 20:53:58.036862 :B4B5D332:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17836 pso_num=26 pso_serial#=86
2025-07-20 20:53:58.037000 :B4B5D333:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17836 osp_idx=24 osp_ver=650336 osp_pg=0 (spawn #405680)
2025-07-20 20:53:58.037001 :B4B5D334:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 20:53:58.037001 :B4B5D335:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=192 time=1725613923
2025-07-20 20:53:58.037001 :B4B5D336:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 20:53:58.037002 :B4B5D337:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 20:53:58.037002 :B4B5D338:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 20:53:58.037002 :B4B5D339:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 20:53:58.037003 :B4B5D33A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 20:53:58.037510 :B4B5D33B: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-07-20 20:53:58.037557 :B4B5D342: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-07-20 21:03:58.953448 :B4B69D39:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 21:03:59.333130 :B4B69D53:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 21:03:59.328
2025-07-20 21:03:59.333131 :B4B69D54:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=19192 pso_num=26 pso_serial#=87
2025-07-20 21:03:59.333269 :B4B69D55:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=19192 osp_idx=24 osp_ver=650337 osp_pg=0 (spawn #405705)
2025-07-20 21:03:59.333269 :B4B69D56:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 21:03:59.333270 :B4B69D57:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=193 time=1726215220
2025-07-20 21:03:59.333270 :B4B69D58:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 21:03:59.333270 :B4B69D59:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 21:03:59.333271 :B4B69D5A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 21:03:59.333271 :B4B69D5B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 21:03:59.333272 :B4B69D5C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 21:03:59.333761 :B4B69D5D: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-07-20 21:03:59.333807 :B4B69D63: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-07-20 21:14:00.233865 :B4B76B78:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 21:14:00.660451 :B4B76BB1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 21:14:00.655
2025-07-20 21:14:00.660452 :B4B76BB2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18288 pso_num=26 pso_serial#=88
2025-07-20 21:14:00.660595 :B4B76BB3:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18288 osp_idx=24 osp_ver=650338 osp_pg=0 (spawn #405728)
2025-07-20 21:14:00.660596 :B4B76BB4:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 21:14:00.660596 :B4B76BB5:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=194 time=1726816548
2025-07-20 21:14:00.660597 :B4B76BB6:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 21:14:00.660597 :B4B76BB7:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 21:14:00.660597 :B4B76BB8:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 21:14:00.660598 :B4B76BB9:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 21:14:00.660598 :B4B76BBA:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 21:14:00.661093 :B4B76BBB: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-07-20 21:14:00.661137 :B4B76BC3: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-07-20 21:24:01.510032 :B4B83675:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 21:24:01.952187 :B4B836A2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 21:24:01.947
2025-07-20 21:24:01.952190 :B4B836A3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8336 pso_num=26 pso_serial#=89
2025-07-20 21:24:01.952327 :B4B836A4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8336 osp_idx=24 osp_ver=650339 osp_pg=0 (spawn #405751)
2025-07-20 21:24:01.952328 :B4B836A5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 21:24:01.952328 :B4B836A6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=195 time=1727417845
2025-07-20 21:24:01.952328 :B4B836A7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 21:24:01.952329 :B4B836A8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 21:24:01.952329 :B4B836A9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 21:24:01.952329 :B4B836AA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 21:24:01.952330 :B4B836AB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 21:24:01.952861 :B4B836AC: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-07-20 21:24:01.952916 :B4B836B6: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-07-20 21:30:10.161515 :B4B8B077: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-07-20 21:34:03.264250 :B4B8FED2: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-07-20 21:34:03.264291 :B4B8FED3:db_trace:ksl2.c@2477:kslwtbctx(): [10005:26:209] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1
2025-07-20 21:34:03.264310 :B4B8FED8:db_trace:ksl2.c@2611:kslwtectx(): [10005:26:209] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=123 seq_num=124 snap_id=1
2025-07-20 21:34:03.264311 :B4B8FED9:db_trace:ksl2.c@2619:kslwtectx(): [10005:26:209] KSL WAIT END wait times (usecs) - snap=19, exc=19, tot=19
2025-07-20 21:44:04.241194 :B4B9C99B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 21:44:04.698897 :B4B9C9C8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 21:44:04.694
2025-07-20 21:44:04.698899 :B4B9C9C9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16504 pso_num=26 pso_serial#=90
2025-07-20 21:44:04.699038 :B4B9C9CA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16504 osp_idx=24 osp_ver=650340 osp_pg=0 (spawn #405795)
2025-07-20 21:44:04.699039 :B4B9C9CB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 21:44:04.699039 :B4B9C9CC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=196 time=1728620595
2025-07-20 21:44:04.699040 :B4B9C9CD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 21:44:04.699040 :B4B9C9CE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 21:44:04.699040 :B4B9C9CF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 21:44:04.699041 :B4B9C9D0:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 21:44:04.699041 :B4B9C9D1:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 21:44:04.699540 :B4B9C9D2: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-07-20 21:44:04.699582 :B4B9C9DA: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-07-20 21:54:05.587379 :B4BA922B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 21:54:06.154911 :B4BA9258:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 21:54:06.150
2025-07-20 21:54:06.154913 :B4BA9259:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17944 pso_num=26 pso_serial#=91
2025-07-20 21:54:06.155054 :B4BA925A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17944 osp_idx=24 osp_ver=650341 osp_pg=0 (spawn #405818)
2025-07-20 21:54:06.155055 :B4BA925B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 21:54:06.155055 :B4BA925C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=197 time=1729222048
2025-07-20 21:54:06.155056 :B4BA925D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 21:54:06.155056 :B4BA925E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 21:54:06.155057 :B4BA925F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 21:54:06.155057 :B4BA9260:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 21:54:06.155057 :B4BA9261:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 21:54:06.155617 :B4BA9262: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-07-20 21:54:06.155681 :B4BA9269: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-07-20 22:04:07.093459 :B4BB5CB9:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-07-20 22:04:07.629439 :B4BB5CF2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 07-20 22:04:07.625
2025-07-20 22:04:07.629440 :B4BB5CF3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11336 pso_num=26 pso_serial#=92
2025-07-20 22:04:07.629578 :B4BB5CF4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=11336 osp_idx=24 osp_ver=650342 osp_pg=0 (spawn #405843)
2025-07-20 22:04:07.629579 :B4BB5CF5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-20 22:04:07.629579 :B4BB5CF6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=198 time=1729823517
2025-07-20 22:04:07.629580 :B4BB5CF7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-07-20 22:04:07.629580 :B4BB5CF8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-07-20 22:04:07.629580 :B4BB5CF9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-07-20 22:04:07.629580 :B4BB5CFA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-20 22:04:07.629580 :B4BB5CFB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-20 22:04:07.630078 :B4BB5CFC: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-07-20 22:04:07.630123 :B4BB5D04: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 26 (osid: 11336, W000)