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


*** 2025-08-10 22:09:56.251
*** SESSION ID:(177.14043) 2025-08-10 22:09:56.251
 

*** 2025-08-10 22:09:56.251
Process diagnostic dump for ORACLE.EXE (W000), OS id=19108,
pid: 22, proc_ser: 113, sid: 177, sess_ser: 14043 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=59 seq_num=60 snap_id=1
    wait times: snap=3.516234 sec, exc=3.516234 sec, total=3.516234 sec
    wait times: max=5.000000 sec, heur=4 min 53 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.000020 sec since current wait
 0: 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.014712 sec, exc=5.014712 sec, total=5.014712 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 1: 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.005152 sec, exc=5.005152 sec, total=5.005152 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 2: 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.003560 sec, exc=5.003560 sec, total=5.003560 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 3: 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.013704 sec, exc=5.013704 sec, total=5.013704 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=54 seq_num=55 snap_id=1
    wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000037 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=53 seq_num=54 snap_id=1
    wait times: snap=5.004604 sec, exc=5.004604 sec, total=5.004604 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000033 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=52 seq_num=53 snap_id=1
    wait times: snap=5.009733 sec, exc=5.009733 sec, total=5.009733 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=51 seq_num=52 snap_id=1
    wait times: snap=5.002156 sec, exc=5.002156 sec, total=5.002156 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=50 seq_num=51 snap_id=1
    wait times: snap=5.001416 sec, exc=5.001416 sec, total=5.001416 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=49 seq_num=50 snap_id=1
    wait times: snap=5.008262 sec, exc=5.008262 sec, total=5.008262 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
Sampled Session History of session 177 serial 14043
---------------------------------------------------
The sampled session history is constructed by sampling
the target session every 1 second. The sampling process
captures at each sample if the session is in a non-idle wait,
an idle wait, or not in a wait. If the session is in a
non-idle wait then one interval is shown for all the samples
the session was in the same non-idle wait. If the
session is in an idle wait or not in a wait for
consecutive samples then one interval is shown for all
the consecutive samples. Though we display these consecutive
samples  in a single interval the session may NOT be continuously
idle or not in a wait (the sampling process does not know).
 
The history is displayed in reverse chronological order.
 
sample interval: 1 sec, max history 120 sec
---------------------------------------------------
  [121 samples,                                            22:07:56 - 22:09:56]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-10 22:09:56.251
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 19108, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-10 19:44:44.382452 :BDFD9612:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19264 (legacy spawn)
2025-08-10 19:44:44.398502 :BDFD9613:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-10 19:44:44.398684 :BDFD961C:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-08-10 19:44:44.399588 :BDFD961D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 19:44:44.432910 :BDFD9642:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 19:44:44.428
2025-08-10 19:44:44.432913 :BDFD9643:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20624 pso_num=22 pso_serial#=100
2025-08-10 19:44:44.433053 :BDFD9644:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20624 osp_idx=0 osp_ver=502522 osp_pg=0 (spawn #475512)
2025-08-10 19:44:44.433054 :BDFD9645: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-10 19:44:44.433054 :BDFD9646:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=240 time=3535753736
2025-08-10 19:44:44.433055 :BDFD9647:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 19:44:44.433055 :BDFD9648:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 19:44:44.433055 :BDFD9649:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 19:44:44.433056 :BDFD964A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 19:44:44.433056 :BDFD964B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 19:44:44.433548 :BDFD964C: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-10 19:44:44.433593 :BDFD9654: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-10 19:54:45.270348 :BDFE5AEB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 19:54:45.618874 :BDFE5B44:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 19:54:45.614
2025-08-10 19:54:45.618876 :BDFE5B45:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10620 pso_num=22 pso_serial#=101
2025-08-10 19:54:45.619014 :BDFE5B46:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10620 osp_idx=0 osp_ver=502523 osp_pg=0 (spawn #475535)
2025-08-10 19:54:45.619015 :BDFE5B47: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-10 19:54:45.619015 :BDFE5B48:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=241 time=3536354923
2025-08-10 19:54:45.619016 :BDFE5B49:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 19:54:45.619016 :BDFE5B4A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 19:54:45.619017 :BDFE5B4B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 19:54:45.619017 :BDFE5B4C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 19:54:45.619017 :BDFE5B4D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 19:54:45.619506 :BDFE5B4E: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-10 19:54:45.619551 :BDFE5B55: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-10 20:04:46.409413 :BDFF255D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 20:04:46.997416 :BDFF259C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 20:04:46.992
2025-08-10 20:04:46.997418 :BDFF259D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20936 pso_num=22 pso_serial#=102
2025-08-10 20:04:46.997553 :BDFF259E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20936 osp_idx=0 osp_ver=502524 osp_pg=0 (spawn #475560)
2025-08-10 20:04:46.997553 :BDFF259F: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-10 20:04:46.997554 :BDFF25A0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=242 time=3536956298
2025-08-10 20:04:46.997554 :BDFF25A1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 20:04:46.997555 :BDFF25A2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 20:04:46.997555 :BDFF25A3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 20:04:46.997555 :BDFF25A4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 20:04:46.997555 :BDFF25A5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 20:04:46.998039 :BDFF25A6: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-10 20:04:46.998085 :BDFF25AE: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-10 20:14:47.909719 :BDFFF210:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 20:14:48.368040 :BDFFF25A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 20:14:48.363
2025-08-10 20:14:48.368042 :BDFFF25B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20628 pso_num=22 pso_serial#=103
2025-08-10 20:14:48.368182 :BDFFF25C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20628 osp_idx=0 osp_ver=502525 osp_pg=0 (spawn #475584)
2025-08-10 20:14:48.368183 :BDFFF25D: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-10 20:14:48.368183 :BDFFF25E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=243 time=3537557673
2025-08-10 20:14:48.368184 :BDFFF25F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 20:14:48.368184 :BDFFF260:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 20:14:48.368184 :BDFFF261:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 20:14:48.368185 :BDFFF262:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 20:14:48.368185 :BDFFF263:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 20:14:48.368688 :BDFFF264: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-10 20:14:48.368740 :BDFFF26A: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-10 20:16:42.280628 :BE00173A: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-10 20:24:49.668986 :BE00B82B: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-10 20:34:50.542818 :BE017DAF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 20:34:51.000622 :BE017E16:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 20:34:50.995
2025-08-10 20:34:51.000624 :BE017E17:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11336 pso_num=22 pso_serial#=104
2025-08-10 20:34:51.000761 :BE017E18:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11336 osp_idx=0 osp_ver=502526 osp_pg=0 (spawn #475628)
2025-08-10 20:34:51.000761 :BE017E19: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-10 20:34:51.000762 :BE017E1A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=244 time=3538760314
2025-08-10 20:34:51.000762 :BE017E1B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 20:34:51.000763 :BE017E1C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 20:34:51.000763 :BE017E1D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 20:34:51.000763 :BE017E1E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 20:34:51.000764 :BE017E1F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 20:34:51.001267 :BE017E20: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-10 20:34:51.001315 :BE017E27: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-10 20:44:51.901203 :BE0243B9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 20:44:52.422041 :BE02442C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 20:44:52.416
2025-08-10 20:44:52.422043 :BE02442D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21008 pso_num=22 pso_serial#=105
2025-08-10 20:44:52.422195 :BE02442E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21008 osp_idx=0 osp_ver=502527 osp_pg=0 (spawn #475651)
2025-08-10 20:44:52.422195 :BE02442F: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-10 20:44:52.422196 :BE024430:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=245 time=3539361720
2025-08-10 20:44:52.422196 :BE024431:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 20:44:52.422197 :BE024432:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 20:44:52.422198 :BE024433:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 20:44:52.422198 :BE024434:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 20:44:52.422198 :BE024435:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 20:44:52.422777 :BE024436: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-10 20:44:52.422830 :BE02443D: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-10 20:54:53.222877 :BE0308EC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 20:54:53.680799 :BE030929:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 20:54:53.676
2025-08-10 20:54:53.680801 :BE03092A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16988 pso_num=22 pso_serial#=106
2025-08-10 20:54:53.680942 :BE03092B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16988 osp_idx=0 osp_ver=502528 osp_pg=0 (spawn #475674)
2025-08-10 20:54:53.680943 :BE03092C: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-10 20:54:53.680943 :BE03092D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=246 time=3539962986
2025-08-10 20:54:53.680943 :BE03092E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 20:54:53.680944 :BE03092F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 20:54:53.680944 :BE030930:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 20:54:53.680944 :BE030931:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 20:54:53.680945 :BE030932:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 20:54:53.681437 :BE030933: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-10 20:54:53.681492 :BE03093B: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-10 21:04:54.505826 :BE03D20D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 21:04:55.041867 :BE03D259:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 21:04:55.036
2025-08-10 21:04:55.041869 :BE03D25A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21324 pso_num=22 pso_serial#=107
2025-08-10 21:04:55.042014 :BE03D25B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21324 osp_idx=0 osp_ver=502529 osp_pg=0 (spawn #475699)
2025-08-10 21:04:55.042015 :BE03D25C: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-10 21:04:55.042015 :BE03D25D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=247 time=3540564345
2025-08-10 21:04:55.042016 :BE03D25E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 21:04:55.042016 :BE03D25F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 21:04:55.042016 :BE03D260:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 21:04:55.042017 :BE03D261:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 21:04:55.042017 :BE03D262:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 21:04:55.042515 :BE03D263: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-10 21:04:55.042561 :BE03D26B: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-10 21:14:55.848064 :BE049DAE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 21:14:56.321522 :BE049DEC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 21:14:56.317
2025-08-10 21:14:56.321524 :BE049DED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6128 pso_num=22 pso_serial#=108
2025-08-10 21:14:56.321665 :BE049DEE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6128 osp_idx=0 osp_ver=502530 osp_pg=0 (spawn #475722)
2025-08-10 21:14:56.321666 :BE049DEF: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-10 21:14:56.321667 :BE049DF0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=248 time=3541165626
2025-08-10 21:14:56.321667 :BE049DF1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 21:14:56.321667 :BE049DF2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 21:14:56.321668 :BE049DF3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 21:14:56.321668 :BE049DF4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 21:14:56.321668 :BE049DF5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 21:14:56.322163 :BE049DF6: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-10 21:14:56.322209 :BE049DFC: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-10 21:24:57.229190 :BE0564FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 21:24:57.530886 :BE05654B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 21:24:57.526
2025-08-10 21:24:57.530888 :BE05654C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21104 pso_num=22 pso_serial#=109
2025-08-10 21:24:57.531022 :BE05654D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21104 osp_idx=0 osp_ver=502531 osp_pg=0 (spawn #475745)
2025-08-10 21:24:57.531022 :BE05654E: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-10 21:24:57.531023 :BE05654F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=249 time=3541766829
2025-08-10 21:24:57.531023 :BE056550:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 21:24:57.531024 :BE056551:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 21:24:57.531024 :BE056552:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 21:24:57.531024 :BE056553:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 21:24:57.531025 :BE056554:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 21:24:57.531523 :BE056555: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-10 21:24:57.531567 :BE05655C: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-10 21:34:58.482962 :BE06281E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 21:34:58.894044 :BE062858:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 21:34:58.889
2025-08-10 21:34:58.894046 :BE062859:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19532 pso_num=22 pso_serial#=110
2025-08-10 21:34:58.894193 :BE06285A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19532 osp_idx=0 osp_ver=502532 osp_pg=0 (spawn #475767)
2025-08-10 21:34:58.894194 :BE06285B: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-10 21:34:58.894194 :BE06285C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=250 time=3542368204
2025-08-10 21:34:58.894194 :BE06285D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 21:34:58.894195 :BE06285E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 21:34:58.894195 :BE06285F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 21:34:58.894195 :BE062860:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 21:34:58.894196 :BE062861:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 21:34:58.894700 :BE062862: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-10 21:34:58.894747 :BE062869: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-10 21:44:59.725430 :BE06ECB2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 21:44:59.995714 :BE06ECF0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 21:44:59.991
2025-08-10 21:44:59.995716 :BE06ECF1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18376 pso_num=22 pso_serial#=111
2025-08-10 21:44:59.995867 :BE06ECF2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18376 osp_idx=0 osp_ver=502533 osp_pg=0 (spawn #475790)
2025-08-10 21:44:59.995868 :BE06ECF3: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-10 21:44:59.995868 :BE06ECF4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=251 time=3542969298
2025-08-10 21:44:59.995869 :BE06ECF5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 21:44:59.995869 :BE06ECF6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 21:44:59.995869 :BE06ECF7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 21:44:59.995870 :BE06ECF8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 21:44:59.995870 :BE06ECF9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 21:44:59.996401 :BE06ECFA: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-10 21:44:59.996446 :BE06ED02: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-10 21:55:00.915932 :BE07AF35:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 21:55:01.233122 :BE07AF7A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 21:55:01.228
2025-08-10 21:55:01.233125 :BE07AF7B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1852 pso_num=22 pso_serial#=112
2025-08-10 21:55:01.233296 :BE07AF7C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1852 osp_idx=0 osp_ver=502534 osp_pg=0 (spawn #475813)
2025-08-10 21:55:01.233296 :BE07AF7D: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-10 21:55:01.233297 :BE07AF7E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=252 time=3543570533
2025-08-10 21:55:01.233297 :BE07AF7F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 21:55:01.233297 :BE07AF80:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 21:55:01.233298 :BE07AF81:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 21:55:01.233298 :BE07AF82:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 21:55:01.233298 :BE07AF83:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 21:55:01.233797 :BE07AF84: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-10 21:55:01.233842 :BE07AF8C: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-10 22:05:02.074674 :BE087423:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-10 22:05:02.360776 :BE087461:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-10 22:05:02.356
2025-08-10 22:05:02.360778 :BE087462:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19108 pso_num=22 pso_serial#=113
2025-08-10 22:05:02.360922 :BE087463:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19108 osp_idx=0 osp_ver=502535 osp_pg=0 (spawn #475838)
2025-08-10 22:05:02.360923 :BE087464: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-10 22:05:02.360924 :BE087465:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=253 time=3544171673
2025-08-10 22:05:02.360924 :BE087466:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-10 22:05:02.360924 :BE087467:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-10 22:05:02.360924 :BE087468:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-10 22:05:02.360925 :BE087469:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-10 22:05:02.360925 :BE08746A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-10 22:05:02.361443 :BE08746B: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-10 22:05:02.361485 :BE087472: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: 19108, W000)