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


*** 2025-05-26 08:12:57.463
*** SESSION ID:(193.24091) 2025-05-26 08:12:57.463
 

*** 2025-05-26 08:12:57.463
Process diagnostic dump for ORACLE.EXE (J001), OS id=14728,
pid: 24, proc_ser: 216, sid: 193, sess_ser: 24091 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14728
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=29 seq_num=30 snap_id=1
    wait times: snap=0.376503 sec, exc=0.376503 sec, total=0.376503 sec
    wait times: max=0.500000 sec, heur=14.905455 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.000009 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=28 seq_num=29 snap_id=1
    wait times: snap=0.500118 sec, exc=0.500118 sec, total=0.500118 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=27 seq_num=28 snap_id=1
    wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=26 seq_num=27 snap_id=1
    wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=25 seq_num=26 snap_id=1
    wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=24 seq_num=25 snap_id=1
    wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=23 seq_num=24 snap_id=1
    wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=22 seq_num=23 snap_id=1
    wait times: snap=0.500106 sec, exc=0.500106 sec, total=0.500106 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=21 seq_num=22 snap_id=1
    wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=20 seq_num=21 snap_id=1
    wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=19 seq_num=20 snap_id=1
    wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
Sampled Session History of session 193 serial 24091
---------------------------------------------------
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
---------------------------------------------------
  [15 samples,                                             08:12:42 - 08:12:57]
    idle wait at each sample
  [session created at: 08:12:42]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-05-26 08:12:57.463
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 14728, J001)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-05-26 07:57:11.104930 :9C2804CE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 07:57:41.149682 :9C280EA5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 07:57:41.138
2025-05-26 07:57:41.149683 :9C280EA6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10636 pso_num=24 pso_serial#=201
2025-05-26 07:57:41.149824 :9C280EA7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10636 osp_idx=22 osp_ver=321755 osp_pg=0 (spawn #220539)
2025-05-26 07:57:41.149825 :9C280EA8: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-26 07:57:41.149825 :9C280EA9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=5 time=1222033391
2025-05-26 07:57:41.149826 :9C280EAA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 07:57:41.149826 :9C280EAB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 07:57:41.149826 :9C280EAC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 07:57:41.149827 :9C280EAD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 07:57:41.149827 :9C280EAE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 07:57:41.150311 :9C280EAF: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-26 07:58:11.235303 :9C281848:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 07:58:41.274022 :9C2822A9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 07:58:41.263
2025-05-26 07:58:41.274024 :9C2822AA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14780 pso_num=24 pso_serial#=202
2025-05-26 07:58:41.274167 :9C2822AB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14780 osp_idx=22 osp_ver=321756 osp_pg=0 (spawn #220541)
2025-05-26 07:58:41.274168 :9C2822AC: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-26 07:58:41.274168 :9C2822AD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=6 time=1222093516
2025-05-26 07:58:41.274168 :9C2822AE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 07:58:41.274169 :9C2822AF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 07:58:41.274169 :9C2822B0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 07:58:41.274169 :9C2822B1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 07:58:41.274170 :9C2822B2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 07:58:41.274693 :9C2822B3: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-26 07:59:11.372119 :9C282D76:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 07:59:41.387512 :9C283762:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 07:59:41.376
2025-05-26 07:59:41.387514 :9C283763:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14956 pso_num=24 pso_serial#=203
2025-05-26 07:59:41.387642 :9C283764:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14956 osp_idx=22 osp_ver=321757 osp_pg=0 (spawn #220543)
2025-05-26 07:59:41.387643 :9C283765: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-26 07:59:41.387643 :9C283766:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=7 time=1222153641
2025-05-26 07:59:41.387644 :9C283767:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 07:59:41.387644 :9C283768:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 07:59:41.387645 :9C283769:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 07:59:41.387645 :9C28376A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 07:59:41.387645 :9C28376B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 07:59:41.388106 :9C28376C: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-26 08:00:11.422044 :9C2840DC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:00:41.463749 :9C284ACA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:00:41.452
2025-05-26 08:00:41.463751 :9C284ACB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13532 pso_num=24 pso_serial#=204
2025-05-26 08:00:41.463887 :9C284ACC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13532 osp_idx=22 osp_ver=321758 osp_pg=0 (spawn #220545)
2025-05-26 08:00:41.463888 :9C284ACD: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-26 08:00:41.463888 :9C284ACE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=8 time=1222213704
2025-05-26 08:00:41.463889 :9C284ACF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:00:41.463889 :9C284AD0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:00:41.463889 :9C284AD1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:00:41.463890 :9C284AD2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:00:41.463890 :9C284AD3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:00:41.464395 :9C284AD4: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-26 08:01:11.575151 :9C285672:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:01:41.572684 :9C286050:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:01:41.561
2025-05-26 08:01:41.572686 :9C286051:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15300 pso_num=24 pso_serial#=205
2025-05-26 08:01:41.572818 :9C286052:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15300 osp_idx=22 osp_ver=321759 osp_pg=0 (spawn #220549)
2025-05-26 08:01:41.572819 :9C286053: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-26 08:01:41.572819 :9C286054:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=9 time=1222273813
2025-05-26 08:01:41.572819 :9C286055:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:01:41.572820 :9C286056:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:01:41.572820 :9C286057:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:01:41.572820 :9C286058:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:01:41.572821 :9C286059:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:01:41.573311 :9C28605A: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-26 08:02:11.669609 :9C286AC1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:02:41.672895 :9C28745D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:02:41.662
2025-05-26 08:02:41.672897 :9C28745E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13992 pso_num=24 pso_serial#=206
2025-05-26 08:02:41.673029 :9C28745F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13992 osp_idx=22 osp_ver=321760 osp_pg=0 (spawn #220553)
2025-05-26 08:02:41.673029 :9C287460: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-26 08:02:41.673030 :9C287461:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=10 time=1222333922
2025-05-26 08:02:41.673030 :9C287462:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:02:41.673031 :9C287463:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:02:41.673031 :9C287464:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:02:41.673032 :9C287465:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:02:41.673032 :9C287466:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:02:41.673519 :9C287467: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-26 08:03:11.789682 :9C287DD1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:03:41.756653 :9C2887C6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:03:41.745
2025-05-26 08:03:41.756656 :9C2887C7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14192 pso_num=24 pso_serial#=207
2025-05-26 08:03:41.756776 :9C2887C8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14192 osp_idx=22 osp_ver=321761 osp_pg=0 (spawn #220555)
2025-05-26 08:03:41.756777 :9C2887C9: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-26 08:03:41.756778 :9C2887CA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=11 time=1222394000
2025-05-26 08:03:41.756778 :9C2887CB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:03:41.756779 :9C2887CC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:03:41.756779 :9C2887CD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:03:41.756779 :9C2887CE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:03:41.756780 :9C2887CF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:03:41.757279 :9C2887D0: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-26 08:04:11.847522 :9C2891F8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:04:41.852158 :9C289BA6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:04:41.841
2025-05-26 08:04:41.852160 :9C289BA7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12360 pso_num=24 pso_serial#=208
2025-05-26 08:04:41.852285 :9C289BA8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12360 osp_idx=22 osp_ver=321762 osp_pg=0 (spawn #220557)
2025-05-26 08:04:41.852285 :9C289BA9: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-26 08:04:41.852286 :9C289BAA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=12 time=1222454094
2025-05-26 08:04:41.852286 :9C289BAB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:04:41.852287 :9C289BAC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:04:41.852287 :9C289BAD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:04:41.852287 :9C289BAE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:04:41.852288 :9C289BAF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:04:41.852764 :9C289BB0: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-26 08:05:11.928269 :9C28A593:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:05:41.944924 :9C28AF68:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:05:41.934
2025-05-26 08:05:41.944926 :9C28AF69:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14896 pso_num=24 pso_serial#=209
2025-05-26 08:05:41.945056 :9C28AF6A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14896 osp_idx=22 osp_ver=321763 osp_pg=0 (spawn #220560)
2025-05-26 08:05:41.945057 :9C28AF6B: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-26 08:05:41.945058 :9C28AF6C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=13 time=1222514188
2025-05-26 08:05:41.945058 :9C28AF6D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:05:41.945058 :9C28AF6E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:05:41.945059 :9C28AF6F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:05:41.945059 :9C28AF70:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:05:41.945059 :9C28AF71:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:05:41.945528 :9C28AF72: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-26 08:06:12.011306 :9C28BF79:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:06:17.002252 :9C28C14B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:06:16.990
2025-05-26 08:06:17.002254 :9C28C14C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9500 pso_num=24 pso_serial#=210
2025-05-26 08:06:17.002390 :9C28C14D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9500 osp_idx=22 osp_ver=321764 osp_pg=0 (spawn #220562)
2025-05-26 08:06:17.002391 :9C28C14E: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-26 08:06:17.002392 :9C28C14F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=14 time=1222549250
2025-05-26 08:06:17.002392 :9C28C150:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:06:17.002393 :9C28C151:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:06:17.002393 :9C28C152:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:06:17.002393 :9C28C153:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:06:17.002394 :9C28C154:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:06:17.002882 :9C28C155: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-26 08:06:42.027607 :9C28C962: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-26 08:07:12.126358 :9C28D3F4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:07:42.111966 :9C28DDAF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:07:42.101
2025-05-26 08:07:42.111969 :9C28DDB0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13840 pso_num=24 pso_serial#=211
2025-05-26 08:07:42.112097 :9C28DDB1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13840 osp_idx=22 osp_ver=321765 osp_pg=0 (spawn #220565)
2025-05-26 08:07:42.112098 :9C28DDB2: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-26 08:07:42.112098 :9C28DDB3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=15 time=1222634360
2025-05-26 08:07:42.112099 :9C28DDB4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:07:42.112099 :9C28DDB5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:07:42.112099 :9C28DDB6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:07:42.112100 :9C28DDB7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:07:42.112100 :9C28DDB8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:07:42.112598 :9C28DDB9: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-26 08:08:12.188429 :9C28E758:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:08:42.203017 :9C28F0FD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:08:42.191
2025-05-26 08:08:42.203019 :9C28F0FE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15156 pso_num=24 pso_serial#=212
2025-05-26 08:08:42.203155 :9C28F0FF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15156 osp_idx=22 osp_ver=321766 osp_pg=0 (spawn #220567)
2025-05-26 08:08:42.203156 :9C28F100: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-26 08:08:42.203157 :9C28F101:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=16 time=1222694454
2025-05-26 08:08:42.203157 :9C28F102:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:08:42.203158 :9C28F103:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:08:42.203158 :9C28F104:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:08:42.203158 :9C28F105:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:08:42.203159 :9C28F106:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:08:42.203715 :9C28F107: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-26 08:09:12.287135 :9C28FBBC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:09:42.321771 :9C290563:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:09:42.310
2025-05-26 08:09:42.321773 :9C290564:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11796 pso_num=24 pso_serial#=213
2025-05-26 08:09:42.321908 :9C290565:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11796 osp_idx=22 osp_ver=321767 osp_pg=0 (spawn #220569)
2025-05-26 08:09:42.321908 :9C290566: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-26 08:09:42.321909 :9C290567:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=17 time=1222754563
2025-05-26 08:09:42.321909 :9C290568:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:09:42.321910 :9C290569:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:09:42.321910 :9C29056A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:09:42.321910 :9C29056B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:09:42.321911 :9C29056C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:09:42.322442 :9C29056D: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-26 08:10:12.419528 :9C290F17:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:10:42.408014 :9C2918E0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:10:42.396
2025-05-26 08:10:42.408017 :9C2918E1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14688 pso_num=24 pso_serial#=214
2025-05-26 08:10:42.408154 :9C2918E2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14688 osp_idx=22 osp_ver=321768 osp_pg=0 (spawn #220571)
2025-05-26 08:10:42.408154 :9C2918E3: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-26 08:10:42.408155 :9C2918E4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=18 time=1222814657
2025-05-26 08:10:42.408156 :9C2918E5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:10:42.408156 :9C2918E6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:10:42.408156 :9C2918E7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:10:42.408157 :9C2918E8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:10:42.408157 :9C2918E9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:10:42.408642 :9C2918EA: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-26 08:11:12.491422 :9C2922B4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:11:42.473847 :9C292C39:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:11:42.462
2025-05-26 08:11:42.473849 :9C292C3A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14372 pso_num=24 pso_serial#=215
2025-05-26 08:11:42.473981 :9C292C3B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14372 osp_idx=22 osp_ver=321769 osp_pg=0 (spawn #220573)
2025-05-26 08:11:42.473982 :9C292C3C: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-26 08:11:42.473982 :9C292C3D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=19 time=1222874719
2025-05-26 08:11:42.473983 :9C292C3E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:11:42.473983 :9C292C3F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:11:42.473983 :9C292C40:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:11:42.473984 :9C292C41:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:11:42.473984 :9C292C42:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:11:42.474509 :9C292C43: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-26 08:12:12.562268 :9C29366E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-05-26 08:12:42.557916 :9C29403C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-26 08:12:42.547
2025-05-26 08:12:42.557920 :9C29403D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14728 pso_num=24 pso_serial#=216
2025-05-26 08:12:42.558043 :9C29403E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14728 osp_idx=22 osp_ver=321770 osp_pg=0 (spawn #220575)
2025-05-26 08:12:42.558043 :9C29403F: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-26 08:12:42.558044 :9C294040:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=20 time=1222934813
2025-05-26 08:12:42.558044 :9C294041:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-05-26 08:12:42.558045 :9C294042:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-05-26 08:12:42.558045 :9C294043:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-05-26 08:12:42.558046 :9C294044:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-05-26 08:12:42.558046 :9C294045:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-05-26 08:12:42.558553 :9C294046: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 24 (osid: 14728, J001)