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


*** 2025-07-25 22:00:08.286
*** SESSION ID:(177.23257) 2025-07-25 22:00:08.286
 

*** 2025-07-25 22:00:08.286
Process diagnostic dump for ORACLE.EXE (W000), OS id=19156,
pid: 22, proc_ser: 179, sid: 177, sess_ser: 23257 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=2.357242 sec, exc=2.357242 sec, total=2.357242 sec
    wait times: max=5.000000 sec, heur=42.408562 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.000026 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=5.007245 sec, exc=5.007245 sec, total=5.007245 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=5.001304 sec, exc=5.001304 sec, total=5.001304 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=6 seq_num=7 snap_id=1
    wait times: snap=5.001897 sec, exc=5.001897 sec, total=5.001897 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=5.006014 sec, exc=5.006014 sec, total=5.006014 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=5.010149 sec, exc=5.010149 sec, total=5.010149 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=5.014714 sec, exc=5.014714 sec, total=5.014714 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=2 seq_num=3 snap_id=1
    wait times: snap=5.004613 sec, exc=5.004613 sec, total=5.004613 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=5.005179 sec, exc=5.005179 sec, total=5.005179 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.004018 sec of elapsed time
 8: waited for 'class slave wait'
    slave id=0x12399330, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.000041 sec, exc=0.000041 sec, total=0.000041 sec
    wait times: max=infinite
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 177 serial 23257
---------------------------------------------------
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
---------------------------------------------------
  [43 samples,                                             21:59:25 - 22:00:08]
    idle wait at each sample
  [session created at: 21:59:25]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-25 22:00:08.286
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 19156, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-25 19:19:07.842858 :B6E5AF54: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-07-25 19:29:08.531904 :B6E6766F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 19:29:09.036605 :B6E676BC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 19:29:09.032
2025-07-25 19:29:09.036607 :B6E676BD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15788 pso_num=22 pso_serial#=166
2025-07-25 19:29:09.036743 :B6E676BE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15788 osp_idx=0 osp_ver=450823 osp_pg=0 (spawn #422136)
2025-07-25 19:29:09.036744 :B6E676BF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 19:29:09.036744 :B6E676C0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=218 time=2152524923
2025-07-25 19:29:09.036745 :B6E676C1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 19:29:09.036745 :B6E676C2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 19:29:09.036745 :B6E676C3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 19:29:09.036746 :B6E676C4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 19:29:09.036746 :B6E676C5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 19:29:09.037270 :B6E676C6: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-07-25 19:29:09.037316 :B6E676CD: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-07-25 19:39:09.788012 :B6E73F43:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 19:39:10.183392 :B6E73F83:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 19:39:10.179
2025-07-25 19:39:10.183394 :B6E73F84:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18156 pso_num=22 pso_serial#=167
2025-07-25 19:39:10.183538 :B6E73F85:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18156 osp_idx=0 osp_ver=450824 osp_pg=0 (spawn #422157)
2025-07-25 19:39:10.183539 :B6E73F86:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 19:39:10.183539 :B6E73F87:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=219 time=2153126079
2025-07-25 19:39:10.183540 :B6E73F88:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 19:39:10.183540 :B6E73F89:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 19:39:10.183540 :B6E73F8A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 19:39:10.183541 :B6E73F8B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 19:39:10.183541 :B6E73F8C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 19:39:10.184052 :B6E73F8D: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-07-25 19:39:10.184101 :B6E73F95: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-07-25 19:42:21.294851 :B6E77E4C: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-07-25 19:49:11.488228 :B6E80728: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-07-25 19:59:12.217467 :B6E8CFD2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 19:59:12.628275 :B6E8D02B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 19:59:12.623
2025-07-25 19:59:12.628277 :B6E8D02C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18984 pso_num=22 pso_serial#=168
2025-07-25 19:59:12.628410 :B6E8D02D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18984 osp_idx=0 osp_ver=450825 osp_pg=0 (spawn #422202)
2025-07-25 19:59:12.628411 :B6E8D02E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 19:59:12.628412 :B6E8D02F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=220 time=2154328517
2025-07-25 19:59:12.628412 :B6E8D030:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 19:59:12.628412 :B6E8D031:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 19:59:12.628413 :B6E8D032:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 19:59:12.628413 :B6E8D033:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 19:59:12.628413 :B6E8D034:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 19:59:12.628948 :B6E8D035: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-07-25 19:59:12.629002 :B6E8D03D: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-07-25 20:09:13.349258 :B6E99FDC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 20:09:13.747506 :B6E9A044:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 20:09:13.742
2025-07-25 20:09:13.747508 :B6E9A045:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17752 pso_num=22 pso_serial#=169
2025-07-25 20:09:13.747643 :B6E9A046:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17752 osp_idx=0 osp_ver=450826 osp_pg=0 (spawn #422228)
2025-07-25 20:09:13.747644 :B6E9A047:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 20:09:13.747644 :B6E9A048:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=221 time=2154929642
2025-07-25 20:09:13.747645 :B6E9A049:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 20:09:13.747645 :B6E9A04A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 20:09:13.747645 :B6E9A04B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 20:09:13.747646 :B6E9A04C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 20:09:13.747648 :B6E9A04D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 20:09:13.748135 :B6E9A04E: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-07-25 20:09:13.748192 :B6E9A054: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-07-25 20:19:14.462006 :B6EA6B44:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 20:19:14.883928 :B6EA6BBD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 20:19:14.879
2025-07-25 20:19:14.883930 :B6EA6BBE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6512 pso_num=22 pso_serial#=170
2025-07-25 20:19:14.884070 :B6EA6BBF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6512 osp_idx=0 osp_ver=450827 osp_pg=0 (spawn #422250)
2025-07-25 20:19:14.884071 :B6EA6BC0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 20:19:14.884071 :B6EA6BC1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=222 time=2155530783
2025-07-25 20:19:14.884072 :B6EA6BC2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 20:19:14.884072 :B6EA6BC3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 20:19:14.884072 :B6EA6BC4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 20:19:14.884073 :B6EA6BC5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 20:19:14.884073 :B6EA6BC6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 20:19:14.884567 :B6EA6BC7: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-07-25 20:19:14.884612 :B6EA6BCF: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-07-25 20:29:15.706676 :B6EB32F7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 20:29:15.914727 :B6EB3335:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 20:29:15.909
2025-07-25 20:29:15.914728 :B6EB3336:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18960 pso_num=22 pso_serial#=171
2025-07-25 20:29:15.914877 :B6EB3337:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18960 osp_idx=0 osp_ver=450828 osp_pg=0 (spawn #422273)
2025-07-25 20:29:15.914878 :B6EB3338:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 20:29:15.914878 :B6EB3339:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=223 time=2156131814
2025-07-25 20:29:15.914878 :B6EB333A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 20:29:15.914879 :B6EB333B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 20:29:15.914879 :B6EB333C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 20:29:15.914879 :B6EB333D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 20:29:15.914879 :B6EB333E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 20:29:15.915404 :B6EB333F: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-07-25 20:29:15.915456 :B6EB3346: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-07-25 20:39:16.605469 :B6EBFFB7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 20:39:16.938426 :B6EBFFFC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 20:39:16.934
2025-07-25 20:39:16.938428 :B6EBFFFD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14268 pso_num=22 pso_serial#=172
2025-07-25 20:39:16.938572 :B6EBFFFE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14268 osp_idx=0 osp_ver=450829 osp_pg=0 (spawn #422296)
2025-07-25 20:39:16.938573 :B6EBFFFF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 20:39:16.938573 :B6EC0000:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=2156732829
2025-07-25 20:39:16.938574 :B6EC0001:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 20:39:16.938575 :B6EC0002:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 20:39:16.938575 :B6EC0003:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 20:39:16.938575 :B6EC0004:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 20:39:16.938576 :B6EC0005:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 20:39:16.939077 :B6EC0006: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-07-25 20:39:16.939125 :B6EC000F: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-07-25 20:49:17.707721 :B6ECCAB1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 20:49:18.009374 :B6ECCAFD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 20:49:18.004
2025-07-25 20:49:18.009376 :B6ECCAFE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19048 pso_num=22 pso_serial#=173
2025-07-25 20:49:18.009509 :B6ECCAFF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19048 osp_idx=0 osp_ver=450830 osp_pg=0 (spawn #422318)
2025-07-25 20:49:18.009510 :B6ECCB00:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 20:49:18.009511 :B6ECCB01:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=2157333908
2025-07-25 20:49:18.009511 :B6ECCB02:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 20:49:18.009512 :B6ECCB03:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 20:49:18.009512 :B6ECCB04:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 20:49:18.009512 :B6ECCB05:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 20:49:18.009513 :B6ECCB06:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 20:49:18.010010 :B6ECCB07: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-07-25 20:49:18.010059 :B6ECCB10: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-07-25 20:59:18.814108 :B6ED95D8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 20:59:19.100091 :B6ED961B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 20:59:19.095
2025-07-25 20:59:19.100094 :B6ED961C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18444 pso_num=22 pso_serial#=174
2025-07-25 20:59:19.100228 :B6ED961D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18444 osp_idx=0 osp_ver=450831 osp_pg=0 (spawn #422341)
2025-07-25 20:59:19.100229 :B6ED961E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 20:59:19.100229 :B6ED961F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=226 time=2157935001
2025-07-25 20:59:19.100230 :B6ED9620:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 20:59:19.100230 :B6ED9621:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 20:59:19.100231 :B6ED9622:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 20:59:19.100231 :B6ED9623:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 20:59:19.100231 :B6ED9624:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 20:59:19.100715 :B6ED9625: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-07-25 20:59:19.100760 :B6ED962C: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-07-25 21:09:19.903975 :B6EE69D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 21:09:20.283701 :B6EE6A16:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 21:09:20.279
2025-07-25 21:09:20.283704 :B6EE6A17:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1388 pso_num=22 pso_serial#=175
2025-07-25 21:09:20.283839 :B6EE6A18:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1388 osp_idx=0 osp_ver=450832 osp_pg=0 (spawn #422367)
2025-07-25 21:09:20.283839 :B6EE6A19:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 21:09:20.283840 :B6EE6A1A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=227 time=2158536173
2025-07-25 21:09:20.283840 :B6EE6A1B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 21:09:20.283841 :B6EE6A1C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 21:09:20.283841 :B6EE6A1D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 21:09:20.283841 :B6EE6A1E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 21:09:20.283842 :B6EE6A1F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 21:09:20.284333 :B6EE6A20: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-07-25 21:09:20.284379 :B6EE6A29: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-07-25 21:19:21.060096 :B6EF36FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 21:19:21.408629 :B6EF3743:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 21:19:21.404
2025-07-25 21:19:21.408631 :B6EF3744:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1216 pso_num=22 pso_serial#=176
2025-07-25 21:19:21.408768 :B6EF3745:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1216 osp_idx=0 osp_ver=450833 osp_pg=0 (spawn #422389)
2025-07-25 21:19:21.408769 :B6EF3746:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 21:19:21.408769 :B6EF3747:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=228 time=2159137298
2025-07-25 21:19:21.408769 :B6EF3748:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 21:19:21.408770 :B6EF3749:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 21:19:21.408770 :B6EF374A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 21:19:21.408771 :B6EF374B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 21:19:21.408771 :B6EF374C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 21:19:21.409268 :B6EF374D: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-07-25 21:19:21.409314 :B6EF3755: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-07-25 21:29:22.194603 :B6F00268:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 21:29:22.470267 :B6F002A4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 21:29:22.465
2025-07-25 21:29:22.470269 :B6F002A5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19316 pso_num=22 pso_serial#=177
2025-07-25 21:29:22.470414 :B6F002A6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19316 osp_idx=0 osp_ver=450834 osp_pg=0 (spawn #422412)
2025-07-25 21:29:22.470414 :B6F002A7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 21:29:22.470415 :B6F002A8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=229 time=2159738361
2025-07-25 21:29:22.470415 :B6F002A9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 21:29:22.470416 :B6F002AA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 21:29:22.470416 :B6F002AB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 21:29:22.470416 :B6F002AC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 21:29:22.470417 :B6F002AD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 21:29:22.470919 :B6F002AE: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-07-25 21:29:22.470966 :B6F002B8: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-07-25 21:39:23.286907 :B6F0CAFE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 21:39:23.667363 :B6F0CB4C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 21:39:23.662
2025-07-25 21:39:23.667365 :B6F0CB4D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19268 pso_num=22 pso_serial#=178
2025-07-25 21:39:23.667500 :B6F0CB4E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19268 osp_idx=0 osp_ver=450835 osp_pg=0 (spawn #422435)
2025-07-25 21:39:23.667501 :B6F0CB4F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 21:39:23.667501 :B6F0CB50:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=230 time=2160339564
2025-07-25 21:39:23.667501 :B6F0CB51:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 21:39:23.667502 :B6F0CB52:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 21:39:23.667502 :B6F0CB53:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 21:39:23.667502 :B6F0CB54:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 21:39:23.667502 :B6F0CB55:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 21:39:23.667995 :B6F0CB56: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-07-25 21:39:23.668040 :B6F0CB5E: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-07-25 21:45:42.510220 :B6F14955: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-07-25 21:49:24.766963 :B6F19389: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-07-25 21:59:25.532901 :B6F25B09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-07-25 21:59:25.881412 :B6F25B4A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-25 21:59:25.877
2025-07-25 21:59:25.881414 :B6F25B4B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19156 pso_num=22 pso_serial#=179
2025-07-25 21:59:25.881548 :B6F25B4C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19156 osp_idx=0 osp_ver=450836 osp_pg=0 (spawn #422479)
2025-07-25 21:59:25.881549 :B6F25B4D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-25 21:59:25.881550 :B6F25B4E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=231 time=2161541783
2025-07-25 21:59:25.881550 :B6F25B4F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-07-25 21:59:25.881551 :B6F25B50:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-07-25 21:59:25.881551 :B6F25B51:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-07-25 21:59:25.881551 :B6F25B52:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-25 21:59:25.881552 :B6F25B53:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-25 21:59:25.882103 :B6F25B54: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-07-25 21:59:25.882149 :B6F25B5C: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: 19156, W000)