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


*** 2025-06-28 22:08:13.300
*** SESSION ID:(209.7651) 2025-06-28 22:08:13.300
 

*** 2025-06-28 22:08:13.300
Process diagnostic dump for ORACLE.EXE (W000), OS id=16604,
pid: 26, proc_ser: 227, sid: 209, sess_ser: 7651 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=157 seq_num=158 snap_id=1
    wait times: snap=4.379313 sec, exc=4.379313 sec, total=4.379313 sec
    wait times: max=5.000000 sec, heur=3 min 8 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.000024 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=156 seq_num=157 snap_id=1
    wait times: snap=5.006019 sec, exc=5.006019 sec, total=5.006019 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=155 seq_num=156 snap_id=1
    wait times: snap=5.004618 sec, exc=5.004618 sec, total=5.004618 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=154 seq_num=155 snap_id=1
    wait times: snap=5.004625 sec, exc=5.004625 sec, total=5.004625 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=153 seq_num=154 snap_id=1
    wait times: snap=5.004614 sec, exc=5.004614 sec, total=5.004614 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=152 seq_num=153 snap_id=1
    wait times: snap=5.008474 sec, exc=5.008474 sec, total=5.008474 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=151 seq_num=152 snap_id=1
    wait times: snap=5.000177 sec, exc=5.000177 sec, total=5.000177 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=150 seq_num=151 snap_id=1
    wait times: snap=5.009496 sec, exc=5.009496 sec, total=5.009496 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=149 seq_num=150 snap_id=1
    wait times: snap=5.014440 sec, exc=5.014440 sec, total=5.014440 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=148 seq_num=149 snap_id=1
    wait times: snap=5.014720 sec, exc=5.014720 sec, total=5.014720 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=147 seq_num=148 snap_id=1
    wait times: snap=5.004660 sec, exc=5.004660 sec, total=5.004660 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
Sampled Session History of session 209 serial 7651
---------------------------------------------------
The sampled session history is constructed by sampling
the target session every 1 second. The sampling process
captures at each sample if the session is in a non-idle wait,
an idle wait, or not in a wait. If the session is in a
non-idle wait then one interval is shown for all the samples
the session was in the same non-idle wait. If the
session is in an idle wait or not in a wait for
consecutive samples then one interval is shown for all
the consecutive samples. Though we display these consecutive
samples  in a single interval the session may NOT be continuously
idle or not in a wait (the sampling process does not know).
 
The history is displayed in reverse chronological order.
 
sample interval: 1 sec, max history 120 sec
---------------------------------------------------
  [121 samples,                                            22:06:13 - 22:08:13]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-28 22:08:13.300
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 26 (osid: 16604, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-28 19:34:59.889823 :AAE5DCAC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 19:35:00.310942 :AAE5DD2A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 19:35:00.306
2025-06-28 19:35:00.310944 :AAE5DD2B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12112 pso_num=26 pso_serial#=214
2025-06-28 19:35:00.311084 :AAE5DD2C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12112 osp_idx=24 osp_ver=526534 osp_pg=0 (spawn #332173)
2025-06-28 19:35:00.311085 :AAE5DD2D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 19:35:00.311086 :AAE5DD2E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=84 time=4115043500
2025-06-28 19:35:00.311086 :AAE5DD2F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 19:35:00.311087 :AAE5DD30:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 19:35:00.311087 :AAE5DD31:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 19:35:00.311087 :AAE5DD32:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 19:35:00.311088 :AAE5DD33:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 19:35:00.311588 :AAE5DD34: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-06-28 19:35:00.311631 :AAE5DD3C: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-06-28 19:45:01.110407 :AAE6A69F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 19:45:01.646386 :AAE6A711:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 19:45:01.641
2025-06-28 19:45:01.646389 :AAE6A712:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3264 pso_num=26 pso_serial#=215
2025-06-28 19:45:01.646527 :AAE6A713:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=3264 osp_idx=24 osp_ver=526535 osp_pg=0 (spawn #332196)
2025-06-28 19:45:01.646528 :AAE6A714:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 19:45:01.646528 :AAE6A715:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=85 time=4115644844
2025-06-28 19:45:01.646529 :AAE6A716:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 19:45:01.646530 :AAE6A717:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 19:45:01.646530 :AAE6A718:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 19:45:01.646530 :AAE6A719:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 19:45:01.646531 :AAE6A71A:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 19:45:01.647027 :AAE6A71B: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-06-28 19:45:01.647070 :AAE6A722: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-06-28 19:55:02.485349 :AAE77168:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 19:55:02.765751 :AAE771A6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 19:55:02.761
2025-06-28 19:55:02.765753 :AAE771A7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15596 pso_num=26 pso_serial#=216
2025-06-28 19:55:02.765890 :AAE771A8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15596 osp_idx=24 osp_ver=526536 osp_pg=0 (spawn #332218)
2025-06-28 19:55:02.765891 :AAE771A9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 19:55:02.765892 :AAE771AA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=86 time=4116245954
2025-06-28 19:55:02.765893 :AAE771AB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 19:55:02.765893 :AAE771AC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 19:55:02.765894 :AAE771AD:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 19:55:02.765894 :AAE771AE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 19:55:02.765894 :AAE771AF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 19:55:02.766390 :AAE771B0: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-06-28 19:55:02.766434 :AAE771B8: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-06-28 20:02:32.783721 :AAE80981: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-06-28 20:05:04.012621 :AAE83D2F: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-06-28 20:15:04.988888 :AAE90BF0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 20:15:05.212221 :AAE90C29:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 20:15:05.207
2025-06-28 20:15:05.212224 :AAE90C2A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13512 pso_num=26 pso_serial#=217
2025-06-28 20:15:05.212366 :AAE90C2B:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13512 osp_idx=24 osp_ver=526537 osp_pg=0 (spawn #332266)
2025-06-28 20:15:05.212367 :AAE90C2C:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 20:15:05.212367 :AAE90C2D:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=87 time=4117448407
2025-06-28 20:15:05.212368 :AAE90C2E:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 20:15:05.212368 :AAE90C2F:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 20:15:05.212369 :AAE90C30:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 20:15:05.212369 :AAE90C31:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 20:15:05.212369 :AAE90C32:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 20:15:05.212855 :AAE90C33: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-06-28 20:15:05.212908 :AAE90C3D: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-06-28 20:25:06.134198 :AAE9D6EB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 20:25:06.435769 :AAE9D72E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 20:25:06.431
2025-06-28 20:25:06.435771 :AAE9D72F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=18212 pso_num=26 pso_serial#=218
2025-06-28 20:25:06.435908 :AAE9D730:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=18212 osp_idx=24 osp_ver=526538 osp_pg=0 (spawn #332288)
2025-06-28 20:25:06.435909 :AAE9D731:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 20:25:06.435909 :AAE9D732:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=88 time=4118049625
2025-06-28 20:25:06.435910 :AAE9D733:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 20:25:06.435910 :AAE9D734:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 20:25:06.435911 :AAE9D735:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 20:25:06.435912 :AAE9D736:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 20:25:06.435912 :AAE9D737:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 20:25:06.436419 :AAE9D738: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-06-28 20:25:06.436466 :AAE9D740: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-06-28 20:35:07.336579 :AAEAA02F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 20:35:07.716327 :AAEAA09B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 20:35:07.711
2025-06-28 20:35:07.716329 :AAEAA09C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16092 pso_num=26 pso_serial#=219
2025-06-28 20:35:07.716467 :AAEAA09D:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16092 osp_idx=24 osp_ver=526539 osp_pg=0 (spawn #332311)
2025-06-28 20:35:07.716468 :AAEAA09E:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 20:35:07.716468 :AAEAA09F:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=89 time=4118650907
2025-06-28 20:35:07.716469 :AAEAA0A0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 20:35:07.716469 :AAEAA0A1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 20:35:07.716469 :AAEAA0A2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 20:35:07.716470 :AAEAA0A3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 20:35:07.716470 :AAEAA0A4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 20:35:07.716977 :AAEAA0A5: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-06-28 20:35:07.717029 :AAEAA0AC: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-06-28 20:45:08.558985 :AAEB6A0F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 20:45:08.844935 :AAEB6A72:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 20:45:08.840
2025-06-28 20:45:08.844937 :AAEB6A73:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7568 pso_num=26 pso_serial#=220
2025-06-28 20:45:08.845075 :AAEB6A74:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7568 osp_idx=24 osp_ver=526540 osp_pg=0 (spawn #332334)
2025-06-28 20:45:08.845075 :AAEB6A75:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 20:45:08.845076 :AAEB6A76:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=90 time=4119252032
2025-06-28 20:45:08.845076 :AAEB6A77:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 20:45:08.845077 :AAEB6A78:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 20:45:08.845077 :AAEB6A79:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 20:45:08.845077 :AAEB6A7A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 20:45:08.845078 :AAEB6A7B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 20:45:08.845591 :AAEB6A7C: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-06-28 20:45:08.845637 :AAEB6A84: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-06-28 20:55:09.769053 :AAEC327B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 20:55:10.070621 :AAEC32E5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 20:55:10.066
2025-06-28 20:55:10.070624 :AAEC32E6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9380 pso_num=26 pso_serial#=221
2025-06-28 20:55:10.070766 :AAEC32E7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9380 osp_idx=24 osp_ver=526541 osp_pg=0 (spawn #332356)
2025-06-28 20:55:10.070767 :AAEC32E8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 20:55:10.070767 :AAEC32E9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=91 time=4119853266
2025-06-28 20:55:10.070768 :AAEC32EA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 20:55:10.070768 :AAEC32EB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 20:55:10.070769 :AAEC32EC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 20:55:10.070769 :AAEC32ED:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 20:55:10.070770 :AAEC32EE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 20:55:10.071271 :AAEC32EF: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-06-28 20:55:10.071313 :AAEC32F7: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-06-28 21:05:11.029966 :AAECFDA1:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 21:05:11.300388 :AAECFE0C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 21:05:11.296
2025-06-28 21:05:11.300390 :AAECFE0D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9636 pso_num=26 pso_serial#=222
2025-06-28 21:05:11.300524 :AAECFE0E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=9636 osp_idx=24 osp_ver=526542 osp_pg=0 (spawn #332382)
2025-06-28 21:05:11.300525 :AAECFE0F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 21:05:11.300525 :AAECFE10:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=92 time=4120454485
2025-06-28 21:05:11.300526 :AAECFE11:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 21:05:11.300526 :AAECFE12:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 21:05:11.300527 :AAECFE13:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 21:05:11.300527 :AAECFE14:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 21:05:11.300527 :AAECFE15:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 21:05:11.301012 :AAECFE16: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-06-28 21:05:11.301061 :AAECFE1D: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-06-28 21:15:12.182125 :AAEDCBB6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 21:15:12.467913 :AAEDCC26:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 21:15:12.463
2025-06-28 21:15:12.467916 :AAEDCC27:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16440 pso_num=26 pso_serial#=223
2025-06-28 21:15:12.468068 :AAEDCC28:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16440 osp_idx=24 osp_ver=526543 osp_pg=0 (spawn #332405)
2025-06-28 21:15:12.468068 :AAEDCC29:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 21:15:12.468069 :AAEDCC2A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=93 time=4121055657
2025-06-28 21:15:12.468069 :AAEDCC2B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 21:15:12.468069 :AAEDCC2C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 21:15:12.468070 :AAEDCC2D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 21:15:12.468070 :AAEDCC2E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 21:15:12.468070 :AAEDCC2F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 21:15:12.468582 :AAEDCC30: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-06-28 21:15:12.468627 :AAEDCC38: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-06-28 21:25:13.438195 :AAEE96C0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 21:25:13.771011 :AAEE9713:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 21:25:13.766
2025-06-28 21:25:13.771013 :AAEE9714:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14984 pso_num=26 pso_serial#=224
2025-06-28 21:25:13.771158 :AAEE9715:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14984 osp_idx=24 osp_ver=526544 osp_pg=0 (spawn #332427)
2025-06-28 21:25:13.771158 :AAEE9716:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 21:25:13.771159 :AAEE9717:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=94 time=4121656969
2025-06-28 21:25:13.771159 :AAEE9718:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 21:25:13.771160 :AAEE9719:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 21:25:13.771160 :AAEE971A:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 21:25:13.771160 :AAEE971B:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 21:25:13.771161 :AAEE971C:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 21:25:13.771740 :AAEE971D: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-06-28 21:25:13.771784 :AAEE9724: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-06-28 21:35:14.619221 :AAEF5EDB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 21:35:14.937622 :AAEF5F4E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 21:35:14.933
2025-06-28 21:35:14.937624 :AAEF5F4F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16332 pso_num=26 pso_serial#=225
2025-06-28 21:35:14.937766 :AAEF5F50:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16332 osp_idx=24 osp_ver=526545 osp_pg=0 (spawn #332450)
2025-06-28 21:35:14.937767 :AAEF5F51:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 21:35:14.937768 :AAEF5F52:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=95 time=4122258125
2025-06-28 21:35:14.937769 :AAEF5F53:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 21:35:14.937769 :AAEF5F54:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 21:35:14.937770 :AAEF5F55:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 21:35:14.937770 :AAEF5F56:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 21:35:14.937770 :AAEF5F57:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 21:35:14.938347 :AAEF5F58: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-06-28 21:35:14.938395 :AAEF5F60: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-06-28 21:45:15.747575 :AAF0284E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 21:45:16.168636 :AAF028C0:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 21:45:16.164
2025-06-28 21:45:16.168638 :AAF028C1:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17948 pso_num=26 pso_serial#=226
2025-06-28 21:45:16.168774 :AAF028C2:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17948 osp_idx=24 osp_ver=526546 osp_pg=0 (spawn #332473)
2025-06-28 21:45:16.168775 :AAF028C3:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 21:45:16.168775 :AAF028C4:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=96 time=4122859360
2025-06-28 21:45:16.168776 :AAF028C5:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 21:45:16.168776 :AAF028C6:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 21:45:16.168776 :AAF028C7:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 21:45:16.168777 :AAF028C8:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 21:45:16.168777 :AAF028C9:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 21:45:16.169256 :AAF028CA: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-06-28 21:45:16.169321 :AAF028D3: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-06-28 21:55:17.138175 :AAF0EFED:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-06-28 21:55:17.439911 :AAF0F052:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-28 21:55:17.435
2025-06-28 21:55:17.439913 :AAF0F053:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16604 pso_num=26 pso_serial#=227
2025-06-28 21:55:17.440050 :AAF0F054:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16604 osp_idx=24 osp_ver=526547 osp_pg=0 (spawn #332495)
2025-06-28 21:55:17.440051 :AAF0F055:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-28 21:55:17.440051 :AAF0F056:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=97 time=4123460625
2025-06-28 21:55:17.440052 :AAF0F057:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-06-28 21:55:17.440052 :AAF0F058:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-06-28 21:55:17.440052 :AAF0F059:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-06-28 21:55:17.440053 :AAF0F05A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-28 21:55:17.440053 :AAF0F05B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-28 21:55:17.440560 :AAF0F05C: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-06-28 21:55:17.440606 :AAF0F064: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-06-28 22:05:04.806450 :AAF1B74C: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-06-28 22:05:18.679978 :AAF1C1D9: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 26 (osid: 16604, W000)