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


*** 2025-06-08 06:00:09.103
*** SESSION ID:(201.9887) 2025-06-08 06:00:09.103
 

*** 2025-06-08 06:00:09.103
Process diagnostic dump for ORACLE.EXE (W000), OS id=14080,
pid: 25, proc_ser: 29, sid: 201, sess_ser: 9887 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=65 seq_num=66 snap_id=1
    wait times: snap=3.952557 sec, exc=3.952557 sec, total=3.952557 sec
    wait times: max=5.000000 sec, heur=5 min 24 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=64 seq_num=65 snap_id=1
    wait times: snap=5.000351 sec, exc=5.000351 sec, total=5.000351 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=63 seq_num=64 snap_id=1
    wait times: snap=5.002638 sec, exc=5.002638 sec, total=5.002638 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=62 seq_num=63 snap_id=1
    wait times: snap=5.013553 sec, exc=5.013553 sec, total=5.013553 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=61 seq_num=62 snap_id=1
    wait times: snap=5.014722 sec, exc=5.014722 sec, total=5.014722 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=60 seq_num=61 snap_id=1
    wait times: snap=5.004634 sec, exc=5.004634 sec, total=5.004634 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=59 seq_num=60 snap_id=1
    wait times: snap=5.015241 sec, exc=5.015241 sec, total=5.015241 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=58 seq_num=59 snap_id=1
    wait times: snap=5.003307 sec, exc=5.003307 sec, total=5.003307 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=57 seq_num=58 snap_id=1
    wait times: snap=5.000425 sec, exc=5.000425 sec, total=5.000425 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=56 seq_num=57 snap_id=1
    wait times: snap=5.001152 sec, exc=5.001152 sec, total=5.001152 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=55 seq_num=56 snap_id=1
    wait times: snap=5.002946 sec, exc=5.002946 sec, total=5.002946 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
Sampled Session History of session 201 serial 9887
---------------------------------------------------
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,                                            05:58:09 - 06:00:09]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-06-08 06:00:09.103
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 25 (osid: 14080, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-08 03:34:28.491684 :A1C988B4:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=16276 (legacy spawn)
2025-06-08 03:34:28.498101 :A1C988B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-06-08 03:34:28.498306 :A1C988BA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-06-08 03:34:28.499120 :A1C988BF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 03:34:28.779198 :A1C9892C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 03:34:28.775
2025-06-08 03:34:28.779200 :A1C9892D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15760 pso_num=25 pso_serial#=16
2025-06-08 03:34:28.779335 :A1C9892E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15760 osp_idx=23 osp_ver=151343 osp_pg=0 (spawn #263252)
2025-06-08 03:34:28.779336 :A1C9892F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 03:34:28.779337 :A1C98930:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=12 time=2329411969
2025-06-08 03:34:28.779337 :A1C98931:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 03:34:28.779337 :A1C98932:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 03:34:28.779338 :A1C98933:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 03:34:28.779338 :A1C98934:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 03:34:28.779339 :A1C98935:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 03:34:28.779832 :A1C98936:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 03:34:28.779879 :A1C9893D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 03:44:29.625870 :A1CA4AFD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 03:44:30.005619 :A1CA4B71:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 03:44:30.001
2025-06-08 03:44:30.005621 :A1CA4B72:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14952 pso_num=25 pso_serial#=17
2025-06-08 03:44:30.005757 :A1CA4B73:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14952 osp_idx=23 osp_ver=151344 osp_pg=0 (spawn #263275)
2025-06-08 03:44:30.005757 :A1CA4B74:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 03:44:30.005758 :A1CA4B75:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=13 time=2330013204
2025-06-08 03:44:30.005758 :A1CA4B76:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 03:44:30.005758 :A1CA4B77:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 03:44:30.005759 :A1CA4B78:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 03:44:30.005759 :A1CA4B79:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 03:44:30.005759 :A1CA4B7A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 03:44:30.006256 :A1CA4B7B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 03:44:30.006303 :A1CA4B83:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 03:54:30.810980 :A1CB100C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 03:54:31.222042 :A1CB1047:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 03:54:31.217
2025-06-08 03:54:31.222045 :A1CB1048:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15036 pso_num=25 pso_serial#=18
2025-06-08 03:54:31.222185 :A1CB1049:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15036 osp_idx=23 osp_ver=151345 osp_pg=0 (spawn #263297)
2025-06-08 03:54:31.222185 :A1CB104A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 03:54:31.222186 :A1CB104B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=14 time=2330614407
2025-06-08 03:54:31.222187 :A1CB104C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 03:54:31.222187 :A1CB104D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 03:54:31.222187 :A1CB104E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 03:54:31.222188 :A1CB104F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 03:54:31.222189 :A1CB1050:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 03:54:31.222696 :A1CB1051:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 03:54:31.222751 :A1CB1059:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:04:32.084812 :A1CBD7F1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 04:04:32.386304 :A1CBD837:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 04:04:32.382
2025-06-08 04:04:32.386306 :A1CBD838:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12796 pso_num=25 pso_serial#=19
2025-06-08 04:04:32.386454 :A1CBD839:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12796 osp_idx=23 osp_ver=151346 osp_pg=0 (spawn #263323)
2025-06-08 04:04:32.386455 :A1CBD83A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 04:04:32.386455 :A1CBD83B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=15 time=2331215579
2025-06-08 04:04:32.386456 :A1CBD83C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 04:04:32.386456 :A1CBD83D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 04:04:32.386456 :A1CBD83E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 04:04:32.386456 :A1CBD83F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 04:04:32.386457 :A1CBD840:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 04:04:32.386965 :A1CBD841:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 04:04:32.387011 :A1CBD84A:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:14:33.139674 :A1CCA11A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 04:14:33.529399 :A1CCA156:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 04:14:33.525
2025-06-08 04:14:33.529401 :A1CCA157:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12380 pso_num=25 pso_serial#=20
2025-06-08 04:14:33.529540 :A1CCA158:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12380 osp_idx=23 osp_ver=151347 osp_pg=0 (spawn #263346)
2025-06-08 04:14:33.529541 :A1CCA159:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 04:14:33.529541 :A1CCA15A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=16 time=2331816719
2025-06-08 04:14:33.529542 :A1CCA15B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 04:14:33.529542 :A1CCA15C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 04:14:33.529542 :A1CCA15D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 04:14:33.529542 :A1CCA15E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 04:14:33.529543 :A1CCA15F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 04:14:33.530097 :A1CCA160:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 04:14:33.530146 :A1CCA168:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:24:34.254350 :A1CD6261:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 04:24:34.602766 :A1CD6299:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 04:24:34.597
2025-06-08 04:24:34.602768 :A1CD629A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12880 pso_num=25 pso_serial#=21
2025-06-08 04:24:34.602903 :A1CD629B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12880 osp_idx=23 osp_ver=151348 osp_pg=0 (spawn #263368)
2025-06-08 04:24:34.602904 :A1CD629C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 04:24:34.602904 :A1CD629D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=17 time=2332417797
2025-06-08 04:24:34.602905 :A1CD629E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 04:24:34.602905 :A1CD629F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 04:24:34.602905 :A1CD62A0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 04:24:34.602905 :A1CD62A1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 04:24:34.602906 :A1CD62A2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 04:24:34.603405 :A1CD62A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 04:24:34.603450 :A1CD62AB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:26:19.915095 :A1CD8403:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:34:35.729246 :A1CE256C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:44:36.607305 :A1CEE79A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 04:44:36.830494 :A1CEE7D6:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 04:44:36.826
2025-06-08 04:44:36.830496 :A1CEE7D7:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16320 pso_num=25 pso_serial#=22
2025-06-08 04:44:36.830632 :A1CEE7D8:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16320 osp_idx=23 osp_ver=151349 osp_pg=0 (spawn #263413)
2025-06-08 04:44:36.830632 :A1CEE7D9:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 04:44:36.830633 :A1CEE7DA:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=18 time=2333620016
2025-06-08 04:44:36.830633 :A1CEE7DB:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 04:44:36.830633 :A1CEE7DC:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 04:44:36.830634 :A1CEE7DD:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 04:44:36.830634 :A1CEE7DE:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 04:44:36.830634 :A1CEE7DF:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 04:44:36.831181 :A1CEE7E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 04:44:36.831223 :A1CEE7E8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 04:54:37.703657 :A1CFA944:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 04:54:37.958503 :A1CFA98A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 04:54:37.954
2025-06-08 04:54:37.958505 :A1CFA98B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10584 pso_num=25 pso_serial#=23
2025-06-08 04:54:37.958664 :A1CFA98C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10584 osp_idx=23 osp_ver=151350 osp_pg=0 (spawn #263435)
2025-06-08 04:54:37.958665 :A1CFA98D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 04:54:37.958665 :A1CFA98E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=19 time=2334221157
2025-06-08 04:54:37.958666 :A1CFA98F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 04:54:37.958667 :A1CFA990:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 04:54:37.958667 :A1CFA991:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 04:54:37.958667 :A1CFA992:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 04:54:37.958668 :A1CFA993:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 04:54:37.959246 :A1CFA994:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 04:54:37.959297 :A1CFA99E:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 05:04:38.865692 :A1D06E81:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 05:04:39.089137 :A1D06EBC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 05:04:39.084
2025-06-08 05:04:39.089138 :A1D06EBD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11644 pso_num=25 pso_serial#=24
2025-06-08 05:04:39.089281 :A1D06EBE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11644 osp_idx=23 osp_ver=151351 osp_pg=0 (spawn #263461)
2025-06-08 05:04:39.089282 :A1D06EBF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:04:39.089283 :A1D06EC0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=20 time=2334822282
2025-06-08 05:04:39.089283 :A1D06EC1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:04:39.089283 :A1D06EC2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:04:39.089284 :A1D06EC3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:04:39.089284 :A1D06EC4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:04:39.089284 :A1D06EC5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:04:39.089777 :A1D06EC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 05:04:39.089823 :A1D06ECD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 05:14:39.930247 :A1D13662:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 05:14:40.263063 :A1D136B9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 05:14:40.258
2025-06-08 05:14:40.263065 :A1D136BA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=8352 pso_num=25 pso_serial#=25
2025-06-08 05:14:40.263211 :A1D136BB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=8352 osp_idx=23 osp_ver=151352 osp_pg=0 (spawn #263484)
2025-06-08 05:14:40.263211 :A1D136BC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:14:40.263212 :A1D136BD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=21 time=2335423454
2025-06-08 05:14:40.263212 :A1D136BE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:14:40.263213 :A1D136BF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:14:40.263213 :A1D136C0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:14:40.263214 :A1D136C1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:14:40.263214 :A1D136C2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:14:40.263763 :A1D136C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 05:14:40.263814 :A1D136CC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 05:24:41.106255 :A1D1FB19:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 05:24:41.282716 :A1D1FB63:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 05:24:41.277
2025-06-08 05:24:41.282718 :A1D1FB64:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13948 pso_num=25 pso_serial#=26
2025-06-08 05:24:41.282851 :A1D1FB65:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13948 osp_idx=23 osp_ver=151353 osp_pg=0 (spawn #263506)
2025-06-08 05:24:41.282852 :A1D1FB66:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:24:41.282852 :A1D1FB67:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=22 time=2336024469
2025-06-08 05:24:41.282852 :A1D1FB68:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:24:41.282853 :A1D1FB69:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:24:41.282853 :A1D1FB6A:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:24:41.282853 :A1D1FB6B:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:24:41.282853 :A1D1FB6C:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:24:41.283330 :A1D1FB6D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 05:24:41.283375 :A1D1FB75:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 05:34:42.167208 :A1D2C065:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 05:34:42.359430 :A1D2C0A0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 05:34:42.354
2025-06-08 05:34:42.359432 :A1D2C0A1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15936 pso_num=25 pso_serial#=27
2025-06-08 05:34:42.359569 :A1D2C0A2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15936 osp_idx=23 osp_ver=151354 osp_pg=0 (spawn #263529)
2025-06-08 05:34:42.359570 :A1D2C0A3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:34:42.359571 :A1D2C0A4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=23 time=2336625547
2025-06-08 05:34:42.359571 :A1D2C0A5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:34:42.359571 :A1D2C0A6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:34:42.359571 :A1D2C0A7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:34:42.359572 :A1D2C0A8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:34:42.359572 :A1D2C0A9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:34:42.360079 :A1D2C0AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 05:34:42.360125 :A1D2C0B4:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 05:44:43.214144 :A1D3858E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 05:44:43.469000 :A1D385C9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 05:44:43.464
2025-06-08 05:44:43.469002 :A1D385CA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15816 pso_num=25 pso_serial#=28
2025-06-08 05:44:43.469145 :A1D385CB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15816 osp_idx=23 osp_ver=151355 osp_pg=0 (spawn #263552)
2025-06-08 05:44:43.469146 :A1D385CC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:44:43.469146 :A1D385CD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=24 time=2337226657
2025-06-08 05:44:43.469147 :A1D385CE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:44:43.469147 :A1D385CF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:44:43.469147 :A1D385D0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:44:43.469148 :A1D385D1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:44:43.469148 :A1D385D2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:44:43.469686 :A1D385D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 05:44:43.469735 :A1D385DC:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-08 05:54:44.342076 :A1D44B8E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted
2025-06-08 05:54:44.643481 :A1D44BC3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-08 05:54:44.639
2025-06-08 05:54:44.643484 :A1D44BC4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14080 pso_num=25 pso_serial#=29
2025-06-08 05:54:44.643611 :A1D44BC5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14080 osp_idx=23 osp_ver=151356 osp_pg=0 (spawn #263574)
2025-06-08 05:54:44.643612 :A1D44BC6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:54:44.643612 :A1D44BC7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=25 time=2337827829
2025-06-08 05:54:44.643613 :A1D44BC8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:54:44.643613 :A1D44BC9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:54:44.643614 :A1D44BCA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:54:44.643614 :A1D44BCB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:54:44.643614 :A1D44BCC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:54:44.644093 :A1D44BCD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-06-08 05:54:44.644136 :A1D44BD7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 14080, W000)