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


*** 2025-09-07 14:06:41.669
*** SESSION ID:(209.41067) 2025-09-07 14:06:41.669
 

*** 2025-09-07 14:06:41.669
Process diagnostic dump for ORACLE.EXE (W000), OS id=22888,
pid: 26, proc_ser: 167, sid: 209, sess_ser: 41067 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=91 seq_num=92 snap_id=1
    wait times: snap=3.951706 sec, exc=3.951706 sec, total=3.951706 sec
    wait times: max=5.000000 sec, heur=7 min 34 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.000041 sec since current wait
 0: 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.004559 sec, exc=5.004559 sec, total=5.004559 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000082 sec of elapsed time
 1: 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.014760 sec, exc=5.014760 sec, total=5.014760 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 2: 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.004627 sec, exc=5.004627 sec, total=5.004627 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 3: 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.014732 sec, exc=5.014732 sec, total=5.014732 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=86 seq_num=87 snap_id=1
    wait times: snap=5.010136 sec, exc=5.010136 sec, total=5.010136 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=85 seq_num=86 snap_id=1
    wait times: snap=5.008318 sec, exc=5.008318 sec, total=5.008318 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 6: 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.004351 sec, exc=5.004351 sec, total=5.004351 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 7: 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.008407 sec, exc=5.008407 sec, total=5.008407 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 8: 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.004634 sec, exc=5.004634 sec, total=5.004634 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=81 seq_num=82 snap_id=1
    wait times: snap=5.009208 sec, exc=5.009208 sec, total=5.009208 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
Sampled Session History of session 209 serial 41067
---------------------------------------------------
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:41 - 14:06:41]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-07 14:06:41.669
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 26 (osid: 22888, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-07 11:28:48.804848 :CA2E6545:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 11:28:49.137735 :CA2E658C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 11:28:49.133
2025-09-07 11:28:49.137737 :CA2E658D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21588 pso_num=26 pso_serial#=154
2025-09-07 11:28:49.137873 :CA2E658E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21588 osp_idx=24 osp_ver=804944 osp_pg=0 (spawn #567711)
2025-09-07 11:28:49.137874 :CA2E658F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 11:28:49.137874 :CA2E6590:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=53 time=1630180440
2025-09-07 11:28:49.137874 :CA2E6591:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 11:28:49.137875 :CA2E6592:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 11:28:49.137875 :CA2E6593:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 11:28:49.137875 :CA2E6594:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 11:28:49.137876 :CA2E6595:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 11:28:49.138376 :CA2E6596:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 11:28:49.138420 :CA2E659D:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 11:38:49.902224 :CA2F2CE2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 11:38:50.234950 :CA2F2D1E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 11:38:50.230
2025-09-07 11:38:50.234952 :CA2F2D1F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=4644 pso_num=26 pso_serial#=155
2025-09-07 11:38:50.235084 :CA2F2D20:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=4644 osp_idx=24 osp_ver=804945 osp_pg=0 (spawn #567734)
2025-09-07 11:38:50.235085 :CA2F2D21:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 11:38:50.235086 :CA2F2D22:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=54 time=1630781533
2025-09-07 11:38:50.235086 :CA2F2D23:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 11:38:50.235087 :CA2F2D24:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 11:38:50.235087 :CA2F2D25:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 11:38:50.235087 :CA2F2D26:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 11:38:50.235088 :CA2F2D27:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 11:38:50.235574 :CA2F2D28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 11:38:50.235616 :CA2F2D2F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 11:39:15.778915 :CA2F3502:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 11:48:51.338243 :CA2FF3B8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 11:58:52.189908 :CA30BB5E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 11:58:52.475704 :CA30BB90:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 11:58:52.471
2025-09-07 11:58:52.475706 :CA30BB91:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21648 pso_num=26 pso_serial#=156
2025-09-07 11:58:52.475848 :CA30BB92:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21648 osp_idx=24 osp_ver=804946 osp_pg=0 (spawn #567778)
2025-09-07 11:58:52.475849 :CA30BB93:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 11:58:52.475849 :CA30BB94:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=55 time=1631983783
2025-09-07 11:58:52.475849 :CA30BB95:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 11:58:52.475850 :CA30BB96:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 11:58:52.475850 :CA30BB97:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 11:58:52.475850 :CA30BB98:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 11:58:52.475851 :CA30BB99:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 11:58:52.476366 :CA30BB9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 11:58:52.476410 :CA30BBA1:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 12:08:53.205834 :CA3189FE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 12:08:53.726243 :CA318A3C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 12:08:53.721
2025-09-07 12:08:53.726245 :CA318A3D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21868 pso_num=26 pso_serial#=157
2025-09-07 12:08:53.726380 :CA318A3E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21868 osp_idx=24 osp_ver=804947 osp_pg=0 (spawn #567804)
2025-09-07 12:08:53.726380 :CA318A3F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 12:08:53.726381 :CA318A40:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=56 time=1632585033
2025-09-07 12:08:53.726381 :CA318A41:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 12:08:53.726382 :CA318A42:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 12:08:53.726382 :CA318A43:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 12:08:53.726382 :CA318A44:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 12:08:53.726382 :CA318A45:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 12:08:53.726897 :CA318A46:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 12:08:53.726942 :CA318A4E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 12:18:54.510919 :CA32520F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 12:18:54.900818 :CA325248:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 12:18:54.896
2025-09-07 12:18:54.900820 :CA325249:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24168 pso_num=26 pso_serial#=158
2025-09-07 12:18:54.900959 :CA32524A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24168 osp_idx=24 osp_ver=804948 osp_pg=0 (spawn #567826)
2025-09-07 12:18:54.900960 :CA32524B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 12:18:54.900961 :CA32524C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=57 time=1633186205
2025-09-07 12:18:54.900961 :CA32524D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 12:18:54.900961 :CA32524E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 12:18:54.900962 :CA32524F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 12:18:54.900962 :CA325250:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 12:18:54.900962 :CA325251:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 12:18:54.901461 :CA325252:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 12:18:54.901510 :CA325259:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 12:28:55.766727 :CA33175F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 12:28:56.099748 :CA3317A5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 12:28:56.095
2025-09-07 12:28:56.099751 :CA3317A6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=23956 pso_num=26 pso_serial#=159
2025-09-07 12:28:56.099897 :CA3317A7:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=23956 osp_idx=24 osp_ver=804949 osp_pg=0 (spawn #567849)
2025-09-07 12:28:56.099897 :CA3317A8:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 12:28:56.099898 :CA3317A9:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=58 time=1633787408
2025-09-07 12:28:56.099898 :CA3317AA:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 12:28:56.099898 :CA3317AB:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 12:28:56.099899 :CA3317AC:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 12:28:56.099899 :CA3317AD:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 12:28:56.099899 :CA3317AE:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 12:28:56.100405 :CA3317AF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 12:28:56.100453 :CA3317B8:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 12:38:56.934422 :CA33DDE2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 12:38:57.220433 :CA33DE1C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 12:38:57.215
2025-09-07 12:38:57.220435 :CA33DE1D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22016 pso_num=26 pso_serial#=160
2025-09-07 12:38:57.220569 :CA33DE1E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22016 osp_idx=24 osp_ver=804950 osp_pg=0 (spawn #567872)
2025-09-07 12:38:57.220569 :CA33DE1F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 12:38:57.220570 :CA33DE20:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=59 time=1634388518
2025-09-07 12:38:57.220570 :CA33DE21:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 12:38:57.220571 :CA33DE22:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 12:38:57.220571 :CA33DE23:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 12:38:57.220571 :CA33DE24:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 12:38:57.220572 :CA33DE25:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 12:38:57.221062 :CA33DE26:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 12:38:57.221118 :CA33DE2C:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 12:48:58.188071 :CA34A2C7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 12:48:58.474257 :CA34A310:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 12:48:58.469
2025-09-07 12:48:58.474259 :CA34A311:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=21696 pso_num=26 pso_serial#=161
2025-09-07 12:48:58.474401 :CA34A312:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=21696 osp_idx=24 osp_ver=804951 osp_pg=0 (spawn #567894)
2025-09-07 12:48:58.474402 :CA34A313:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 12:48:58.474402 :CA34A314:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=60 time=1634989783
2025-09-07 12:48:58.474403 :CA34A315:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 12:48:58.474404 :CA34A316:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 12:48:58.474404 :CA34A317:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 12:48:58.474404 :CA34A318:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 12:48:58.474405 :CA34A319:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 12:48:58.474929 :CA34A31A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 12:48:58.474976 :CA34A324:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 12:58:59.343234 :CA3568BD:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 12:58:59.691682 :CA3568F8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 12:58:59.687
2025-09-07 12:58:59.691684 :CA3568F9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24284 pso_num=26 pso_serial#=162
2025-09-07 12:58:59.691825 :CA3568FA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24284 osp_idx=24 osp_ver=804952 osp_pg=0 (spawn #567917)
2025-09-07 12:58:59.691825 :CA3568FB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 12:58:59.691826 :CA3568FC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=61 time=1635591002
2025-09-07 12:58:59.691827 :CA3568FD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 12:58:59.691828 :CA3568FE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 12:58:59.691829 :CA3568FF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 12:58:59.691829 :CA356900:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 12:58:59.691829 :CA356901:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 12:58:59.692330 :CA356902:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 12:58:59.692375 :CA35690B:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:09:00.531901 :CA363B3B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 13:09:00.818208 :CA363B82:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 13:09:00.812
2025-09-07 13:09:00.818211 :CA363B83:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22912 pso_num=26 pso_serial#=163
2025-09-07 13:09:00.818352 :CA363B84:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22912 osp_idx=24 osp_ver=804953 osp_pg=0 (spawn #567944)
2025-09-07 13:09:00.818353 :CA363B85:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 13:09:00.818353 :CA363B86:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=62 time=1636192127
2025-09-07 13:09:00.818353 :CA363B87:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 13:09:00.818354 :CA363B88:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 13:09:00.818354 :CA363B89:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 13:09:00.818354 :CA363B8A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 13:09:00.818355 :CA363B8B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 13:09:00.818874 :CA363B8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 13:09:00.818919 :CA363B93:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:19:01.554338 :CA36FE6B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 13:19:02.074869 :CA36FEEE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 13:19:02.070
2025-09-07 13:19:02.074871 :CA36FEEF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24244 pso_num=26 pso_serial#=164
2025-09-07 13:19:02.075008 :CA36FEF0:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24244 osp_idx=24 osp_ver=804954 osp_pg=0 (spawn #567966)
2025-09-07 13:19:02.075009 :CA36FEF1:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 13:19:02.075009 :CA36FEF2:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=63 time=1636793377
2025-09-07 13:19:02.075010 :CA36FEF3:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 13:19:02.075010 :CA36FEF4:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 13:19:02.075010 :CA36FEF5:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 13:19:02.075011 :CA36FEF6:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 13:19:02.075011 :CA36FEF7:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 13:19:02.075506 :CA36FEF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 13:19:02.075552 :CA36FF00:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:29:02.859943 :CA37C534:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 13:29:03.333570 :CA37C59D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 13:29:03.328
2025-09-07 13:29:03.333572 :CA37C59E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=24568 pso_num=26 pso_serial#=165
2025-09-07 13:29:03.333709 :CA37C59F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=24568 osp_idx=24 osp_ver=804955 osp_pg=0 (spawn #567989)
2025-09-07 13:29:03.333710 :CA37C5A0:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 13:29:03.333711 :CA37C5A1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=64 time=1637394643
2025-09-07 13:29:03.333711 :CA37C5A2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 13:29:03.333711 :CA37C5A3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 13:29:03.333712 :CA37C5A4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 13:29:03.333712 :CA37C5A5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 13:29:03.333712 :CA37C5A6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 13:29:03.334223 :CA37C5A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 13:29:03.334267 :CA37C5AF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:39:04.175697 :CA388C2E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 13:39:04.539882 :CA388C7D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 13:39:04.535
2025-09-07 13:39:04.539884 :CA388C7E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=6376 pso_num=26 pso_serial#=166
2025-09-07 13:39:04.540028 :CA388C7F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=6376 osp_idx=24 osp_ver=804956 osp_pg=0 (spawn #568012)
2025-09-07 13:39:04.540028 :CA388C80:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 13:39:04.540029 :CA388C81:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=65 time=1637995846
2025-09-07 13:39:04.540029 :CA388C82:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 13:39:04.540029 :CA388C83:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 13:39:04.540030 :CA388C84:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 13:39:04.540030 :CA388C85:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 13:39:04.540030 :CA388C86:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 13:39:04.540536 :CA388C87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 13:39:04.540580 :CA388C90:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:39:16.601363 :CA38903E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:49:05.767091 :CA3951D6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-09-07 13:59:06.536384 :CA3A1706:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted
2025-09-07 13:59:07.041134 :CA3A175C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 09-07 13:59:07.036
2025-09-07 13:59:07.041136 :CA3A175D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=22888 pso_num=26 pso_serial#=167
2025-09-07 13:59:07.041274 :CA3A175E:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=22888 osp_idx=24 osp_ver=804957 osp_pg=0 (spawn #568056)
2025-09-07 13:59:07.041274 :CA3A175F:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-07 13:59:07.041275 :CA3A1760:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=66 time=1639198346
2025-09-07 13:59:07.041275 :CA3A1761:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs
2025-09-07 13:59:07.041275 :CA3A1762:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs
2025-09-07 13:59:07.041276 :CA3A1763:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs
2025-09-07 13:59:07.041276 :CA3A1764:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-07 13:59:07.041276 :CA3A1765:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-07 13:59:07.041765 :CA3A1766:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-07 13:59:07.041812 :CA3A176E:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 22888, W000)