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


*** 2025-06-26 22:00:05.933
*** SESSION ID:(177.52281) 2025-06-26 22:00:05.933
 

*** 2025-06-26 22:00:05.933
Process diagnostic dump for ORACLE.EXE (W000), OS id=4860,
pid: 22, proc_ser: 245, sid: 177, sess_ser: 52281 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=3.544147 sec, exc=3.544147 sec, total=3.544147 sec
    wait times: max=5.000000 sec, heur=43.594131 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=8 seq_num=9 snap_id=1
    wait times: snap=5.001901 sec, exc=5.001901 sec, total=5.001901 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=5.014695 sec, exc=5.014695 sec, total=5.014695 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=6 seq_num=7 snap_id=1
    wait times: snap=5.014740 sec, exc=5.014740 sec, total=5.014740 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=5.004627 sec, exc=5.004627 sec, total=5.004627 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=5.004633 sec, exc=5.004633 sec, total=5.004633 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=5.008333 sec, exc=5.008333 sec, total=5.008333 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=5.000225 sec, exc=5.000225 sec, total=5.000225 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=1 seq_num=2 snap_id=1
    wait times: snap=5.000644 sec, exc=5.000644 sec, total=5.000644 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.003963 sec of elapsed time
 8: waited for 'class slave wait'
    slave id=0x8876c60, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.000045 sec, exc=0.000045 sec, total=0.000045 sec
    wait times: max=infinite
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 177 serial 52281
---------------------------------------------------
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
---------------------------------------------------
  [44 samples,                                             21:59:22 - 22:00:05]
    idle wait at each sample
  [session created at: 21:59:22]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-26 22:00:05.933
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 4860, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-26 19:29:04.421954 :AA01CBBD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 19:29:04.421954 :AA01CBBE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 19:29:04.422443 :AA01CBBF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 19:29:04.422487 :AA01CBC7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 19:39:05.254871 :AA0293AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 19:39:05.608298 :AA029430:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 19:39:05.603
2025-06-26 19:39:05.608300 :AA029431:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4120 pso_num=22 pso_serial#=232
2025-06-26 19:39:05.608430 :AA029432:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4120 osp_idx=0 osp_ver=352204 osp_pg=0 (spawn #325497)
2025-06-26 19:39:05.608430 :AA029433:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 19:39:05.608431 :AA029434:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=192 time=3942488797
2025-06-26 19:39:05.608431 :AA029435:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 19:39:05.608432 :AA029436:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 19:39:05.608432 :AA029437:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 19:39:05.608432 :AA029438:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 19:39:05.608433 :AA029439:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 19:39:05.608968 :AA02943A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 19:39:05.609018 :AA029444:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 19:49:06.346325 :AA035D5E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 19:49:06.866485 :AA035DEB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 19:49:06.861
2025-06-26 19:49:06.866487 :AA035DEC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7180 pso_num=22 pso_serial#=233
2025-06-26 19:49:06.866624 :AA035DED:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7180 osp_idx=0 osp_ver=352205 osp_pg=0 (spawn #325519)
2025-06-26 19:49:06.866625 :AA035DEE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 19:49:06.866625 :AA035DEF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=193 time=3943090063
2025-06-26 19:49:06.866626 :AA035DF0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 19:49:06.866626 :AA035DF1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 19:49:06.866627 :AA035DF2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 19:49:06.866627 :AA035DF3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 19:49:06.866627 :AA035DF4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 19:49:06.867141 :AA035DF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 19:49:06.867191 :AA035DFC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 19:59:07.754612 :AA042591:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 19:59:08.118470 :AA042617:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 19:59:08.113
2025-06-26 19:59:08.118472 :AA042618:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16396 pso_num=22 pso_serial#=234
2025-06-26 19:59:08.118609 :AA042619:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16396 osp_idx=0 osp_ver=352206 osp_pg=0 (spawn #325542)
2025-06-26 19:59:08.118610 :AA04261A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 19:59:08.118610 :AA04261B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=194 time=3943691313
2025-06-26 19:59:08.118610 :AA04261C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 19:59:08.118611 :AA04261D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 19:59:08.118611 :AA04261E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 19:59:08.118612 :AA04261F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 19:59:08.118612 :AA042620:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 19:59:08.119117 :AA042621:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 19:59:08.119170 :AA042628:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 20:09:08.965256 :AA04F9BC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 20:09:09.360323 :AA04FA25:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 20:09:09.355
2025-06-26 20:09:09.360325 :AA04FA26:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18096 pso_num=22 pso_serial#=235
2025-06-26 20:09:09.360460 :AA04FA27:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18096 osp_idx=0 osp_ver=352207 osp_pg=0 (spawn #325569)
2025-06-26 20:09:09.360460 :AA04FA28:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 20:09:09.360461 :AA04FA29:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=3944292547
2025-06-26 20:09:09.360461 :AA04FA2A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 20:09:09.360461 :AA04FA2B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 20:09:09.360462 :AA04FA2C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 20:09:09.360462 :AA04FA2D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 20:09:09.360462 :AA04FA2E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 20:09:09.360948 :AA04FA2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 20:09:09.360991 :AA04FA37:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 20:19:10.194850 :AA05C1F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 20:19:10.496097 :AA05C255:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 20:19:10.491
2025-06-26 20:19:10.496099 :AA05C256:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9276 pso_num=22 pso_serial#=236
2025-06-26 20:19:10.496235 :AA05C257:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9276 osp_idx=0 osp_ver=352208 osp_pg=0 (spawn #325591)
2025-06-26 20:19:10.496235 :AA05C258:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 20:19:10.496235 :AA05C259:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=196 time=3944893688
2025-06-26 20:19:10.496236 :AA05C25A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 20:19:10.496236 :AA05C25B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 20:19:10.496236 :AA05C25C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 20:19:10.496237 :AA05C25D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 20:19:10.496237 :AA05C25E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 20:19:10.496724 :AA05C25F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 20:19:10.496772 :AA05C269:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 20:29:11.436248 :AA068AC0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 20:29:11.644090 :AA068B28:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 20:29:11.639
2025-06-26 20:29:11.644093 :AA068B29:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=732 pso_num=22 pso_serial#=237
2025-06-26 20:29:11.644232 :AA068B2A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=732 osp_idx=0 osp_ver=352209 osp_pg=0 (spawn #325614)
2025-06-26 20:29:11.644233 :AA068B2B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 20:29:11.644233 :AA068B2C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=197 time=3945494829
2025-06-26 20:29:11.644234 :AA068B2D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 20:29:11.644234 :AA068B2E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 20:29:11.644234 :AA068B2F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 20:29:11.644235 :AA068B30:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 20:29:11.644235 :AA068B31:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 20:29:11.644718 :AA068B32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 20:29:11.644762 :AA068B39:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 20:39:12.454367 :AA075285:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 20:39:12.896436 :AA0752E3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 20:39:12.892
2025-06-26 20:39:12.896438 :AA0752E4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10376 pso_num=22 pso_serial#=238
2025-06-26 20:39:12.896575 :AA0752E5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10376 osp_idx=0 osp_ver=352210 osp_pg=0 (spawn #325637)
2025-06-26 20:39:12.896575 :AA0752E6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 20:39:12.896575 :AA0752E7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=198 time=3946096094
2025-06-26 20:39:12.896576 :AA0752E8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 20:39:12.896576 :AA0752E9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 20:39:12.896577 :AA0752EA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 20:39:12.896578 :AA0752EB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 20:39:12.896578 :AA0752EC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 20:39:12.897101 :AA0752ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 20:39:12.897157 :AA0752F7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 20:49:13.745214 :AA081AD9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 20:49:14.061863 :AA081B55:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 20:49:14.057
2025-06-26 20:49:14.061865 :AA081B56:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15208 pso_num=22 pso_serial#=239
2025-06-26 20:49:14.062001 :AA081B57:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15208 osp_idx=0 osp_ver=352211 osp_pg=0 (spawn #325659)
2025-06-26 20:49:14.062002 :AA081B58:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 20:49:14.062002 :AA081B59:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=199 time=3946697250
2025-06-26 20:49:14.062003 :AA081B5A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 20:49:14.062003 :AA081B5B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 20:49:14.062004 :AA081B5C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 20:49:14.062004 :AA081B5D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 20:49:14.062004 :AA081B5E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 20:49:14.062507 :AA081B5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 20:49:14.062554 :AA081B67:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 20:59:14.885842 :AA08E345:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 20:59:15.218773 :AA08E3AE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 20:59:15.213
2025-06-26 20:59:15.218775 :AA08E3AF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11144 pso_num=22 pso_serial#=240
2025-06-26 20:59:15.218912 :AA08E3B0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11144 osp_idx=0 osp_ver=352212 osp_pg=0 (spawn #325682)
2025-06-26 20:59:15.218913 :AA08E3B1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 20:59:15.218913 :AA08E3B2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=200 time=3947298407
2025-06-26 20:59:15.218914 :AA08E3B3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 20:59:15.218914 :AA08E3B4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 20:59:15.218915 :AA08E3B5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 20:59:15.218915 :AA08E3B6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 20:59:15.218915 :AA08E3B7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 20:59:15.219407 :AA08E3B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 20:59:15.219451 :AA08E3C0:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:02:05.027246 :AA091DC2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:09:16.449102 :AA09B688:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:19:17.381169 :AA0A7E6C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 21:19:17.588549 :AA0A7ECB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 21:19:17.584
2025-06-26 21:19:17.588552 :AA0A7ECC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7692 pso_num=22 pso_serial#=241
2025-06-26 21:19:17.588684 :AA0A7ECD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7692 osp_idx=0 osp_ver=352213 osp_pg=0 (spawn #325729)
2025-06-26 21:19:17.588685 :AA0A7ECE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 21:19:17.588685 :AA0A7ECF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=201 time=3948500782
2025-06-26 21:19:17.588686 :AA0A7ED0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 21:19:17.588686 :AA0A7ED1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 21:19:17.588687 :AA0A7ED2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 21:19:17.588687 :AA0A7ED3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 21:19:17.588687 :AA0A7ED4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 21:19:17.589163 :AA0A7ED5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 21:19:17.589225 :AA0A7EDC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:29:18.403242 :AA0B46ED:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 21:29:18.845584 :AA0B4753:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 21:29:18.840
2025-06-26 21:29:18.845586 :AA0B4754:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16812 pso_num=22 pso_serial#=242
2025-06-26 21:29:18.845722 :AA0B4755:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16812 osp_idx=0 osp_ver=352214 osp_pg=0 (spawn #325752)
2025-06-26 21:29:18.845723 :AA0B4756:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 21:29:18.845724 :AA0B4757:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=202 time=3949102032
2025-06-26 21:29:18.845724 :AA0B4758:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 21:29:18.845724 :AA0B4759:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 21:29:18.845725 :AA0B475A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 21:29:18.845725 :AA0B475B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 21:29:18.845725 :AA0B475C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 21:29:18.846241 :AA0B475D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 21:29:18.846284 :AA0B4765:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:39:19.649734 :AA0C108C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 21:39:20.044951 :AA0C10E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 21:39:20.040
2025-06-26 21:39:20.044953 :AA0C10EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17584 pso_num=22 pso_serial#=243
2025-06-26 21:39:20.045088 :AA0C10EB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17584 osp_idx=0 osp_ver=352215 osp_pg=0 (spawn #325775)
2025-06-26 21:39:20.045089 :AA0C10EC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 21:39:20.045089 :AA0C10ED:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=203 time=3949703235
2025-06-26 21:39:20.045090 :AA0C10EE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 21:39:20.045090 :AA0C10EF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 21:39:20.045091 :AA0C10F0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 21:39:20.045091 :AA0C10F1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 21:39:20.045091 :AA0C10F2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 21:39:20.045615 :AA0C10F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 21:39:20.045667 :AA0C10F9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:49:20.886011 :AA0CD96F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 21:49:21.250335 :AA0CD9D7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 21:49:21.245
2025-06-26 21:49:21.250336 :AA0CD9D8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18404 pso_num=22 pso_serial#=244
2025-06-26 21:49:21.250485 :AA0CD9D9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18404 osp_idx=0 osp_ver=352216 osp_pg=0 (spawn #325797)
2025-06-26 21:49:21.250486 :AA0CD9DA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 21:49:21.250487 :AA0CD9DB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=204 time=3950304438
2025-06-26 21:49:21.250487 :AA0CD9DC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 21:49:21.250487 :AA0CD9DD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 21:49:21.250487 :AA0CD9DE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 21:49:21.250488 :AA0CD9DF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 21:49:21.250488 :AA0CD9E0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 21:49:21.251086 :AA0CD9E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 21:49:21.251137 :AA0CD9E8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-26 21:59:22.038444 :AA0DA24E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-06-26 21:59:22.340892 :AA0DA2BB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-26 21:59:22.335
2025-06-26 21:59:22.340895 :AA0DA2BC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4860 pso_num=22 pso_serial#=245
2025-06-26 21:59:22.341064 :AA0DA2BD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4860 osp_idx=0 osp_ver=352217 osp_pg=0 (spawn #325820)
2025-06-26 21:59:22.341065 :AA0DA2BE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-26 21:59:22.341065 :AA0DA2BF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=205 time=3950905532
2025-06-26 21:59:22.341066 :AA0DA2C0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-06-26 21:59:22.341066 :AA0DA2C1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-06-26 21:59:22.341067 :AA0DA2C2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-06-26 21:59:22.341067 :AA0DA2C3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-26 21:59:22.341067 :AA0DA2C4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-26 21:59:22.341558 :AA0DA2C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-26 21:59:22.341608 :AA0DA2CC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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 22 (osid: 4860, W000)