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


*** 2025-05-31 18:04:36.061
*** SESSION ID:(209.11049) 2025-05-31 18:04:36.061
 

*** 2025-05-31 18:04:36.061
Process diagnostic dump for ORACLE.EXE (W000), OS id=14084,
pid: 26, proc_ser: 9, sid: 209, sess_ser: 11049 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=27 seq_num=28 snap_id=1
    wait times: snap=2.808498 sec, exc=2.808498 sec, total=2.808498 sec
    wait times: max=5.000000 sec, heur=2 min 13 sec
    wait counts: calls=1 os=1
    in_wait=1 iflags=0x5a8
Wait State:
  fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000025 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=26 seq_num=27 snap_id=1
    wait times: snap=5.007236 sec, exc=5.007236 sec, total=5.007236 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=25 seq_num=26 snap_id=1
    wait times: snap=5.015077 sec, exc=5.015077 sec, total=5.015077 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=24 seq_num=25 snap_id=1
    wait times: snap=5.010587 sec, exc=5.010587 sec, total=5.010587 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=23 seq_num=24 snap_id=1
    wait times: snap=5.009213 sec, exc=5.009213 sec, total=5.009213 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=22 seq_num=23 snap_id=1
    wait times: snap=5.010165 sec, exc=5.010165 sec, total=5.010165 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=21 seq_num=22 snap_id=1
    wait times: snap=5.009750 sec, exc=5.009750 sec, total=5.009750 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=20 seq_num=21 snap_id=1
    wait times: snap=5.003219 sec, exc=5.003219 sec, total=5.003219 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=19 seq_num=20 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.000031 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=18 seq_num=19 snap_id=1
    wait times: snap=5.013927 sec, exc=5.013927 sec, total=5.013927 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=17 seq_num=18 snap_id=1
    wait times: snap=5.010650 sec, exc=5.010650 sec, total=5.010650 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
Sampled Session History of session 209 serial 11049
---------------------------------------------------
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,                                            18:02:36 - 18:04:36]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-31 18:04:36.061
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 26 (osid: 14084, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-31 15:51:05.298791 :9E7F27FF:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 15:51:05.287
2025-05-31 15:51:05.298793 :9E7F2800:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13452 pso_num=26 pso_serial#=250
2025-05-31 15:51:05.298916 :9E7F2801:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13452 osp_idx=24 osp_ver=364645 osp_pg=0 (spawn #238298)
2025-05-31 15:51:05.298917 :9E7F2802:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 15:51:05.298917 :9E7F2803:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=15 time=1682423938
2025-05-31 15:51:05.298917 :9E7F2804:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 15:51:05.298918 :9E7F2805:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 15:51:05.298918 :9E7F2806:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 15:51:05.298919 :9E7F2807:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 15:51:05.298919 :9E7F2808:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 15:51:05.299401 :9E7F2809: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-05-31 15:51:35.392270 :9E7F31B5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 15:52:05.650570 :9E7F3C36:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 15:52:05.645
2025-05-31 15:52:05.650572 :9E7F3C37:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13180 pso_num=26 pso_serial#=251
2025-05-31 15:52:05.650709 :9E7F3C38:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13180 osp_idx=24 osp_ver=364646 osp_pg=0 (spawn #238301)
2025-05-31 15:52:05.650710 :9E7F3C39:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 15:52:05.650710 :9E7F3C3A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=16 time=1682484297
2025-05-31 15:52:05.650711 :9E7F3C3B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 15:52:05.650711 :9E7F3C3C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 15:52:05.650711 :9E7F3C3D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 15:52:05.650712 :9E7F3C3E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 15:52:05.650712 :9E7F3C3F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 15:52:05.651201 :9E7F3C40: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-05-31 15:52:05.651244 :9E7F3C47: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-05-31 16:02:06.622760 :9E8001C6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 16:02:06.908905 :9E800224:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 16:02:06.904
2025-05-31 16:02:06.908907 :9E800225:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12904 pso_num=26 pso_serial#=252
2025-05-31 16:02:06.909049 :9E800226:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12904 osp_idx=24 osp_ver=364647 osp_pg=0 (spawn #238326)
2025-05-31 16:02:06.909050 :9E800227:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 16:02:06.909050 :9E800228:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=17 time=1683085563
2025-05-31 16:02:06.909051 :9E800229:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 16:02:06.909051 :9E80022A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 16:02:06.909052 :9E80022B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 16:02:06.909052 :9E80022C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 16:02:06.909052 :9E80022D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 16:02:06.909536 :9E80022E: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-05-31 16:02:06.909582 :9E800236: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-05-31 16:12:07.921471 :9E80CF55:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 16:12:08.285341 :9E80CFB0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 16:12:08.281
2025-05-31 16:12:08.285343 :9E80CFB1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13000 pso_num=26 pso_serial#=253
2025-05-31 16:12:08.285480 :9E80CFB2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13000 osp_idx=24 osp_ver=364648 osp_pg=0 (spawn #238350)
2025-05-31 16:12:08.285480 :9E80CFB3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 16:12:08.285481 :9E80CFB4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=18 time=1683686938
2025-05-31 16:12:08.285481 :9E80CFB5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 16:12:08.285482 :9E80CFB6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 16:12:08.285482 :9E80CFB7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 16:12:08.285482 :9E80CFB8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 16:12:08.285483 :9E80CFB9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 16:12:08.285976 :9E80CFBA: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-05-31 16:12:08.286021 :9E80CFC1: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-05-31 16:22:09.230473 :9E8193E5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 16:22:09.453753 :9E81943E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 16:22:09.449
2025-05-31 16:22:09.453755 :9E81943F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12612 pso_num=26 pso_serial#=254
2025-05-31 16:22:09.453901 :9E819440:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12612 osp_idx=24 osp_ver=364649 osp_pg=0 (spawn #238373)
2025-05-31 16:22:09.453902 :9E819441:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 16:22:09.453902 :9E819442:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=19 time=1684288094
2025-05-31 16:22:09.453902 :9E819443:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 16:22:09.453903 :9E819444:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 16:22:09.453903 :9E819445:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 16:22:09.453903 :9E819446:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 16:22:09.453904 :9E819447:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 16:22:09.454413 :9E819448: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-05-31 16:22:09.454460 :9E81944F: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-05-31 16:32:10.347075 :9E825932:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 16:32:10.695347 :9E82598E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 16:32:10.690
2025-05-31 16:32:10.695349 :9E82598F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13224 pso_num=26 pso_serial#=255
2025-05-31 16:32:10.695488 :9E825990:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13224 osp_idx=24 osp_ver=364650 osp_pg=0 (spawn #238395)
2025-05-31 16:32:10.695489 :9E825991:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 16:32:10.695489 :9E825992:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=20 time=1684889344
2025-05-31 16:32:10.695489 :9E825993:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 16:32:10.695490 :9E825994:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 16:32:10.695490 :9E825995:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 16:32:10.695490 :9E825996:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 16:32:10.695491 :9E825997:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 16:32:10.696000 :9E825998: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-05-31 16:32:10.696046 :9E82599F: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-05-31 16:42:11.598182 :9E831C7E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 16:42:11.993390 :9E831CDB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 16:42:11.989
2025-05-31 16:42:11.993391 :9E831CDC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13888 pso_num=26 pso_serial#=1
2025-05-31 16:42:11.993526 :9E831CDD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13888 osp_idx=24 osp_ver=364651 osp_pg=0 (spawn #238418)
2025-05-31 16:42:11.993526 :9E831CDE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 16:42:11.993527 :9E831CDF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=21 time=1685490641
2025-05-31 16:42:11.993528 :9E831CE0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 16:42:11.993528 :9E831CE1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 16:42:11.993528 :9E831CE2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 16:42:11.993529 :9E831CE3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 16:42:11.993529 :9E831CE4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 16:42:11.994027 :9E831CE5: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-05-31 16:42:11.994070 :9E831CEE: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-05-31 16:52:12.954864 :9E83E12E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 16:52:13.303112 :9E83E1BE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 16:52:13.298
2025-05-31 16:52:13.303114 :9E83E1BF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12148 pso_num=26 pso_serial#=2
2025-05-31 16:52:13.303247 :9E83E1C0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12148 osp_idx=24 osp_ver=364652 osp_pg=0 (spawn #238441)
2025-05-31 16:52:13.303247 :9E83E1C1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 16:52:13.303248 :9E83E1C2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=22 time=1686091954
2025-05-31 16:52:13.303248 :9E83E1C3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 16:52:13.303249 :9E83E1C4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 16:52:13.303249 :9E83E1C5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 16:52:13.303250 :9E83E1C6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 16:52:13.303250 :9E83E1C7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 16:52:13.303789 :9E83E1C8: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-05-31 16:52:13.303831 :9E83E1D0: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-05-31 17:02:14.241345 :9E84A871:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 17:02:14.621156 :9E84A8F7:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 17:02:14.617
2025-05-31 17:02:14.621158 :9E84A8F8:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12372 pso_num=26 pso_serial#=3
2025-05-31 17:02:14.621276 :9E84A8F9:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12372 osp_idx=24 osp_ver=364653 osp_pg=0 (spawn #238465)
2025-05-31 17:02:14.621276 :9E84A8FA:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 17:02:14.621276 :9E84A8FB:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=23 time=1686693266
2025-05-31 17:02:14.621277 :9E84A8FC:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 17:02:14.621277 :9E84A8FD:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 17:02:14.621277 :9E84A8FE:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 17:02:14.621277 :9E84A8FF:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 17:02:14.621278 :9E84A900:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 17:02:14.621734 :9E84A901: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-05-31 17:02:14.621780 :9E84A907: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-05-31 17:06:46.682104 :9E850844: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-05-31 17:12:15.981731 :9E8575FF: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-05-31 17:22:16.899545 :9E863964:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 17:22:17.266694 :9E863967:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 17:22:17.259
2025-05-31 17:22:17.266696 :9E863968:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12908 pso_num=26 pso_serial#=4
2025-05-31 17:22:17.266836 :9E863969:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12908 (legacy spawn)
2025-05-31 17:22:17.273296 :9E86396A: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-05-31 17:22:17.273474 :9E86396F: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-05-31 17:22:17.274253 :9E863997:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 17:22:17.357205 :9E8639D5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 17:22:17.353
2025-05-31 17:22:17.357207 :9E8639D6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12400 pso_num=26 pso_serial#=5
2025-05-31 17:22:17.357345 :9E8639D7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12400 osp_idx=24 osp_ver=364655 osp_pg=0 (spawn #238511)
2025-05-31 17:22:17.357346 :9E8639D8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 17:22:17.357346 :9E8639D9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=24 time=1687896000
2025-05-31 17:22:17.357347 :9E8639DA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 17:22:17.357347 :9E8639DB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 17:22:17.357348 :9E8639DC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 17:22:17.357348 :9E8639DD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 17:22:17.357349 :9E8639DE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 17:22:17.357859 :9E8639DF: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-05-31 17:22:17.357905 :9E8639E6: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-05-31 17:32:18.279583 :9E8702D3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 17:32:18.784069 :9E87032E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 17:32:18.779
2025-05-31 17:32:18.784071 :9E87032F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13380 pso_num=26 pso_serial#=6
2025-05-31 17:32:18.784208 :9E870330:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13380 osp_idx=24 osp_ver=364656 osp_pg=0 (spawn #238533)
2025-05-31 17:32:18.784209 :9E870331:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 17:32:18.784209 :9E870332:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=25 time=1688497438
2025-05-31 17:32:18.784209 :9E870333:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 17:32:18.784210 :9E870334:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 17:32:18.784210 :9E870335:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 17:32:18.784210 :9E870336:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 17:32:18.784211 :9E870337:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 17:32:18.784708 :9E870338: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-05-31 17:32:18.784758 :9E870342: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-05-31 17:42:19.735628 :9E87C702:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 17:42:20.271510 :9E87C768:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 17:42:20.266
2025-05-31 17:42:20.271512 :9E87C769:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14828 pso_num=26 pso_serial#=7
2025-05-31 17:42:20.271649 :9E87C76A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14828 osp_idx=24 osp_ver=364657 osp_pg=0 (spawn #238556)
2025-05-31 17:42:20.271650 :9E87C76B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 17:42:20.271650 :9E87C76C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=26 time=1689098922
2025-05-31 17:42:20.271651 :9E87C76D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 17:42:20.271651 :9E87C76E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 17:42:20.271651 :9E87C76F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 17:42:20.271651 :9E87C770:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 17:42:20.271652 :9E87C771:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 17:42:20.272159 :9E87C772: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-05-31 17:42:20.272204 :9E87C779: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-05-31 17:52:21.163003 :9E888AFC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 17:52:21.651875 :9E888B60:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 17:52:21.647
2025-05-31 17:52:21.651877 :9E888B61:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10376 pso_num=26 pso_serial#=8
2025-05-31 17:52:21.652015 :9E888B62:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=10376 osp_idx=24 osp_ver=364658 osp_pg=0 (spawn #238579)
2025-05-31 17:52:21.652015 :9E888B63:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 17:52:21.652016 :9E888B64:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=27 time=1689700297
2025-05-31 17:52:21.652016 :9E888B65:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 17:52:21.652017 :9E888B66:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 17:52:21.652017 :9E888B67:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 17:52:21.652018 :9E888B68:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 17:52:21.652018 :9E888B69:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 17:52:21.652505 :9E888B6A: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-05-31 17:52:21.652555 :9E888B74: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-05-31 18:02:22.618007 :9E895015:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-05-31 18:02:23.044630 :9E895083:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-31 18:02:23.040
2025-05-31 18:02:23.044632 :9E895084:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14084 pso_num=26 pso_serial#=9
2025-05-31 18:02:23.044775 :9E895085:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14084 osp_idx=24 osp_ver=364659 osp_pg=0 (spawn #238603)
2025-05-31 18:02:23.044776 :9E895086:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-31 18:02:23.044776 :9E895087:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=28 time=1690301688
2025-05-31 18:02:23.044776 :9E895088:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-05-31 18:02:23.044777 :9E895089:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-05-31 18:02:23.044777 :9E89508A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-05-31 18:02:23.044777 :9E89508B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-31 18:02:23.044778 :9E89508C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-31 18:02:23.045276 :9E89508D: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-05-31 18:02:23.045321 :9E895095: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: 14084, W000)