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


*** 2025-05-13 08:00:58.406
*** SESSION ID:(177.40671) 2025-05-13 08:00:58.406
 

*** 2025-05-13 08:00:58.406
Process diagnostic dump for ORACLE.EXE (W000), OS id=12352,
pid: 22, proc_ser: 119, sid: 177, sess_ser: 40671 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=96 seq_num=97 snap_id=1
    wait times: snap=4.801195 sec, exc=4.801195 sec, total=4.801195 sec
    wait times: max=5.000000 sec, heur=8 min 0 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.000021 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=95 seq_num=96 snap_id=1
    wait times: snap=5.015244 sec, exc=5.015244 sec, total=5.015244 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=94 seq_num=95 snap_id=1
    wait times: snap=5.003644 sec, exc=5.003644 sec, total=5.003644 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=93 seq_num=94 snap_id=1
    wait times: snap=5.012874 sec, exc=5.012874 sec, total=5.012874 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=92 seq_num=93 snap_id=1
    wait times: snap=5.000094 sec, exc=5.000094 sec, total=5.000094 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=91 seq_num=92 snap_id=1
    wait times: snap=5.010147 sec, exc=5.010147 sec, total=5.010147 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=90 seq_num=91 snap_id=1
    wait times: snap=5.009216 sec, exc=5.009216 sec, total=5.009216 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 6: 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.015242 sec, exc=5.015242 sec, total=5.015242 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 7: 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.003245 sec, exc=5.003245 sec, total=5.003245 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
 8: 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.012264 sec, exc=5.012264 sec, total=5.012264 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=86 seq_num=87 snap_id=1
    wait times: snap=5.001835 sec, exc=5.001835 sec, total=5.001835 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
Sampled Session History of session 177 serial 40671
---------------------------------------------------
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,                                            07:58:58 - 08:00:58]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-13 08:00:58.406
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 12352, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-13 05:32:38.761592 :9665122A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 05:32:38.756
2025-05-13 05:32:38.761594 :9665122B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12964 pso_num=22 pso_serial#=106
2025-05-13 05:32:38.761729 :9665122C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12964 osp_idx=0 osp_ver=186511 osp_pg=0 (spawn #176868)
2025-05-13 05:32:38.761730 :9665122D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 05:32:38.761730 :9665122E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=73 time=90168625
2025-05-13 05:32:38.761730 :9665122F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 05:32:38.761731 :96651230:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 05:32:38.761731 :96651231:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 05:32:38.761731 :96651232:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 05:32:38.761732 :96651233:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 05:32:38.762263 :96651234: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-05-13 05:32:38.762308 :9665123C: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-05-13 05:42:39.715957 :9665D35B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 05:42:39.986270 :9665D3AD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 05:42:39.981
2025-05-13 05:42:39.986272 :9665D3AE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11316 pso_num=22 pso_serial#=107
2025-05-13 05:42:39.986412 :9665D3AF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11316 osp_idx=0 osp_ver=186512 osp_pg=0 (spawn #176891)
2025-05-13 05:42:39.986413 :9665D3B0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 05:42:39.986414 :9665D3B1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=74 time=90769860
2025-05-13 05:42:39.986414 :9665D3B2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 05:42:39.986414 :9665D3B3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 05:42:39.986415 :9665D3B4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 05:42:39.986415 :9665D3B5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 05:42:39.986415 :9665D3B6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 05:42:39.986911 :9665D3B7: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-05-13 05:42:39.986956 :9665D3BF: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-05-13 05:52:40.918439 :96669518:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 05:52:41.391764 :9666955E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 05:52:41.387
2025-05-13 05:52:41.391766 :9666955F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11988 pso_num=22 pso_serial#=108
2025-05-13 05:52:41.391897 :96669560:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11988 osp_idx=0 osp_ver=186513 osp_pg=0 (spawn #176914)
2025-05-13 05:52:41.391898 :96669561:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 05:52:41.391898 :96669562:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=75 time=91371266
2025-05-13 05:52:41.391899 :96669563:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 05:52:41.391899 :96669564:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 05:52:41.391900 :96669565:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 05:52:41.391900 :96669566:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 05:52:41.391901 :96669567:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 05:52:41.392428 :96669568: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-05-13 05:52:41.392472 :96669571: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-05-13 06:02:42.293687 :96675889:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 06:02:42.689024 :966758DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 06:02:42.684
2025-05-13 06:02:42.689026 :966758DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9900 pso_num=22 pso_serial#=109
2025-05-13 06:02:42.689166 :966758DC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9900 osp_idx=0 osp_ver=186514 osp_pg=0 (spawn #176938)
2025-05-13 06:02:42.689167 :966758DD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 06:02:42.689167 :966758DE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=76 time=91972563
2025-05-13 06:02:42.689168 :966758DF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 06:02:42.689168 :966758E0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 06:02:42.689168 :966758E1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 06:02:42.689169 :966758E2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 06:02:42.689169 :966758E3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 06:02:42.689650 :966758E4: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-05-13 06:02:42.689699 :966758EA: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-05-13 06:12:43.616633 :96682387:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 06:12:44.037617 :966823CB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 06:12:44.033
2025-05-13 06:12:44.037618 :966823CC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13104 pso_num=22 pso_serial#=110
2025-05-13 06:12:44.037753 :966823CD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13104 osp_idx=0 osp_ver=186515 osp_pg=0 (spawn #176962)
2025-05-13 06:12:44.037753 :966823CE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 06:12:44.037754 :966823CF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=77 time=92573907
2025-05-13 06:12:44.037754 :966823D0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 06:12:44.037755 :966823D1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 06:12:44.037755 :966823D2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 06:12:44.037755 :966823D3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 06:12:44.037756 :966823D4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 06:12:44.038227 :966823D5: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-05-13 06:12:44.038269 :966823DC: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-05-13 06:22:44.954012 :9668E433:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 06:22:45.505502 :9668E47B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 06:22:45.501
2025-05-13 06:22:45.505504 :9668E47C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9904 pso_num=22 pso_serial#=111
2025-05-13 06:22:45.505636 :9668E47D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9904 osp_idx=0 osp_ver=186516 osp_pg=0 (spawn #176985)
2025-05-13 06:22:45.505637 :9668E47E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 06:22:45.505637 :9668E47F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=78 time=93175375
2025-05-13 06:22:45.505637 :9668E480:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 06:22:45.505638 :9668E481:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 06:22:45.505638 :9668E482:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 06:22:45.505638 :9668E483:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 06:22:45.505638 :9668E484:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 06:22:45.506126 :9668E485: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-05-13 06:22:45.506172 :9668E48D: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-05-13 06:32:46.413467 :9669A69D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 06:32:46.964961 :9669A6EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 06:32:46.960
2025-05-13 06:32:46.964963 :9669A6EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9228 pso_num=22 pso_serial#=112
2025-05-13 06:32:46.965095 :9669A6F0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9228 osp_idx=0 osp_ver=186517 osp_pg=0 (spawn #177008)
2025-05-13 06:32:46.965096 :9669A6F1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 06:32:46.965096 :9669A6F2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=79 time=93776829
2025-05-13 06:32:46.965097 :9669A6F3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 06:32:46.965097 :9669A6F4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 06:32:46.965097 :9669A6F5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 06:32:46.965098 :9669A6F6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 06:32:46.965098 :9669A6F7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 06:32:46.965584 :9669A6F8: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-05-13 06:32:46.965635 :9669A702: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-05-13 06:42:47.881512 :966A6A17:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 06:42:48.386095 :966A6A5C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 06:42:48.381
2025-05-13 06:42:48.386097 :966A6A5D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11632 pso_num=22 pso_serial#=113
2025-05-13 06:42:48.386232 :966A6A5E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11632 osp_idx=0 osp_ver=186518 osp_pg=0 (spawn #177031)
2025-05-13 06:42:48.386232 :966A6A5F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 06:42:48.386233 :966A6A60:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=80 time=94378250
2025-05-13 06:42:48.386233 :966A6A61:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 06:42:48.386233 :966A6A62:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 06:42:48.386234 :966A6A63:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 06:42:48.386234 :966A6A64:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 06:42:48.386234 :966A6A65:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 06:42:48.386728 :966A6A66: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-05-13 06:42:48.386781 :966A6A6C: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-05-13 06:52:49.183297 :966B2C5E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 06:52:49.860011 :966B2CB0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 06:52:49.855
2025-05-13 06:52:49.860013 :966B2CB1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11616 pso_num=22 pso_serial#=114
2025-05-13 06:52:49.860150 :966B2CB2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11616 osp_idx=0 osp_ver=186519 osp_pg=0 (spawn #177054)
2025-05-13 06:52:49.860150 :966B2CB3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 06:52:49.860151 :966B2CB4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=81 time=94979735
2025-05-13 06:52:49.860151 :966B2CB5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 06:52:49.860152 :966B2CB6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 06:52:49.860152 :966B2CB7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 06:52:49.860152 :966B2CB8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 06:52:49.860152 :966B2CB9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 06:52:49.860656 :966B2CBA: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-05-13 06:52:49.860703 :966B2CC3: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-05-13 07:02:50.738195 :966BF330:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 07:02:51.227160 :966BF374:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 07:02:51.223
2025-05-13 07:02:51.227163 :966BF375:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11436 pso_num=22 pso_serial#=115
2025-05-13 07:02:51.227297 :966BF376:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11436 osp_idx=0 osp_ver=186520 osp_pg=0 (spawn #177078)
2025-05-13 07:02:51.227298 :966BF377:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 07:02:51.227298 :966BF378:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=82 time=95581094
2025-05-13 07:02:51.227298 :966BF379:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 07:02:51.227299 :966BF37A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 07:02:51.227299 :966BF37B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 07:02:51.227299 :966BF37C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 07:02:51.227300 :966BF37D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 07:02:51.227801 :966BF37E: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-05-13 07:02:51.227855 :966BF388: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-05-13 07:10:57.432906 :966C96F0: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-05-13 07:12:07.529291 :966CAE44:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=114 seq_num=115 snap_id=1
2025-05-13 07:12:07.529299 :966CAE45:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=114 seq_num=115 snap_id=1
2025-05-13 07:12:07.529300 :966CAE46:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=9, exc=9, tot=9
2025-05-13 07:12:07.529310 :966CAE48: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-05-13 07:12:52.667579 :966CBC5C: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-05-13 07:22:53.568051 :966D7DD1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 07:22:53.994577 :966D7E17:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 07:22:53.989
2025-05-13 07:22:53.994579 :966D7E18:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9648 pso_num=22 pso_serial#=116
2025-05-13 07:22:53.994763 :966D7E19:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9648 osp_idx=0 osp_ver=186521 osp_pg=0 (spawn #177124)
2025-05-13 07:22:53.994764 :966D7E1A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 07:22:53.994765 :966D7E1B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=83 time=96783860
2025-05-13 07:22:53.994765 :966D7E1C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 07:22:53.994766 :966D7E1D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 07:22:53.994766 :966D7E1E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 07:22:53.994766 :966D7E1F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 07:22:53.994767 :966D7E20:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 07:22:53.995273 :966D7E21: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-05-13 07:22:53.995317 :966D7E29: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-05-13 07:32:54.836485 :966E3FBA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 07:32:55.294386 :966E400A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 07:32:55.290
2025-05-13 07:32:55.294387 :966E400B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10872 pso_num=22 pso_serial#=117
2025-05-13 07:32:55.294520 :966E400C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10872 osp_idx=0 osp_ver=186522 osp_pg=0 (spawn #177146)
2025-05-13 07:32:55.294520 :966E400D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 07:32:55.294521 :966E400E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=84 time=97385172
2025-05-13 07:32:55.294521 :966E400F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 07:32:55.294521 :966E4010:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 07:32:55.294522 :966E4011:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 07:32:55.294522 :966E4012:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 07:32:55.294522 :966E4013:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 07:32:55.295056 :966E4014: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-05-13 07:32:55.295102 :966E401C: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-05-13 07:42:56.222934 :966F0248:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 07:42:56.633958 :966F02A2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 07:42:56.634
2025-05-13 07:42:56.633959 :966F02A3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12276 pso_num=22 pso_serial#=118
2025-05-13 07:42:56.634099 :966F02A4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12276 osp_idx=0 osp_ver=186523 osp_pg=0 (spawn #177169)
2025-05-13 07:42:56.634099 :966F02A5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 07:42:56.634100 :966F02A6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=85 time=97986500
2025-05-13 07:42:56.634100 :966F02A7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 07:42:56.634101 :966F02A8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 07:42:56.634101 :966F02A9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 07:42:56.634101 :966F02AA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 07:42:56.634101 :966F02AB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 07:42:56.634582 :966F02AC: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-05-13 07:42:56.634624 :966F02B3: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-05-13 07:52:57.552926 :966FC6BD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-05-13 07:52:57.932578 :966FC736:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-13 07:52:57.928
2025-05-13 07:52:57.932580 :966FC737:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12352 pso_num=22 pso_serial#=119
2025-05-13 07:52:57.932713 :966FC738:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12352 osp_idx=0 osp_ver=186524 osp_pg=0 (spawn #177192)
2025-05-13 07:52:57.932714 :966FC739:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-13 07:52:57.932714 :966FC73A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=86 time=98587797
2025-05-13 07:52:57.932714 :966FC73B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-05-13 07:52:57.932715 :966FC73C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-05-13 07:52:57.932715 :966FC73D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-05-13 07:52:57.932715 :966FC73E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-13 07:52:57.932715 :966FC73F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-13 07:52:57.933199 :966FC740: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-05-13 07:52:57.933244 :966FC74A: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: 12352, W000)