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


*** 2025-08-12 22:00:08.529
*** SESSION ID:(177.25727) 2025-08-12 22:00:08.529
 

*** 2025-08-12 22:00:08.529
Process diagnostic dump for ORACLE.EXE (W000), OS id=21372,
pid: 22, proc_ser: 242, sid: 177, sess_ser: 25727 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=108 seq_num=109 snap_id=1
    wait times: snap=0.719425 sec, exc=0.719425 sec, total=0.719425 sec
    wait times: max=5.000000 sec, heur=8 min 56 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.000023 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=107 seq_num=108 snap_id=1
    wait times: snap=5.015305 sec, exc=5.015305 sec, total=5.015305 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=106 seq_num=107 snap_id=1
    wait times: snap=5.007619 sec, exc=5.007619 sec, total=5.007619 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000019 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=105 seq_num=106 snap_id=1
    wait times: snap=5.000291 sec, exc=5.000291 sec, total=5.000291 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=104 seq_num=105 snap_id=1
    wait times: snap=5.006965 sec, exc=5.006965 sec, total=5.006965 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=103 seq_num=104 snap_id=1
    wait times: snap=5.005970 sec, exc=5.005970 sec, total=5.005970 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=102 seq_num=103 snap_id=1
    wait times: snap=5.005491 sec, exc=5.005491 sec, total=5.005491 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000030 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=101 seq_num=102 snap_id=1
    wait times: snap=5.004643 sec, exc=5.004643 sec, total=5.004643 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=100 seq_num=101 snap_id=1
    wait times: snap=5.004646 sec, exc=5.004646 sec, total=5.004646 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=99 seq_num=100 snap_id=1
    wait times: snap=5.015269 sec, exc=5.015269 sec, total=5.015269 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=98 seq_num=99 snap_id=1
    wait times: snap=5.011624 sec, exc=5.011624 sec, total=5.011624 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 177 serial 25727
---------------------------------------------------
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,                                            21:58:08 - 22:00:08]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-12 22:00:08.529
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 21372, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-12 19:11:37.823939 :BEDA6C5A: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-08-12 19:20:52.543177 :BEDB23EC: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-08-12 19:30:53.423633 :BEDBEC40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 19:30:53.709466 :BEDBEC78:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 19:30:53.705
2025-08-12 19:30:53.709468 :BEDBEC79:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20016 pso_num=22 pso_serial#=229
2025-08-12 19:30:53.709607 :BEDBEC7A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20016 osp_idx=0 osp_ver=507496 osp_pg=0 (spawn #482140)
2025-08-12 19:30:53.709608 :BEDBEC7B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 19:30:53.709608 :BEDBEC7C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=3707723017
2025-08-12 19:30:53.709608 :BEDBEC7D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 19:30:53.709609 :BEDBEC7E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 19:30:53.709609 :BEDBEC7F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 19:30:53.709609 :BEDBEC80:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 19:30:53.709610 :BEDBEC81:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 19:30:53.710153 :BEDBEC82: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-08-12 19:30:53.710199 :BEDBEC8C: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-08-12 19:40:54.604073 :BEDCB420:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 19:40:55.015315 :BEDCB455:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 19:40:55.010
2025-08-12 19:40:55.015317 :BEDCB456:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21104 pso_num=22 pso_serial#=230
2025-08-12 19:40:55.015458 :BEDCB457:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21104 osp_idx=0 osp_ver=507497 osp_pg=0 (spawn #482162)
2025-08-12 19:40:55.015459 :BEDCB458:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 19:40:55.015459 :BEDCB459:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=254 time=3708324314
2025-08-12 19:40:55.015460 :BEDCB45A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 19:40:55.015461 :BEDCB45B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 19:40:55.015461 :BEDCB45C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 19:40:55.015461 :BEDCB45D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 19:40:55.015462 :BEDCB45E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 19:40:55.015965 :BEDCB45F: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-08-12 19:40:55.016010 :BEDCB466: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-08-12 19:50:55.930177 :BEDD7CD6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 19:50:56.341163 :BEDD7D18:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 19:50:56.336
2025-08-12 19:50:56.341165 :BEDD7D19:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19676 pso_num=22 pso_serial#=231
2025-08-12 19:50:56.341326 :BEDD7D1A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19676 osp_idx=0 osp_ver=507498 osp_pg=0 (spawn #482185)
2025-08-12 19:50:56.341326 :BEDD7D1B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 19:50:56.341327 :BEDD7D1C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=3708925642
2025-08-12 19:50:56.341327 :BEDD7D1D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 19:50:56.341327 :BEDD7D1E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 19:50:56.341328 :BEDD7D1F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 19:50:56.341328 :BEDD7D20:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 19:50:56.341328 :BEDD7D21:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 19:50:56.341825 :BEDD7D22: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-08-12 19:50:56.341868 :BEDD7D2A: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-08-12 20:00:57.261167 :BEDE462B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 20:00:57.672245 :BEDE4660:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 20:00:57.667
2025-08-12 20:00:57.672248 :BEDE4661:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21288 pso_num=22 pso_serial#=232
2025-08-12 20:00:57.672383 :BEDE4662:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21288 osp_idx=0 osp_ver=507499 osp_pg=0 (spawn #482210)
2025-08-12 20:00:57.672384 :BEDE4663:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 20:00:57.672385 :BEDE4664:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=3709526986
2025-08-12 20:00:57.672385 :BEDE4665:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 20:00:57.672385 :BEDE4666:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 20:00:57.672386 :BEDE4667:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 20:00:57.672386 :BEDE4668:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 20:00:57.672386 :BEDE4669:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 20:00:57.672888 :BEDE466A: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-08-12 20:00:57.672934 :BEDE4673: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-08-12 20:10:58.556816 :BEDF1785:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 20:10:58.952226 :BEDF17CA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 20:10:58.947
2025-08-12 20:10:58.952227 :BEDF17CB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19836 pso_num=22 pso_serial#=233
2025-08-12 20:10:58.952366 :BEDF17CC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19836 osp_idx=0 osp_ver=507500 osp_pg=0 (spawn #482232)
2025-08-12 20:10:58.952367 :BEDF17CD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 20:10:58.952368 :BEDF17CE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=3710128251
2025-08-12 20:10:58.952368 :BEDF17CF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 20:10:58.952369 :BEDF17D0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 20:10:58.952369 :BEDF17D1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 20:10:58.952369 :BEDF17D2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 20:10:58.952370 :BEDF17D3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 20:10:58.952867 :BEDF17D4: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-08-12 20:10:58.952909 :BEDF17DC: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-08-12 20:20:59.930488 :BEDFDF5D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 20:21:00.242313 :BEDFDF93:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 20:21:00.237
2025-08-12 20:21:00.242315 :BEDFDF94:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8296 pso_num=22 pso_serial#=234
2025-08-12 20:21:00.242460 :BEDFDF95:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8296 osp_idx=0 osp_ver=507501 osp_pg=0 (spawn #482255)
2025-08-12 20:21:00.242461 :BEDFDF96:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 20:21:00.242461 :BEDFDF97:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=3710729548
2025-08-12 20:21:00.242462 :BEDFDF98:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 20:21:00.242462 :BEDFDF99:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 20:21:00.242462 :BEDFDF9A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 20:21:00.242463 :BEDFDF9B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 20:21:00.242463 :BEDFDF9C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 20:21:00.242960 :BEDFDF9D: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-08-12 20:21:00.243008 :BEDFDFA4: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-08-12 20:31:01.205426 :BEE0A7AC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 20:31:01.600844 :BEE0A7F5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 20:31:01.596
2025-08-12 20:31:01.600846 :BEE0A7F6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20460 pso_num=22 pso_serial#=235
2025-08-12 20:31:01.600985 :BEE0A7F7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20460 osp_idx=0 osp_ver=507502 osp_pg=0 (spawn #482278)
2025-08-12 20:31:01.600986 :BEE0A7F8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 20:31:01.600986 :BEE0A7F9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=3 time=3711330908
2025-08-12 20:31:01.600987 :BEE0A7FA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 20:31:01.600987 :BEE0A7FB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 20:31:01.600988 :BEE0A7FC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 20:31:01.600988 :BEE0A7FD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 20:31:01.600988 :BEE0A7FE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 20:31:01.601489 :BEE0A7FF: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-08-12 20:31:01.601534 :BEE0A807: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-08-12 20:41:02.534940 :BEE16F22:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 20:41:02.899151 :BEE16F66:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 20:41:02.894
2025-08-12 20:41:02.899153 :BEE16F67:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14052 pso_num=22 pso_serial#=236
2025-08-12 20:41:02.899288 :BEE16F68:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14052 osp_idx=0 osp_ver=507503 osp_pg=0 (spawn #482300)
2025-08-12 20:41:02.899289 :BEE16F69:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 20:41:02.899289 :BEE16F6A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=4 time=3711932204
2025-08-12 20:41:02.899290 :BEE16F6B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 20:41:02.899290 :BEE16F6C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 20:41:02.899290 :BEE16F6D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 20:41:02.899291 :BEE16F6E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 20:41:02.899291 :BEE16F6F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 20:41:02.899784 :BEE16F70: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-08-12 20:41:02.899829 :BEE16F78: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-08-12 20:51:03.704194 :BEE236C2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 20:51:04.131076 :BEE236FA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 20:51:04.125
2025-08-12 20:51:04.131079 :BEE236FB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20428 pso_num=22 pso_serial#=237
2025-08-12 20:51:04.131215 :BEE236FC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20428 osp_idx=0 osp_ver=507504 osp_pg=0 (spawn #482323)
2025-08-12 20:51:04.131215 :BEE236FD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 20:51:04.131216 :BEE236FE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=5 time=3712533439
2025-08-12 20:51:04.131216 :BEE236FF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 20:51:04.131217 :BEE23700:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 20:51:04.131217 :BEE23701:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 20:51:04.131217 :BEE23702:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 20:51:04.131218 :BEE23703:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 20:51:04.131727 :BEE23704: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-08-12 20:51:04.131769 :BEE2370C: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-08-12 21:01:04.926454 :BEE30094:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 21:01:05.425765 :BEE300DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 21:01:05.421
2025-08-12 21:01:05.425767 :BEE300DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19324 pso_num=22 pso_serial#=238
2025-08-12 21:01:05.425908 :BEE300DC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19324 osp_idx=0 osp_ver=507505 osp_pg=0 (spawn #482348)
2025-08-12 21:01:05.425908 :BEE300DD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 21:01:05.425909 :BEE300DE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=6 time=3713134736
2025-08-12 21:01:05.425909 :BEE300DF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 21:01:05.425910 :BEE300E0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 21:01:05.425910 :BEE300E1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 21:01:05.425910 :BEE300E2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 21:01:05.425911 :BEE300E3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 21:01:05.426394 :BEE300E4: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-08-12 21:01:05.426438 :BEE300ED: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-08-12 21:11:06.332226 :BEE3CF3C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 21:11:06.665124 :BEE3CF74:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 21:11:06.660
2025-08-12 21:11:06.665126 :BEE3CF75:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21440 pso_num=22 pso_serial#=239
2025-08-12 21:11:06.665263 :BEE3CF76:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21440 osp_idx=0 osp_ver=507506 osp_pg=0 (spawn #482371)
2025-08-12 21:11:06.665264 :BEE3CF77:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 21:11:06.665265 :BEE3CF78:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=7 time=3713735970
2025-08-12 21:11:06.665265 :BEE3CF79:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 21:11:06.665265 :BEE3CF7A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 21:11:06.665266 :BEE3CF7B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 21:11:06.665266 :BEE3CF7C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 21:11:06.665266 :BEE3CF7D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 21:11:06.665759 :BEE3CF7E: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-08-12 21:11:06.665806 :BEE3CF88: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-08-12 21:11:38.595070 :BEE3DAA5: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-08-12 21:21:08.045701 :BEE494A4: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-08-12 21:31:08.871183 :BEE5599D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 21:31:09.370362 :BEE559FE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 21:31:09.365
2025-08-12 21:31:09.370364 :BEE559FF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18624 pso_num=22 pso_serial#=240
2025-08-12 21:31:09.370495 :BEE55A00:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18624 osp_idx=0 osp_ver=507507 osp_pg=0 (spawn #482416)
2025-08-12 21:31:09.370496 :BEE55A01:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 21:31:09.370496 :BEE55A02:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=8 time=3714938673
2025-08-12 21:31:09.370497 :BEE55A03:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 21:31:09.370497 :BEE55A04:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 21:31:09.370498 :BEE55A05:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 21:31:09.370498 :BEE55A06:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 21:31:09.370499 :BEE55A07:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 21:31:09.370983 :BEE55A08: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-08-12 21:31:09.371025 :BEE55A10: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-08-12 21:41:10.262569 :BEE61E24:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 21:41:10.720696 :BEE61E95:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 21:41:10.716
2025-08-12 21:41:10.720698 :BEE61E96:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15216 pso_num=22 pso_serial#=241
2025-08-12 21:41:10.720840 :BEE61E97:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15216 osp_idx=0 osp_ver=507508 osp_pg=0 (spawn #482438)
2025-08-12 21:41:10.720842 :BEE61E98:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 21:41:10.720842 :BEE61E99:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=9 time=3715540033
2025-08-12 21:41:10.720843 :BEE61E9A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 21:41:10.720843 :BEE61E9B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 21:41:10.720844 :BEE61E9C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 21:41:10.720844 :BEE61E9D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 21:41:10.720844 :BEE61E9E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 21:41:10.721351 :BEE61E9F: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-08-12 21:41:10.721400 :BEE61EA7: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-08-12 21:51:11.578705 :BEE6E5EE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-12 21:51:12.044752 :BEE6E624:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-12 21:51:12.042
2025-08-12 21:51:12.044754 :BEE6E625:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21372 pso_num=22 pso_serial#=242
2025-08-12 21:51:12.044897 :BEE6E626:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21372 osp_idx=0 osp_ver=507509 osp_pg=0 (spawn #482461)
2025-08-12 21:51:12.044898 :BEE6E627:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-12 21:51:12.044898 :BEE6E628:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=10 time=3716141345
2025-08-12 21:51:12.044899 :BEE6E629:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-12 21:51:12.044899 :BEE6E62A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-12 21:51:12.044899 :BEE6E62B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-12 21:51:12.044900 :BEE6E62C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-12 21:51:12.044901 :BEE6E62D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-12 21:51:12.045401 :BEE6E62E: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-08-12 21:51:12.045446 :BEE6E636: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: 21372, W000)