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


*** 2025-08-31 14:06:40.830
*** SESSION ID:(177.13475) 2025-08-31 14:06:40.830
 

*** 2025-08-31 14:06:40.830
Process diagnostic dump for ORACLE.EXE (W000), OS id=17200,
pid: 22, proc_ser: 249, sid: 177, sess_ser: 13475 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=90 seq_num=91 snap_id=1
    wait times: snap=2.644576 sec, exc=2.644576 sec, total=2.644576 sec
    wait times: max=5.000000 sec, heur=7 min 28 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.000033 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=89 seq_num=90 snap_id=1
    wait times: snap=5.007234 sec, exc=5.007234 sec, total=5.007234 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=88 seq_num=89 snap_id=1
    wait times: snap=5.001347 sec, exc=5.001347 sec, total=5.001347 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=87 seq_num=88 snap_id=1
    wait times: snap=5.000417 sec, exc=5.000417 sec, total=5.000417 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=86 seq_num=87 snap_id=1
    wait times: snap=5.000186 sec, exc=5.000186 sec, total=5.000186 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=85 seq_num=86 snap_id=1
    wait times: snap=5.005981 sec, exc=5.005981 sec, total=5.005981 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000043 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=84 seq_num=85 snap_id=1
    wait times: snap=5.004570 sec, exc=5.004570 sec, total=5.004570 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000076 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=83 seq_num=84 snap_id=1
    wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000038 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=82 seq_num=83 snap_id=1
    wait times: snap=5.015367 sec, exc=5.015367 sec, total=5.015367 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=81 seq_num=82 snap_id=1
    wait times: snap=5.003306 sec, exc=5.003306 sec, total=5.003306 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=80 seq_num=81 snap_id=1
    wait times: snap=5.015013 sec, exc=5.015013 sec, total=5.015013 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
Sampled Session History of session 177 serial 13475
---------------------------------------------------
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,                                            14:04:40 - 14:06:40]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-31 14:06:40.830
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 17200, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-31 11:38:55.748941 :C712FB71:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23024 osp_idx=0 osp_ver=557992 osp_pg=0 (spawn #544395)
2025-08-31 11:38:55.748942 :C712FB72: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-31 11:38:55.748942 :C712FB73:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=14 time=1025987049
2025-08-31 11:38:55.748943 :C712FB74:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 11:38:55.748943 :C712FB75:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 11:38:55.748944 :C712FB76:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 11:38:55.748944 :C712FB77:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 11:38:55.748944 :C712FB78:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 11:38:55.749431 :C712FB79: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-31 11:38:55.749472 :C712FB80: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-31 11:48:56.649281 :C713C190:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 11:48:56.924797 :C713C1C5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 11:48:56.920
2025-08-31 11:48:56.924799 :C713C1C6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6744 pso_num=22 pso_serial#=236
2025-08-31 11:48:56.924938 :C713C1C7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6744 osp_idx=0 osp_ver=557993 osp_pg=0 (spawn #544418)
2025-08-31 11:48:56.924939 :C713C1C8: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-31 11:48:56.924939 :C713C1C9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=15 time=1026588237
2025-08-31 11:48:56.924940 :C713C1CA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 11:48:56.924940 :C713C1CB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 11:48:56.924941 :C713C1CC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 11:48:56.924941 :C713C1CD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 11:48:56.924942 :C713C1CE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 11:48:56.925436 :C713C1CF: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-31 11:48:56.925489 :C713C1D9: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-31 11:58:57.741574 :C7148831:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 11:58:57.859865 :C7148834:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 11:58:57.851
2025-08-31 11:58:57.859867 :C7148835:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2268 pso_num=22 pso_serial#=237
2025-08-31 11:58:57.860004 :C7148836:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2268 (legacy spawn)
2025-08-31 11:58:57.866550 :C7148837: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-31 11:58:57.866719 :C714883C: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-31 11:58:57.867528 :C7148841:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 11:58:58.058676 :C7148878:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 11:58:58.054
2025-08-31 11:58:58.058679 :C7148879:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21328 pso_num=22 pso_serial#=238
2025-08-31 11:58:58.058838 :C714887A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21328 osp_idx=0 osp_ver=557995 osp_pg=0 (spawn #544440)
2025-08-31 11:58:58.058838 :C714887B: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-31 11:58:58.058839 :C714887C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=16 time=1027189362
2025-08-31 11:58:58.058839 :C714887D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 11:58:58.058839 :C714887E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 11:58:58.058840 :C714887F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 11:58:58.058840 :C7148880:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 11:58:58.058840 :C7148881:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 11:58:58.059348 :C7148882: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-31 11:58:58.059393 :C7148889: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-31 12:08:58.980241 :C71556F5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 12:08:59.235055 :C7155731:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 12:08:59.230
2025-08-31 12:08:59.235058 :C7155732:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21988 pso_num=22 pso_serial#=239
2025-08-31 12:08:59.235218 :C7155733:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21988 osp_idx=0 osp_ver=557996 osp_pg=0 (spawn #544466)
2025-08-31 12:08:59.235219 :C7155734: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-31 12:08:59.235219 :C7155735:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=17 time=1027790533
2025-08-31 12:08:59.235220 :C7155736:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 12:08:59.235220 :C7155737:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 12:08:59.235220 :C7155738:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 12:08:59.235220 :C7155739:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 12:08:59.235221 :C715573A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 12:08:59.235710 :C715573B: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-31 12:08:59.235756 :C7155743: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-31 12:19:00.070962 :C71620B7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 12:19:00.372540 :C71620ED:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 12:19:00.368
2025-08-31 12:19:00.372543 :C71620EE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23136 pso_num=22 pso_serial#=240
2025-08-31 12:19:00.372685 :C71620EF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23136 osp_idx=0 osp_ver=557997 osp_pg=0 (spawn #544489)
2025-08-31 12:19:00.372685 :C71620F0: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-31 12:19:00.372686 :C71620F1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=18 time=1028391674
2025-08-31 12:19:00.372686 :C71620F2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 12:19:00.372687 :C71620F3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 12:19:00.372687 :C71620F4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 12:19:00.372688 :C71620F5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 12:19:00.372688 :C71620F6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 12:19:00.373198 :C71620F7: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-31 12:19:00.373240 :C71620FE: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-31 12:29:01.269294 :C716E6D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 12:29:01.649486 :C716E714:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 12:29:01.644
2025-08-31 12:29:01.649488 :C716E715:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21204 pso_num=22 pso_serial#=241
2025-08-31 12:29:01.649638 :C716E716:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21204 osp_idx=0 osp_ver=557998 osp_pg=0 (spawn #544511)
2025-08-31 12:29:01.649638 :C716E717: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-31 12:29:01.649639 :C716E718:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=19 time=1028992955
2025-08-31 12:29:01.649640 :C716E719:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 12:29:01.649640 :C716E71A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 12:29:01.649641 :C716E71B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 12:29:01.649641 :C716E71C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 12:29:01.649641 :C716E71D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 12:29:01.650173 :C716E71E: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-31 12:29:01.650226 :C716E724: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-31 12:39:02.505325 :C717AE0A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 12:39:02.863914 :C717AE47:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 12:39:02.859
2025-08-31 12:39:02.863915 :C717AE48:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22460 pso_num=22 pso_serial#=242
2025-08-31 12:39:02.864054 :C717AE49:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22460 osp_idx=0 osp_ver=557999 osp_pg=0 (spawn #544534)
2025-08-31 12:39:02.864055 :C717AE4A: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-31 12:39:02.864055 :C717AE4B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=20 time=1029594174
2025-08-31 12:39:02.864055 :C717AE4C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 12:39:02.864056 :C717AE4D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 12:39:02.864056 :C717AE4E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 12:39:02.864056 :C717AE4F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 12:39:02.864057 :C717AE50:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 12:39:02.864555 :C717AE51: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-31 12:39:02.864599 :C717AE58: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-31 12:49:03.668068 :C718756C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 12:49:03.938478 :C71875A1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 12:49:03.933
2025-08-31 12:49:03.938480 :C71875A2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21588 pso_num=22 pso_serial#=243
2025-08-31 12:49:03.938619 :C71875A3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21588 osp_idx=0 osp_ver=558000 osp_pg=0 (spawn #544557)
2025-08-31 12:49:03.938620 :C71875A4: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-31 12:49:03.938620 :C71875A5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=21 time=1030195237
2025-08-31 12:49:03.938620 :C71875A6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 12:49:03.938621 :C71875A7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 12:49:03.938621 :C71875A8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 12:49:03.938621 :C71875A9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 12:49:03.938622 :C71875AA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 12:49:03.939116 :C71875AB: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-31 12:49:03.939164 :C71875B1: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-31 12:59:04.781081 :C7193F84:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 12:59:05.082879 :C7193FC0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 12:59:05.082
2025-08-31 12:59:05.082883 :C7193FC1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22740 pso_num=22 pso_serial#=244
2025-08-31 12:59:05.083060 :C7193FC2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22740 osp_idx=0 osp_ver=558001 osp_pg=0 (spawn #544579)
2025-08-31 12:59:05.083061 :C7193FC3: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-31 12:59:05.083061 :C7193FC4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=22 time=1030796393
2025-08-31 12:59:05.083062 :C7193FC5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 12:59:05.083062 :C7193FC6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 12:59:05.083063 :C7193FC7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 12:59:05.083063 :C7193FC8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 12:59:05.083063 :C7193FC9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 12:59:05.083558 :C7193FCA: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-31 12:59:05.083603 :C7193FD2: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-31 13:09:05.978230 :C71A14F7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 13:09:06.279872 :C71A1533:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 13:09:06.275
2025-08-31 13:09:06.279874 :C71A1534:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22748 pso_num=22 pso_serial#=245
2025-08-31 13:09:06.280011 :C71A1535:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22748 osp_idx=0 osp_ver=558002 osp_pg=0 (spawn #544606)
2025-08-31 13:09:06.280012 :C71A1536: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-31 13:09:06.280012 :C71A1537:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=23 time=1031397580
2025-08-31 13:09:06.280013 :C71A1538:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 13:09:06.280013 :C71A1539:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 13:09:06.280013 :C71A153A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 13:09:06.280013 :C71A153B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 13:09:06.280014 :C71A153C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 13:09:06.280522 :C71A153D: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-31 13:09:06.280569 :C71A1543: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-31 13:19:07.079773 :C71ADE1A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 13:19:07.412527 :C71ADE91:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 13:19:07.408
2025-08-31 13:19:07.412530 :C71ADE92:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20984 pso_num=22 pso_serial#=246
2025-08-31 13:19:07.412705 :C71ADE93:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20984 osp_idx=0 osp_ver=558003 osp_pg=0 (spawn #544629)
2025-08-31 13:19:07.412706 :C71ADE94: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-31 13:19:07.412707 :C71ADE95:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=24 time=1031998721
2025-08-31 13:19:07.412707 :C71ADE96:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 13:19:07.412708 :C71ADE97:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 13:19:07.412708 :C71ADE98:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 13:19:07.412708 :C71ADE99:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 13:19:07.412709 :C71ADE9A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 13:19:07.413217 :C71ADE9B: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-31 13:19:07.413260 :C71ADEA3: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-31 13:23:41.781411 :C71B39C9: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-31 13:29:08.617396 :C71BA835: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-31 13:39:09.534050 :C71C6E31:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 13:39:09.991842 :C71C6E67:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 13:39:09.986
2025-08-31 13:39:09.991845 :C71C6E68:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22996 pso_num=22 pso_serial#=247
2025-08-31 13:39:09.991976 :C71C6E69:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22996 osp_idx=0 osp_ver=558004 osp_pg=0 (spawn #544673)
2025-08-31 13:39:09.991977 :C71C6E6A: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-31 13:39:09.991978 :C71C6E6B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=25 time=1033201299
2025-08-31 13:39:09.991978 :C71C6E6C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 13:39:09.991978 :C71C6E6D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 13:39:09.991979 :C71C6E6E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 13:39:09.991979 :C71C6E6F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 13:39:09.991980 :C71C6E70:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 13:39:09.992463 :C71C6E71: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-31 13:39:09.992506 :C71C6E79: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-31 13:49:10.862456 :C71D358F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 13:49:11.367072 :C71D35D5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 13:49:11.362
2025-08-31 13:49:11.367074 :C71D35D6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21036 pso_num=22 pso_serial#=248
2025-08-31 13:49:11.367214 :C71D35D7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21036 osp_idx=0 osp_ver=558005 osp_pg=0 (spawn #544696)
2025-08-31 13:49:11.367215 :C71D35D8: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-31 13:49:11.367216 :C71D35D9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=26 time=1033802674
2025-08-31 13:49:11.367216 :C71D35DA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 13:49:11.367216 :C71D35DB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 13:49:11.367217 :C71D35DC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 13:49:11.367217 :C71D35DD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 13:49:11.367218 :C71D35DE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 13:49:11.367728 :C71D35DF: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-31 13:49:11.367776 :C71D35E8: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-31 13:59:12.182438 :C71DFB4B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-31 13:59:12.624760 :C71DFB94:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-31 13:59:12.620
2025-08-31 13:59:12.624762 :C71DFB95:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17200 pso_num=22 pso_serial#=249
2025-08-31 13:59:12.624904 :C71DFB96:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17200 osp_idx=0 osp_ver=558006 osp_pg=0 (spawn #544718)
2025-08-31 13:59:12.624905 :C71DFB97: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-31 13:59:12.624905 :C71DFB98:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=27 time=1034403924
2025-08-31 13:59:12.624906 :C71DFB99:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-31 13:59:12.624906 :C71DFB9A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-31 13:59:12.624907 :C71DFB9B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-31 13:59:12.624907 :C71DFB9C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-31 13:59:12.624907 :C71DFB9D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-31 13:59:12.625415 :C71DFB9E: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-31 13:59:12.625465 :C71DFBA5: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: 17200, W000)