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


*** 2025-08-19 08:07:59.397
*** SESSION ID:(177.22781) 2025-08-19 08:07:59.397
 

*** 2025-08-19 08:07:59.397
Process diagnostic dump for ORACLE.EXE (W000), OS id=21216,
pid: 22, proc_ser: 255, sid: 177, sess_ser: 22781 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=81 seq_num=82 snap_id=1
    wait times: snap=3.476404 sec, exc=3.476404 sec, total=3.476404 sec
    wait times: max=5.000000 sec, heur=6 min 44 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.000024 sec since current wait
 0: 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.004355 sec, exc=5.004355 sec, total=5.004355 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=79 seq_num=80 snap_id=1
    wait times: snap=5.008772 sec, exc=5.008772 sec, total=5.008772 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=78 seq_num=79 snap_id=1
    wait times: snap=5.014735 sec, exc=5.014735 sec, total=5.014735 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=77 seq_num=78 snap_id=1
    wait times: snap=5.014730 sec, exc=5.014730 sec, total=5.014730 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=76 seq_num=77 snap_id=1
    wait times: snap=5.004639 sec, exc=5.004639 sec, total=5.004639 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000019 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=75 seq_num=76 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.000024 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=74 seq_num=75 snap_id=1
    wait times: snap=5.008372 sec, exc=5.008372 sec, total=5.008372 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=73 seq_num=74 snap_id=1
    wait times: snap=5.011288 sec, exc=5.011288 sec, total=5.011288 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=72 seq_num=73 snap_id=1
    wait times: snap=5.002438 sec, exc=5.002438 sec, total=5.002438 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=71 seq_num=72 snap_id=1
    wait times: snap=5.004626 sec, exc=5.004626 sec, total=5.004626 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000027 sec of elapsed time
Sampled Session History of session 177 serial 22781
---------------------------------------------------
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,                                            08:05:59 - 08:07:59]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-19 08:07:59.397
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 21216, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-19 05:40:56.222309 :C1B0A8C9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 05:40:56.586601 :C1B0A920:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 05:40:56.581
2025-08-19 05:40:56.586603 :C1B0A921:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21056 pso_num=22 pso_serial#=242
2025-08-19 05:40:56.586753 :C1B0A922:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21056 osp_idx=0 osp_ver=534029 osp_pg=0 (spawn #503557)
2025-08-19 05:40:56.586754 :C1B0A923: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-19 05:40:56.586755 :C1B0A924:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=125 time=4262725892
2025-08-19 05:40:56.586755 :C1B0A925:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 05:40:56.586755 :C1B0A926:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 05:40:56.586756 :C1B0A927:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 05:40:56.586757 :C1B0A928:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 05:40:56.586757 :C1B0A929:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 05:40:56.587316 :C1B0A92A: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-19 05:40:56.587369 :C1B0A932: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-19 05:50:57.451387 :C1B16BD3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 05:50:57.831336 :C1B16C28:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 05:50:57.826
2025-08-19 05:50:57.831338 :C1B16C29:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14268 pso_num=22 pso_serial#=243
2025-08-19 05:50:57.831477 :C1B16C2A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14268 osp_idx=0 osp_ver=534030 osp_pg=0 (spawn #503578)
2025-08-19 05:50:57.831478 :C1B16C2B: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-19 05:50:57.831479 :C1B16C2C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=126 time=4263327142
2025-08-19 05:50:57.831479 :C1B16C2D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 05:50:57.831479 :C1B16C2E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 05:50:57.831480 :C1B16C2F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 05:50:57.831480 :C1B16C30:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 05:50:57.831480 :C1B16C31:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 05:50:57.831975 :C1B16C32: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-19 05:50:57.832022 :C1B16C3B: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-19 06:00:58.717419 :C1B23109:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 06:00:59.206811 :C1B23173:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 06:00:59.202
2025-08-19 06:00:59.206812 :C1B23174:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14924 pso_num=22 pso_serial#=244
2025-08-19 06:00:59.206964 :C1B23175:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14924 osp_idx=0 osp_ver=534031 osp_pg=0 (spawn #503602)
2025-08-19 06:00:59.206965 :C1B23176: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-19 06:00:59.206966 :C1B23177:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=127 time=4263928517
2025-08-19 06:00:59.206967 :C1B23178:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 06:00:59.206967 :C1B23179:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 06:00:59.206968 :C1B2317A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 06:00:59.206968 :C1B2317B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 06:00:59.206968 :C1B2317C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 06:00:59.207487 :C1B2317D: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-19 06:00:59.207536 :C1B23185: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-19 06:01:14.218897 :C1B235FD:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1
2025-08-19 06:01:14.218916 :C1B2360A:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1
2025-08-19 06:01:14.218918 :C1B2360B:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=18, exc=18, tot=18
2025-08-19 06:11:00.032889 :C1B2FCFF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 06:11:00.493031 :C1B2FD53:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 06:11:00.488
2025-08-19 06:11:00.493033 :C1B2FD54:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22512 pso_num=22 pso_serial#=245
2025-08-19 06:11:00.493194 :C1B2FD55:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22512 osp_idx=0 osp_ver=534032 osp_pg=0 (spawn #503626)
2025-08-19 06:11:00.493196 :C1B2FD56: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-19 06:11:00.493197 :C1B2FD57:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=128 time=4264529798
2025-08-19 06:11:00.493197 :C1B2FD58:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 06:11:00.493198 :C1B2FD59:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 06:11:00.493198 :C1B2FD5A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 06:11:00.493198 :C1B2FD5B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 06:11:00.493199 :C1B2FD5C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 06:11:00.493728 :C1B2FD5D: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-19 06:11:00.493778 :C1B2FD64: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-19 06:21:01.388195 :C1B3C0E7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 06:21:01.877573 :C1B3C148:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 06:21:01.872
2025-08-19 06:21:01.877575 :C1B3C149:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22432 pso_num=22 pso_serial#=246
2025-08-19 06:21:01.877717 :C1B3C14A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22432 osp_idx=0 osp_ver=534033 osp_pg=0 (spawn #503649)
2025-08-19 06:21:01.877718 :C1B3C14B: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-19 06:21:01.877719 :C1B3C14C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=129 time=4265131189
2025-08-19 06:21:01.877720 :C1B3C14D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 06:21:01.877720 :C1B3C14E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 06:21:01.877721 :C1B3C14F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 06:21:01.877721 :C1B3C150:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 06:21:01.877722 :C1B3C151:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 06:21:01.878234 :C1B3C152: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-19 06:21:01.878281 :C1B3C159: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-19 06:31:02.777489 :C1B48480:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 06:31:03.261235 :C1B484D4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 06:31:03.256
2025-08-19 06:31:03.261237 :C1B484D5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22456 pso_num=22 pso_serial#=247
2025-08-19 06:31:03.261376 :C1B484D6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22456 osp_idx=0 osp_ver=534034 osp_pg=0 (spawn #503671)
2025-08-19 06:31:03.261377 :C1B484D7: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-19 06:31:03.261377 :C1B484D8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=130 time=4265732564
2025-08-19 06:31:03.261377 :C1B484D9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 06:31:03.261378 :C1B484DA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 06:31:03.261379 :C1B484DB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 06:31:03.261379 :C1B484DC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 06:31:03.261379 :C1B484DD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 06:31:03.261883 :C1B484DE: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-19 06:31:03.261932 :C1B484E6: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-19 06:41:04.217203 :C1B548E7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 06:41:04.690744 :C1B54953:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 06:41:04.686
2025-08-19 06:41:04.690746 :C1B54954:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4492 pso_num=22 pso_serial#=248
2025-08-19 06:41:04.690884 :C1B54955:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4492 osp_idx=0 osp_ver=534035 osp_pg=0 (spawn #503694)
2025-08-19 06:41:04.690886 :C1B54956: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-19 06:41:04.690886 :C1B54957:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=131 time=4266334001
2025-08-19 06:41:04.690887 :C1B54958:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 06:41:04.690887 :C1B54959:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 06:41:04.690887 :C1B5495A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 06:41:04.690887 :C1B5495B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 06:41:04.690888 :C1B5495C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 06:41:04.691376 :C1B5495D: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-19 06:41:04.691420 :C1B54965: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-19 06:51:05.586486 :C1B60C42:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 06:51:06.054621 :C1B60CBA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 06:51:06.049
2025-08-19 06:51:06.054624 :C1B60CBB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6512 pso_num=22 pso_serial#=249
2025-08-19 06:51:06.054770 :C1B60CBC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6512 osp_idx=0 osp_ver=534036 osp_pg=0 (spawn #503717)
2025-08-19 06:51:06.054771 :C1B60CBD: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-19 06:51:06.054772 :C1B60CBE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=132 time=4266935361
2025-08-19 06:51:06.054772 :C1B60CBF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 06:51:06.054772 :C1B60CC0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 06:51:06.054773 :C1B60CC1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 06:51:06.054773 :C1B60CC2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 06:51:06.054773 :C1B60CC3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 06:51:06.055284 :C1B60CC4: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-19 06:51:06.055331 :C1B60CCB: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-19 07:01:06.944839 :C1B6D201:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 07:01:07.481491 :C1B6D249:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 07:01:07.475
2025-08-19 07:01:07.481493 :C1B6D24A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21756 pso_num=22 pso_serial#=250
2025-08-19 07:01:07.481635 :C1B6D24B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21756 osp_idx=0 osp_ver=534037 osp_pg=0 (spawn #503741)
2025-08-19 07:01:07.481636 :C1B6D24C: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-19 07:01:07.481636 :C1B6D24D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=133 time=4267536783
2025-08-19 07:01:07.481637 :C1B6D24E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 07:01:07.481638 :C1B6D24F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 07:01:07.481638 :C1B6D250:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 07:01:07.481638 :C1B6D251:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 07:01:07.481639 :C1B6D252:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 07:01:07.482196 :C1B6D253: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-19 07:01:07.482265 :C1B6D25D: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-19 07:07:33.493125 :C1B7585A: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-19 07:11:08.768155 :C1B79DCC: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-19 07:21:09.645415 :C1B863DD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 07:21:10.175822 :C1B8643F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 07:21:10.170
2025-08-19 07:21:10.175823 :C1B86440:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13028 pso_num=22 pso_serial#=251
2025-08-19 07:21:10.175961 :C1B86441:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13028 osp_idx=0 osp_ver=534038 osp_pg=0 (spawn #503787)
2025-08-19 07:21:10.175962 :C1B86442: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-19 07:21:10.175963 :C1B86443:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=134 time=4268739486
2025-08-19 07:21:10.175963 :C1B86444:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 07:21:10.175964 :C1B86445:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 07:21:10.175964 :C1B86446:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 07:21:10.175964 :C1B86447:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 07:21:10.175965 :C1B86448:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 07:21:10.176455 :C1B86449: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-19 07:21:10.176502 :C1B86451: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-19 07:31:10.970449 :C1B92656:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 07:31:11.506394 :C1B926F5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 07:31:11.501
2025-08-19 07:31:11.506396 :C1B926F6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17200 pso_num=22 pso_serial#=252
2025-08-19 07:31:11.506537 :C1B926F7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17200 osp_idx=0 osp_ver=534039 osp_pg=0 (spawn #503809)
2025-08-19 07:31:11.506538 :C1B926F8: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-19 07:31:11.506538 :C1B926F9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=135 time=4269340814
2025-08-19 07:31:11.506539 :C1B926FA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 07:31:11.506540 :C1B926FB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 07:31:11.506540 :C1B926FC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 07:31:11.506540 :C1B926FD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 07:31:11.506541 :C1B926FE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 07:31:11.507044 :C1B926FF: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-19 07:31:11.507089 :C1B92707: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-19 07:41:12.445588 :C1B9EAFD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 07:41:12.778426 :C1B9EB4E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 07:41:12.773
2025-08-19 07:41:12.778428 :C1B9EB4F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8372 pso_num=22 pso_serial#=253
2025-08-19 07:41:12.778559 :C1B9EB50:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8372 osp_idx=0 osp_ver=534040 osp_pg=0 (spawn #503832)
2025-08-19 07:41:12.778559 :C1B9EB51: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-19 07:41:12.778560 :C1B9EB52:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=4269942079
2025-08-19 07:41:12.778560 :C1B9EB53:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 07:41:12.778561 :C1B9EB54:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 07:41:12.778561 :C1B9EB55:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 07:41:12.778561 :C1B9EB56:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 07:41:12.778562 :C1B9EB57:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 07:41:12.779113 :C1B9EB58: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-19 07:41:12.779172 :C1B9EB5F: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-19 07:51:13.762564 :C1BAAE73:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 07:51:14.111039 :C1BAAF15:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 07:51:14.106
2025-08-19 07:51:14.111042 :C1BAAF16:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22244 pso_num=22 pso_serial#=254
2025-08-19 07:51:14.111178 :C1BAAF17:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22244 osp_idx=0 osp_ver=534041 osp_pg=0 (spawn #503855)
2025-08-19 07:51:14.111179 :C1BAAF18: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-19 07:51:14.111179 :C1BAAF19:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=137 time=4270543423
2025-08-19 07:51:14.111180 :C1BAAF1A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 07:51:14.111180 :C1BAAF1B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 07:51:14.111180 :C1BAAF1C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 07:51:14.111181 :C1BAAF1D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 07:51:14.111181 :C1BAAF1E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 07:51:14.111676 :C1BAAF1F: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-19 07:51:14.111720 :C1BAAF27: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-19 08:01:14.985613 :C1BB7344:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-19 08:01:15.335415 :C1BB73D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-19 08:01:15.330
2025-08-19 08:01:15.335417 :C1BB73D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21216 pso_num=22 pso_serial#=255
2025-08-19 08:01:15.335553 :C1BB73DA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21216 osp_idx=0 osp_ver=534042 osp_pg=0 (spawn #503879)
2025-08-19 08:01:15.335554 :C1BB73DB: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-19 08:01:15.335555 :C1BB73DC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=138 time=4271144642
2025-08-19 08:01:15.335555 :C1BB73DD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-19 08:01:15.335555 :C1BB73DE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-19 08:01:15.335556 :C1BB73DF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-19 08:01:15.335556 :C1BB73E0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-19 08:01:15.335556 :C1BB73E1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-19 08:01:15.336060 :C1BB73E2: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-19 08:01:15.336105 :C1BB73E9: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: 21216, W000)