Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250527081348\orcl_m002_13808_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:37288M/63366M, Ph+PgF:44247M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 24
Windows thread id: 13808, image: ORACLE.EXE (M002)


*** 2025-05-27 08:13:48.882
*** SESSION ID:(193.34285) 2025-05-27 08:13:48.882
 

*** 2025-05-27 08:13:48.882
Process diagnostic dump for ORACLE.EXE (M002), OS id=13808,
pid: 24, proc_ser: 245, sid: 193, sess_ser: 34285 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'db file sequential read'
    file#=0x2, block#=0x3d3a, blocks=0x1
    wait_id=1512 seq_num=1513 snap_id=1
    wait times: snap=0.001094 sec, exc=0.001094 sec, total=0.001094 sec
    wait times: max=infinite, heur=0.001094 sec
    wait counts: calls=0 os=0
    in_wait=1 iflags=0x5a0
Wait State:
  fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1
Session Wait History:
    elapsed time of 0.000271 sec since current wait
 0: waited for 'db file sequential read'
    file#=0x2, block#=0x3d2a, blocks=0x1
    wait_id=1511 seq_num=1512 snap_id=1
    wait times: snap=0.002091 sec, exc=0.002091 sec, total=0.002091 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000137 sec of elapsed time
 1: waited for 'db file sequential read'
    file#=0x2, block#=0x3ce2, blocks=0x1
    wait_id=1510 seq_num=1511 snap_id=1
    wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000149 sec of elapsed time
 2: waited for 'db file sequential read'
    file#=0x2, block#=0x3cd2, blocks=0x1
    wait_id=1509 seq_num=1510 snap_id=1
    wait times: snap=0.000063 sec, exc=0.000063 sec, total=0.000063 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000237 sec of elapsed time
 3: waited for 'db file sequential read'
    file#=0x2, block#=0x3caa, blocks=0x1
    wait_id=1508 seq_num=1509 snap_id=1
    wait times: snap=0.001404 sec, exc=0.001404 sec, total=0.001404 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000190 sec of elapsed time
 4: waited for 'db file sequential read'
    file#=0x2, block#=0x3c62, blocks=0x1
    wait_id=1507 seq_num=1508 snap_id=1
    wait times: snap=0.000119 sec, exc=0.000119 sec, total=0.000119 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000263 sec of elapsed time
 5: waited for 'db file sequential read'
    file#=0x2, block#=0x3c1a, blocks=0x1
    wait_id=1506 seq_num=1507 snap_id=1
    wait times: snap=0.001514 sec, exc=0.001514 sec, total=0.001514 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000277 sec of elapsed time
 6: waited for 'db file sequential read'
    file#=0x2, block#=0x3c0a, blocks=0x1
    wait_id=1505 seq_num=1506 snap_id=1
    wait times: snap=0.002172 sec, exc=0.002172 sec, total=0.002172 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000145 sec of elapsed time
 7: waited for 'db file sequential read'
    file#=0x2, block#=0x3bb2, blocks=0x1
    wait_id=1504 seq_num=1505 snap_id=1
    wait times: snap=0.000117 sec, exc=0.000117 sec, total=0.000117 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000159 sec of elapsed time
 8: waited for 'db file sequential read'
    file#=0x2, block#=0x3b72, blocks=0x1
    wait_id=1503 seq_num=1504 snap_id=1
    wait times: snap=0.000187 sec, exc=0.000187 sec, total=0.000187 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000249 sec of elapsed time
 9: waited for 'db file sequential read'
    file#=0x2, block#=0x3b22, blocks=0x1
    wait_id=1502 seq_num=1503 snap_id=1
    wait times: snap=0.001377 sec, exc=0.001377 sec, total=0.001377 sec
    wait times: max=infinite
    wait counts: calls=0 os=0
    occurred after 0.000149 sec of elapsed time
Sampled Session History of session 193 serial 34285
---------------------------------------------------
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
---------------------------------------------------
  [1 sample,                                                          08:13:48]
    waited for 'ADR block file read', seq_num: 1050
      p1: ' '=0x0
      p2: ' '=0x0
      p3: ' '=0x0
      time_waited: >= 0 sec (still in wait)
  [1 sample,                                                          08:13:47]
    idle wait at each sample
  [session created at: 08:13:48]
---------------------------------------------------
Sampled Session History Summary:
  longest_non_idle_wait: 'ADR block file read'
  [1 sample, 08:13:48 ]
      time_waited: >= 0 sec (still in wait)
---------------------------------------------------
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.016000 sec
  (max dump time=30.000000 sec)

*** 2025-05-27 08:13:48.897
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 13808, M002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-27 07:59:59.382036 :9C991BB3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 07:59:59.382036 :9C991BB4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 07:59:59.382513 :9C991BB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:00:29.494139 :9C992689:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:00:59.478886 :9C99306A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:00:59.468
2025-05-27 08:00:59.478888 :9C99306B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12360 pso_num=24 pso_serial#=230
2025-05-27 08:00:59.479022 :9C99306C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12360 osp_idx=22 osp_ver=326119 osp_pg=0 (spawn #223877)
2025-05-27 08:00:59.479022 :9C99306D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:00:59.479023 :9C99306E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=222 time=1308631719
2025-05-27 08:00:59.479023 :9C99306F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:00:59.479023 :9C993070:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:00:59.479024 :9C993071:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:00:59.479024 :9C993072:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:00:59.479024 :9C993073:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:00:59.479500 :9C993074:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:01:29.556529 :9C993B1C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:01:59.575534 :9C9944E1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:01:59.564
2025-05-27 08:01:59.575536 :9C9944E2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9064 pso_num=24 pso_serial#=231
2025-05-27 08:01:59.575662 :9C9944E3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9064 osp_idx=22 osp_ver=326120 osp_pg=0 (spawn #223879)
2025-05-27 08:01:59.575663 :9C9944E4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:01:59.575663 :9C9944E5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=223 time=1308691829
2025-05-27 08:01:59.575664 :9C9944E6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:01:59.575664 :9C9944E7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:01:59.575664 :9C9944E8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:01:59.575665 :9C9944E9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:01:59.575665 :9C9944EA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:01:59.576148 :9C9944EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:02:29.709084 :9C994EBB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:02:59.658437 :9C995834:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:02:59.647
2025-05-27 08:02:59.658439 :9C995835:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15044 pso_num=24 pso_serial#=232
2025-05-27 08:02:59.658577 :9C995836:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15044 osp_idx=22 osp_ver=326121 osp_pg=0 (spawn #223881)
2025-05-27 08:02:59.658578 :9C995837:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:02:59.658578 :9C995838:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=224 time=1308751907
2025-05-27 08:02:59.658579 :9C995839:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:02:59.658579 :9C99583A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:02:59.658580 :9C99583B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:02:59.658580 :9C99583C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:02:59.658580 :9C99583D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:02:59.659077 :9C99583E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:03:29.774689 :9C9962AB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:03:59.766727 :9C996C48:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:03:59.755
2025-05-27 08:03:59.766729 :9C996C49:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12296 pso_num=24 pso_serial#=233
2025-05-27 08:03:59.766864 :9C996C4A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12296 osp_idx=22 osp_ver=326122 osp_pg=0 (spawn #223883)
2025-05-27 08:03:59.766865 :9C996C4B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:03:59.766866 :9C996C4C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=225 time=1308812016
2025-05-27 08:03:59.766866 :9C996C4D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:03:59.766867 :9C996C4E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:03:59.766867 :9C996C4F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:03:59.766867 :9C996C50:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:03:59.766868 :9C996C51:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:03:59.767357 :9C996C52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:04:29.847785 :9C9975C0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:04:59.847698 :9C997F3A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:04:59.836
2025-05-27 08:04:59.847701 :9C997F3B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11708 pso_num=24 pso_serial#=234
2025-05-27 08:04:59.847827 :9C997F3C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11708 osp_idx=22 osp_ver=326123 osp_pg=0 (spawn #223885)
2025-05-27 08:04:59.847828 :9C997F3D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:04:59.847828 :9C997F3E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=226 time=1308872094
2025-05-27 08:04:59.847828 :9C997F3F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:04:59.847829 :9C997F40:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:04:59.847829 :9C997F41:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:04:59.847829 :9C997F42:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:04:59.847830 :9C997F43:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:04:59.848360 :9C997F44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:05:29.939516 :9C998E93:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:05:59.940515 :9C999819:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:05:59.929
2025-05-27 08:05:59.940517 :9C99981A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14996 pso_num=24 pso_serial#=235
2025-05-27 08:05:59.940650 :9C99981B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14996 osp_idx=22 osp_ver=326124 osp_pg=0 (spawn #223888)
2025-05-27 08:05:59.940650 :9C99981C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:05:59.940651 :9C99981D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=227 time=1308932188
2025-05-27 08:05:59.940651 :9C99981E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:05:59.940652 :9C99981F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:05:59.940652 :9C999820:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:05:59.940652 :9C999821:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:05:59.940653 :9C999822:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:05:59.941119 :9C999823:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:06:30.003996 :9C99A307:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:07:00.042560 :9C99ACB3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:07:00.032
2025-05-27 08:07:00.042562 :9C99ACB4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15352 pso_num=24 pso_serial#=236
2025-05-27 08:07:00.042702 :9C99ACB5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15352 osp_idx=22 osp_ver=326125 osp_pg=0 (spawn #223890)
2025-05-27 08:07:00.042702 :9C99ACB6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:07:00.042703 :9C99ACB7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=228 time=1308992297
2025-05-27 08:07:00.042703 :9C99ACB8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:07:00.042704 :9C99ACB9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:07:00.042704 :9C99ACBA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:07:00.042705 :9C99ACBB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:07:00.042705 :9C99ACBC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:07:00.043203 :9C99ACBD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:07:30.132772 :9C99B66D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:08:00.152274 :9C99BFEA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:08:00.141
2025-05-27 08:08:00.152276 :9C99BFEB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13584 pso_num=24 pso_serial#=237
2025-05-27 08:08:00.152411 :9C99BFEC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13584 osp_idx=22 osp_ver=326126 osp_pg=0 (spawn #223892)
2025-05-27 08:08:00.152411 :9C99BFED:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:08:00.152412 :9C99BFEE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=229 time=1309052407
2025-05-27 08:08:00.152412 :9C99BFEF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:08:00.152412 :9C99BFF0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:08:00.152413 :9C99BFF1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:08:00.152413 :9C99BFF2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:08:00.152413 :9C99BFF3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:08:00.152887 :9C99BFF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:08:30.251022 :9C99C9EB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:08:35.215037 :9C99CBB0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:08:35.203
2025-05-27 08:08:35.215040 :9C99CBB1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14520 pso_num=24 pso_serial#=238
2025-05-27 08:08:35.215177 :9C99CBB2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14520 osp_idx=22 osp_ver=326127 osp_pg=0 (spawn #223895)
2025-05-27 08:08:35.215178 :9C99CBB3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:08:35.215178 :9C99CBB4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=230 time=1309087469
2025-05-27 08:08:35.215179 :9C99CBB5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:08:35.215179 :9C99CBB6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:08:35.215180 :9C99CBB7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:08:35.215180 :9C99CBB8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:08:35.215180 :9C99CBB9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:08:35.215713 :9C99CBBA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:09:00.219693 :9C99D36C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-05-27 08:09:30.318568 :9C99DCE5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:10:00.304775 :9C99E68E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:10:00.294
2025-05-27 08:10:00.304777 :9C99E68F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13896 pso_num=24 pso_serial#=239
2025-05-27 08:10:00.304914 :9C99E690:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13896 osp_idx=22 osp_ver=326128 osp_pg=0 (spawn #223897)
2025-05-27 08:10:00.304914 :9C99E691:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:10:00.304915 :9C99E692:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=231 time=1309172547
2025-05-27 08:10:00.304915 :9C99E693:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:10:00.304915 :9C99E694:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:10:00.304916 :9C99E695:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:10:00.304916 :9C99E696:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:10:00.304916 :9C99E697:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:10:00.305383 :9C99E698:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:10:30.399858 :9C99F079:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:11:00.408709 :9C99FA4F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:11:00.397
2025-05-27 08:11:00.408711 :9C99FA50:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13244 pso_num=24 pso_serial#=240
2025-05-27 08:11:00.408859 :9C99FA51:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13244 osp_idx=22 osp_ver=326129 osp_pg=0 (spawn #223900)
2025-05-27 08:11:00.408859 :9C99FA52:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:11:00.408860 :9C99FA53:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=232 time=1309232657
2025-05-27 08:11:00.408860 :9C99FA54:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:11:00.408861 :9C99FA55:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:11:00.408861 :9C99FA56:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:11:00.408861 :9C99FA57:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:11:00.408862 :9C99FA58:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:11:00.409349 :9C99FA59:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:11:30.513961 :9C9A03F9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:12:00.538405 :9C9A0D3D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:12:00.527
2025-05-27 08:12:00.538407 :9C9A0D3E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13960 pso_num=24 pso_serial#=241
2025-05-27 08:12:00.538544 :9C9A0D3F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13960 osp_idx=22 osp_ver=326130 osp_pg=0 (spawn #223902)
2025-05-27 08:12:00.538545 :9C9A0D40:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:12:00.538545 :9C9A0D41:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=233 time=1309292782
2025-05-27 08:12:00.538546 :9C9A0D42:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:12:00.538546 :9C9A0D43:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:12:00.538547 :9C9A0D44:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:12:00.538547 :9C9A0D45:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:12:00.538547 :9C9A0D46:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:12:00.539018 :9C9A0D47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:12:30.636668 :9C9A173E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:13:00.641533 :9C9A20D7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:13:00.630
2025-05-27 08:13:00.641535 :9C9A20D8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14928 pso_num=24 pso_serial#=242
2025-05-27 08:13:00.641676 :9C9A20D9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14928 osp_idx=22 osp_ver=326131 osp_pg=0 (spawn #223904)
2025-05-27 08:13:00.641677 :9C9A20DA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:13:00.641678 :9C9A20DB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=234 time=1309352891
2025-05-27 08:13:00.641678 :9C9A20DC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:13:00.641678 :9C9A20DD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:13:00.641679 :9C9A20DE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:13:00.641679 :9C9A20DF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:13:00.641680 :9C9A20E0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:13:00.642166 :9C9A20E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:13:30.703167 :9C9A2A88:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:13:45.206576 :9C9A6794:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:13:45.195
2025-05-27 08:13:45.206578 :9C9A6795:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12532 pso_num=24 pso_serial#=243
2025-05-27 08:13:45.206703 :9C9A6796:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12532 (legacy spawn)
2025-05-27 08:13:45.213109 :9C9A6797:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-05-27 08:13:45.213669 :9C9A67A0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
2025-05-27 08:13:45.214541 :9C9A67A1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:13:46.117497 :9C9A692F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:13:46.117
2025-05-27 08:13:46.117498 :9C9A6930:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13744 pso_num=24 pso_serial#=244
2025-05-27 08:13:46.117663 :9C9A6931:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13744 osp_idx=22 osp_ver=326133 osp_pg=0 (spawn #223906)
2025-05-27 08:13:46.117663 :9C9A6932:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:13:46.117664 :9C9A6933:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=235 time=1309398360
2025-05-27 08:13:46.117664 :9C9A6934:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:13:46.117665 :9C9A6935:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:13:46.117665 :9C9A6936:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:13:46.117665 :9C9A6937:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:13:46.117666 :9C9A6938:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:13:46.118888 :9C9A6939:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:13:46.118931 :9C9A693F:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-05-27 08:13:46.219881 :9C9A6D49:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-27 08:13:48.367187 :9C9A6DE3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-27 08:13:48.366
2025-05-27 08:13:48.367189 :9C9A6DE4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13808 pso_num=24 pso_serial#=245
2025-05-27 08:13:48.367345 :9C9A6DE5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13808 osp_idx=22 osp_ver=326134 osp_pg=0 (spawn #223908)
2025-05-27 08:13:48.367346 :9C9A6DE6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-05-27 08:13:48.367347 :9C9A6DE7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=236 time=1309400610
2025-05-27 08:13:48.367347 :9C9A6DE8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-27 08:13:48.367348 :9C9A6DE9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-27 08:13:48.367348 :9C9A6DEA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-27 08:13:48.367348 :9C9A6DEB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-27 08:13:48.367349 :9C9A6DEC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-27 08:13:48.367860 :9C9A6DED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-05-27 08:13:48.367903 :9C9A6DF3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 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 24 (osid: 13808, M002)