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


*** 2025-08-20 22:00:04.519
*** SESSION ID:(177.33767) 2025-08-20 22:00:04.519
 

*** 2025-08-20 22:00:04.519
Process diagnostic dump for ORACLE.EXE (W000), OS id=21964,
pid: 22, proc_ser: 87, sid: 177, sess_ser: 33767 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=46 seq_num=47 snap_id=1
    wait times: snap=3.972482 sec, exc=3.972482 sec, total=3.972482 sec
    wait times: max=5.000000 sec, heur=3 min 49 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=45 seq_num=46 snap_id=1
    wait times: snap=5.005858 sec, exc=5.005858 sec, total=5.005858 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=44 seq_num=45 snap_id=1
    wait times: snap=5.014730 sec, exc=5.014730 sec, total=5.014730 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=43 seq_num=44 snap_id=1
    wait times: snap=5.010145 sec, exc=5.010145 sec, total=5.010145 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=42 seq_num=43 snap_id=1
    wait times: snap=5.014736 sec, exc=5.014736 sec, total=5.014736 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=41 seq_num=42 snap_id=1
    wait times: snap=5.009743 sec, exc=5.009743 sec, total=5.009743 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=40 seq_num=41 snap_id=1
    wait times: snap=5.003636 sec, exc=5.003636 sec, total=5.003636 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=39 seq_num=40 snap_id=1
    wait times: snap=5.012376 sec, exc=5.012376 sec, total=5.012376 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=38 seq_num=39 snap_id=1
    wait times: snap=5.007411 sec, exc=5.007411 sec, total=5.007411 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=37 seq_num=38 snap_id=1
    wait times: snap=5.010138 sec, exc=5.010138 sec, total=5.010138 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=36 seq_num=37 snap_id=1
    wait times: snap=5.014748 sec, exc=5.014748 sec, total=5.014748 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 33767
---------------------------------------------------
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:04 - 22:00:04]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-20 22:00:04.519
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 21964, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-20 19:25:54.797246 :C260E02E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 19:25:55.270694 :C260E080:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 19:25:55.265
2025-08-20 19:25:55.270696 :C260E081:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16392 pso_num=22 pso_serial#=74
2025-08-20 19:25:55.270833 :C260E082:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16392 osp_idx=0 osp_ver=538706 osp_pg=0 (spawn #508799)
2025-08-20 19:25:55.270834 :C260E083: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-20 19:25:55.270835 :C260E084:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=35 time=103657283
2025-08-20 19:25:55.270835 :C260E085:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 19:25:55.270835 :C260E086:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 19:25:55.270836 :C260E087:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 19:25:55.270836 :C260E088:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 19:25:55.270837 :C260E089:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 19:25:55.271322 :C260E08A: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-20 19:25:55.271375 :C260E093: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-20 19:35:45.492381 :C261A00E: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-20 19:35:56.657491 :C261A4A5: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-20 19:45:57.624447 :C262683F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 19:45:58.076709 :C262688E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 19:45:58.072
2025-08-20 19:45:58.076711 :C262688F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22492 pso_num=22 pso_serial#=75
2025-08-20 19:45:58.076852 :C2626890:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22492 osp_idx=0 osp_ver=538707 osp_pg=0 (spawn #508844)
2025-08-20 19:45:58.076853 :C2626891: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-20 19:45:58.076853 :C2626892:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=36 time=104860080
2025-08-20 19:45:58.076854 :C2626893:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 19:45:58.076854 :C2626894:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 19:45:58.076855 :C2626895:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 19:45:58.076855 :C2626896:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 19:45:58.076856 :C2626897:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 19:45:58.077355 :C2626898: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-20 19:45:58.077400 :C26268A0: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-20 19:55:58.957760 :C2632BCC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 19:55:59.415553 :C2632C41:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 19:55:59.410
2025-08-20 19:55:59.415555 :C2632C42:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20756 pso_num=22 pso_serial#=76
2025-08-20 19:55:59.415686 :C2632C43:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20756 osp_idx=0 osp_ver=538708 osp_pg=0 (spawn #508866)
2025-08-20 19:55:59.415687 :C2632C44: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-20 19:55:59.415688 :C2632C45:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=37 time=105461424
2025-08-20 19:55:59.415688 :C2632C46:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 19:55:59.415688 :C2632C47:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 19:55:59.415689 :C2632C48:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 19:55:59.415689 :C2632C49:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 19:55:59.415689 :C2632C4A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 19:55:59.416180 :C2632C4B: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-20 19:55:59.416229 :C2632C53: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-20 20:06:00.250135 :C263FADB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 20:06:00.786114 :C263FB46:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 20:06:00.781
2025-08-20 20:06:00.786117 :C263FB47:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6364 pso_num=22 pso_serial#=77
2025-08-20 20:06:00.786249 :C263FB48:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6364 osp_idx=0 osp_ver=538709 osp_pg=0 (spawn #508892)
2025-08-20 20:06:00.786250 :C263FB49: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-20 20:06:00.786250 :C263FB4A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=38 time=106062799
2025-08-20 20:06:00.786250 :C263FB4B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 20:06:00.786251 :C263FB4C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 20:06:00.786251 :C263FB4D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 20:06:00.786251 :C263FB4E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 20:06:00.786252 :C263FB4F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 20:06:00.786794 :C263FB50: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-20 20:06:00.786839 :C263FB58: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-20 20:16:01.637950 :C264BED6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 20:16:02.127153 :C264BF51:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 20:16:02.122
2025-08-20 20:16:02.127155 :C264BF52:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20136 pso_num=22 pso_serial#=78
2025-08-20 20:16:02.127342 :C264BF53:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20136 osp_idx=0 osp_ver=538710 osp_pg=0 (spawn #508915)
2025-08-20 20:16:02.127343 :C264BF54: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-20 20:16:02.127343 :C264BF55:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=39 time=106664143
2025-08-20 20:16:02.127344 :C264BF56:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 20:16:02.127344 :C264BF57:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 20:16:02.127344 :C264BF58:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 20:16:02.127345 :C264BF59:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 20:16:02.127345 :C264BF5A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 20:16:02.127866 :C264BF5B: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-20 20:16:02.127913 :C264BF62: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-20 20:26:03.017739 :C2658276:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 20:26:03.407655 :C26582F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 20:26:03.402
2025-08-20 20:26:03.407657 :C26582F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20572 pso_num=22 pso_serial#=79
2025-08-20 20:26:03.407794 :C26582F2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20572 osp_idx=0 osp_ver=538711 osp_pg=0 (spawn #508937)
2025-08-20 20:26:03.407795 :C26582F3: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-20 20:26:03.407795 :C26582F4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=107265424
2025-08-20 20:26:03.407796 :C26582F5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 20:26:03.407796 :C26582F6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 20:26:03.407797 :C26582F7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 20:26:03.407797 :C26582F8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 20:26:03.407797 :C26582F9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 20:26:03.408301 :C26582FA: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-20 20:26:03.408347 :C2658302: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-20 20:36:04.276959 :C2664683:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 20:36:04.828565 :C26646CF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 20:36:04.823
2025-08-20 20:36:04.828567 :C26646D0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20628 pso_num=22 pso_serial#=80
2025-08-20 20:36:04.828708 :C26646D1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20628 osp_idx=0 osp_ver=538712 osp_pg=0 (spawn #508960)
2025-08-20 20:36:04.828708 :C26646D2: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-20 20:36:04.828709 :C26646D3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=107866846
2025-08-20 20:36:04.828709 :C26646D4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 20:36:04.828710 :C26646D5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 20:36:04.828710 :C26646D6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 20:36:04.828710 :C26646D7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 20:36:04.828711 :C26646D8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 20:36:04.829199 :C26646D9: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-20 20:36:04.829243 :C26646E1: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-20 20:46:05.659207 :C2670AB7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 20:46:06.038818 :C2670B04:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 20:46:06.034
2025-08-20 20:46:06.038821 :C2670B05:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22292 pso_num=22 pso_serial#=81
2025-08-20 20:46:06.038959 :C2670B06:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22292 osp_idx=0 osp_ver=538713 osp_pg=0 (spawn #508984)
2025-08-20 20:46:06.038959 :C2670B07: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-20 20:46:06.038960 :C2670B08:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=108468049
2025-08-20 20:46:06.038960 :C2670B09:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 20:46:06.038960 :C2670B0A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 20:46:06.038961 :C2670B0B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 20:46:06.038961 :C2670B0C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 20:46:06.038961 :C2670B0D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 20:46:06.039460 :C2670B0E: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-20 20:46:06.039506 :C2670B16: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-20 20:56:06.874089 :C267CDEE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 20:56:07.394437 :C267CE2F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 20:56:07.390
2025-08-20 20:56:07.394439 :C267CE30:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6744 pso_num=22 pso_serial#=82
2025-08-20 20:56:07.394576 :C267CE31:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6744 osp_idx=0 osp_ver=538714 osp_pg=0 (spawn #509006)
2025-08-20 20:56:07.394577 :C267CE32: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-20 20:56:07.394577 :C267CE33:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=43 time=109069408
2025-08-20 20:56:07.394578 :C267CE34:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 20:56:07.394578 :C267CE35:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 20:56:07.394579 :C267CE36:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 20:56:07.394579 :C267CE37:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 20:56:07.394580 :C267CE38:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 20:56:07.395080 :C267CE39: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-20 20:56:07.395122 :C267CE41: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-20 21:06:08.328668 :C2689C11:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 21:06:08.708480 :C2689C64:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 21:06:08.703
2025-08-20 21:06:08.708482 :C2689C65:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19852 pso_num=22 pso_serial#=83
2025-08-20 21:06:08.708625 :C2689C66:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19852 osp_idx=0 osp_ver=538715 osp_pg=0 (spawn #509032)
2025-08-20 21:06:08.708625 :C2689C67: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-20 21:06:08.708626 :C2689C68:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=44 time=109670721
2025-08-20 21:06:08.708626 :C2689C69:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 21:06:08.708626 :C2689C6A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 21:06:08.708627 :C2689C6B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 21:06:08.708627 :C2689C6C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 21:06:08.708627 :C2689C6D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 21:06:08.709122 :C2689C6E: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-20 21:06:08.709172 :C2689C75: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-20 21:16:09.617063 :C2695EE0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 21:16:10.012437 :C2695F22:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 21:16:10.007
2025-08-20 21:16:10.012439 :C2695F23:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22036 pso_num=22 pso_serial#=84
2025-08-20 21:16:10.012575 :C2695F24:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22036 osp_idx=0 osp_ver=538716 osp_pg=0 (spawn #509055)
2025-08-20 21:16:10.012576 :C2695F25: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-20 21:16:10.012576 :C2695F26:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=45 time=110272018
2025-08-20 21:16:10.012577 :C2695F27:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 21:16:10.012577 :C2695F28:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 21:16:10.012577 :C2695F29:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 21:16:10.012578 :C2695F2A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 21:16:10.012578 :C2695F2B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 21:16:10.013065 :C2695F2C: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-20 21:16:10.013110 :C2695F34: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-20 21:26:10.973291 :C26A2337:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 21:26:11.321729 :C26A2399:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 21:26:11.316
2025-08-20 21:26:11.321730 :C26A239A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20516 pso_num=22 pso_serial#=85
2025-08-20 21:26:11.321864 :C26A239B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20516 osp_idx=0 osp_ver=538717 osp_pg=0 (spawn #509077)
2025-08-20 21:26:11.321865 :C26A239C: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-20 21:26:11.321865 :C26A239D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=46 time=110873330
2025-08-20 21:26:11.321865 :C26A239E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 21:26:11.321866 :C26A239F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 21:26:11.321866 :C26A23A0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 21:26:11.321866 :C26A23A1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 21:26:11.321866 :C26A23A2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 21:26:11.322367 :C26A23A3: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-20 21:26:11.322412 :C26A23AB: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-20 21:36:12.130000 :C26AE6D4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 21:36:12.588528 :C26AE731:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 21:36:12.583
2025-08-20 21:36:12.588530 :C26AE732:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14024 pso_num=22 pso_serial#=86
2025-08-20 21:36:12.588664 :C26AE733:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14024 osp_idx=0 osp_ver=538718 osp_pg=0 (spawn #509100)
2025-08-20 21:36:12.588664 :C26AE734: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-20 21:36:12.588665 :C26AE735:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=47 time=111474596
2025-08-20 21:36:12.588665 :C26AE736:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 21:36:12.588666 :C26AE737:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 21:36:12.588666 :C26AE738:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 21:36:12.588666 :C26AE739:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 21:36:12.588667 :C26AE73A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 21:36:12.589183 :C26AE73B: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-20 21:36:12.589230 :C26AE743: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-20 21:40:46.267110 :C26B40BE: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-20 21:46:13.938225 :C26BAD74: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-20 21:56:14.770476 :C26C7390:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-20 21:56:15.213767 :C26C73F4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-20 21:56:15.209
2025-08-20 21:56:15.213769 :C26C73F5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21964 pso_num=22 pso_serial#=87
2025-08-20 21:56:15.213903 :C26C73F6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21964 osp_idx=0 osp_ver=538719 osp_pg=0 (spawn #509144)
2025-08-20 21:56:15.213904 :C26C73F7: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-20 21:56:15.213905 :C26C73F8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=48 time=112677221
2025-08-20 21:56:15.213905 :C26C73F9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-20 21:56:15.213905 :C26C73FA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-20 21:56:15.213905 :C26C73FB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-20 21:56:15.213906 :C26C73FC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-20 21:56:15.213906 :C26C73FD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-20 21:56:15.214398 :C26C73FE: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-20 21:56:15.214442 :C26C7406: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: 21964, W000)