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


*** 2025-09-06 06:00:04.497
*** SESSION ID:(193.865) 2025-09-06 06:00:04.497
 

*** 2025-09-06 06:00:04.497
Process diagnostic dump for ORACLE.EXE (J000), OS id=24112,
pid: 24, proc_ser: 147, sid: 193, sess_ser: 865 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24112
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.296970 sec, exc=0.296970 sec, total=0.296970 sec
    wait times: max=0.500000 sec, heur=3.800156 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.000013 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=0.502933 sec, exc=0.502933 sec, total=0.502933 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000014 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.510120 sec, exc=0.510120 sec, total=0.510120 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 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
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000006 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.490096 sec, exc=0.490096 sec, total=0.490096 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 193 serial 865
---------------------------------------------------
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
---------------------------------------------------
  [4 samples,                                              06:00:00 - 06:00:04]
    idle wait at each sample
  [session created at: 06:00:00]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-06 06:00:04.512
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 24112, J000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-06 05:50:09.787436 :C9A38E8D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:50:09.787437 :C9A38E8E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:50:09.787437 :C9A38E8F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:50:09.787438 :C9A38E90:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:50:09.787928 :C9A38E91: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-09-06 05:50:39.854659 :C9A39864:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:51:09.872457 :C9A3A241:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:51:09.861
2025-09-06 05:51:09.872459 :C9A3A242:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24468 pso_num=24 pso_serial#=137
2025-09-06 05:51:09.872575 :C9A3A243:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24468 osp_idx=22 osp_ver=849896 osp_pg=0 (spawn #563578)
2025-09-06 05:51:09.872576 :C9A3A244:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:51:09.872577 :C9A3A245:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=172 time=1523521174
2025-09-06 05:51:09.872577 :C9A3A246:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:51:09.872577 :C9A3A247:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:51:09.872578 :C9A3A248:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:51:09.872578 :C9A3A249:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:51:09.872579 :C9A3A24A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:51:09.873108 :C9A3A24B: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-09-06 05:51:39.970965 :C9A3ABEB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:52:09.967423 :C9A3B592:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:52:09.956
2025-09-06 05:52:09.967426 :C9A3B593:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18848 pso_num=24 pso_serial#=138
2025-09-06 05:52:09.967564 :C9A3B594:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18848 osp_idx=22 osp_ver=849897 osp_pg=0 (spawn #563580)
2025-09-06 05:52:09.967565 :C9A3B595:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:52:09.967565 :C9A3B596:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=173 time=1523581268
2025-09-06 05:52:09.967566 :C9A3B597:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:52:09.967566 :C9A3B598:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:52:09.967566 :C9A3B599:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:52:09.967567 :C9A3B59A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:52:09.967567 :C9A3B59B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:52:09.968060 :C9A3B59C: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-09-06 05:52:40.060502 :C9A3C037:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:53:10.065127 :C9A3CA64:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:53:10.053
2025-09-06 05:53:10.065129 :C9A3CA65:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16664 pso_num=24 pso_serial#=139
2025-09-06 05:53:10.065250 :C9A3CA66:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16664 osp_idx=22 osp_ver=849898 osp_pg=0 (spawn #563583)
2025-09-06 05:53:10.065251 :C9A3CA67:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:53:10.065252 :C9A3CA68:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=174 time=1523641362
2025-09-06 05:53:10.065252 :C9A3CA69:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:53:10.065253 :C9A3CA6A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:53:10.065253 :C9A3CA6B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:53:10.065253 :C9A3CA6C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:53:10.065254 :C9A3CA6D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:53:10.065737 :C9A3CA6E: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-09-06 05:53:40.118676 :C9A3D421:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:54:10.121382 :C9A3DE45:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:54:10.110
2025-09-06 05:54:10.121384 :C9A3DE46:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22716 pso_num=24 pso_serial#=140
2025-09-06 05:54:10.121507 :C9A3DE47:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22716 osp_idx=22 osp_ver=849899 osp_pg=0 (spawn #563585)
2025-09-06 05:54:10.121508 :C9A3DE48:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:54:10.121508 :C9A3DE49:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=175 time=1523701424
2025-09-06 05:54:10.121508 :C9A3DE4A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:54:10.121509 :C9A3DE4B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:54:10.121509 :C9A3DE4C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:54:10.121509 :C9A3DE4D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:54:10.121509 :C9A3DE4E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:54:10.121997 :C9A3DE4F: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-09-06 05:54:40.184412 :C9A3E80E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:55:10.191063 :C9A3F188:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:55:10.180
2025-09-06 05:55:10.191066 :C9A3F189:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19808 pso_num=24 pso_serial#=141
2025-09-06 05:55:10.191181 :C9A3F18A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19808 osp_idx=22 osp_ver=849900 osp_pg=0 (spawn #563587)
2025-09-06 05:55:10.191182 :C9A3F18B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:55:10.191182 :C9A3F18C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=176 time=1523761487
2025-09-06 05:55:10.191183 :C9A3F18D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:55:10.191183 :C9A3F18E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:55:10.191184 :C9A3F18F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:55:10.191184 :C9A3F190:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:55:10.191184 :C9A3F191:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:55:10.191670 :C9A3F192: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-09-06 05:55:40.276163 :C9A3FBC0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:56:10.291565 :C9A4057B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:56:10.279
2025-09-06 05:56:10.291567 :C9A4057C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19996 pso_num=24 pso_serial#=142
2025-09-06 05:56:10.291686 :C9A4057D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19996 osp_idx=22 osp_ver=849901 osp_pg=0 (spawn #563590)
2025-09-06 05:56:10.291687 :C9A4057E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:56:10.291687 :C9A4057F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=177 time=1523821596
2025-09-06 05:56:10.291688 :C9A40580:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:56:10.291688 :C9A40581:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:56:10.291688 :C9A40582:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:56:10.291689 :C9A40583:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:56:10.291689 :C9A40584:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:56:10.292205 :C9A40585: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-09-06 05:56:40.340429 :C9A40EFB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:56:55.344911 :C9A4140F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:56:55.334
2025-09-06 05:56:55.344913 :C9A41410:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24044 pso_num=24 pso_serial#=143
2025-09-06 05:56:55.345042 :C9A41411:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24044 osp_idx=22 osp_ver=849902 osp_pg=0 (spawn #563592)
2025-09-06 05:56:55.345043 :C9A41412:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:56:55.345043 :C9A41413:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=178 time=1523866643
2025-09-06 05:56:55.345044 :C9A41414:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:56:55.345044 :C9A41415:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:56:55.345044 :C9A41416:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:56:55.345045 :C9A41417:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:56:55.345045 :C9A41418:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:56:55.345509 :C9A41419: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-09-06 05:57:10.373679 :C9A41901: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-09-06 05:57:40.444753 :C9A42319:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:58:10.457039 :C9A42CAC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:58:10.445
2025-09-06 05:58:10.457041 :C9A42CAD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23576 pso_num=24 pso_serial#=144
2025-09-06 05:58:10.457176 :C9A42CAE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23576 osp_idx=22 osp_ver=849903 osp_pg=0 (spawn #563594)
2025-09-06 05:58:10.457177 :C9A42CAF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:58:10.457178 :C9A42CB0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=179 time=1523941752
2025-09-06 05:58:10.457178 :C9A42CB1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:58:10.457179 :C9A42CB2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:58:10.457179 :C9A42CB3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:58:10.457179 :C9A42CB4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:58:10.457180 :C9A42CB5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:58:10.457716 :C9A42CB6: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-09-06 05:58:40.528728 :C9A43673:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 05:59:10.523256 :C9A4405F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 05:59:10.512
2025-09-06 05:59:10.523258 :C9A44060:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12980 pso_num=24 pso_serial#=145
2025-09-06 05:59:10.523375 :C9A44061:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12980 osp_idx=22 osp_ver=849904 osp_pg=0 (spawn #563596)
2025-09-06 05:59:10.523376 :C9A44062:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 05:59:10.523376 :C9A44063:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=180 time=1524001815
2025-09-06 05:59:10.523377 :C9A44064:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 05:59:10.523377 :C9A44065:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 05:59:10.523377 :C9A44066:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 05:59:10.523378 :C9A44067:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 05:59:10.523378 :C9A44068:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 05:59:10.523856 :C9A44069: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-09-06 05:59:40.596346 :C9A449FA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 06:00:00.039481 :C9A45084:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 06:00:00.031
2025-09-06 06:00:00.039483 :C9A45085:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24300 pso_num=24 pso_serial#=146
2025-09-06 06:00:00.039618 :C9A45086:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24300 osp_idx=22 osp_ver=849905 osp_pg=0 (spawn #563598)
2025-09-06 06:00:00.039618 :C9A45087:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 06:00:00.039618 :C9A45088:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=181 time=1524051346
2025-09-06 06:00:00.039619 :C9A45089:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 06:00:00.039619 :C9A4508A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 06:00:00.039620 :C9A4508B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 06:00:00.039620 :C9A4508C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 06:00:00.039620 :C9A4508D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 06:00:00.040108 :C9A4508E: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-09-06 06:00:00.040151 :C9A45095: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-09-06 06:00:00.040233 :C9A450A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.044188 :C9A450B8: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-09-06 06:00:00.045452 :C9A450C0: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-09-06 06:00:00.045479 :C9A450C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.045518 :C9A450CE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-06 06:00:00.047318 :C9A450D9: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-09-06 06:00:00.047345 :C9A450DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.047381 :C9A450E7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-06 06:00:00.047853 :C9A450F2: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-09-06 06:00:00.050414 :C9A450FA: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-09-06 06:00:00.055685 :C9A45102: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-09-06 06:00:00.061204 :C9A4510A: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-09-06 06:00:00.062631 :C9A45112: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-09-06 06:00:00.065680 :C9A4511A: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-09-06 06:00:00.066965 :C9A45122: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-09-06 06:00:00.070044 :C9A4512A: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-09-06 06:00:00.073904 :C9A45132:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.074323 :C9A45141: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-09-06 06:00:00.074374 :C9A45144:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-09-06 06:00:00.074389 :C9A45145:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-09-06 06:00:00.077126 :C9A4514B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.077425 :C9A4515A: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-09-06 06:00:00.077474 :C9A4515D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-09-06 06:00:00.077489 :C9A4515E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-09-06 06:00:00.080154 :C9A45164:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.080445 :C9A45173: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-09-06 06:00:00.080490 :C9A45176:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-09-06 06:00:00.080503 :C9A45177:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-09-06 06:00:00.080676 :C9A4517D: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-09-06 06:00:00.080688 :C9A45180:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.080710 :C9A4518C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-06 06:00:00.082369 :C9A45196:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-06 06:00:00.567401 :C9A451B6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-06 06:00:00.562
2025-09-06 06:00:00.567403 :C9A451B7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24112 pso_num=24 pso_serial#=147
2025-09-06 06:00:00.567542 :C9A451B8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24112 osp_idx=22 osp_ver=849906 osp_pg=0 (spawn #563599)
2025-09-06 06:00:00.567543 :C9A451B9:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-06 06:00:00.567543 :C9A451BA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=182 time=1524051877
2025-09-06 06:00:00.567545 :C9A451BB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-06 06:00:00.567545 :C9A451BC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-06 06:00:00.567545 :C9A451BD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-06 06:00:00.567546 :C9A451BE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-06 06:00:00.567546 :C9A451BF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-06 06:00:00.568027 :C9A451C0: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-09-06 06:00:00.573485 :C9A451ED: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-09-06 06:00:00.573526 :C9A451F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-09-06 06:00:00.583135 :C9A4522D: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-09-06 06:00:00.585186 :C9A4525D: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-09-06 06:00:00.590361 :C9A4527A: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-09-06 06:00:00.591137 :C9A452C4: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-09-06 06:00:00.596682 :C9A453ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.596843 :C9A453FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.597601 :C9A4540E: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-09-06 06:00:00.597819 :C9A45416:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-09-06 06:00:00.599387 :C9A45417: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-09-06 06:00:00.599435 :C9A4541A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.599508 :C9A45427: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-09-06 06:00:00.702816 :C9A4552F: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-09-06 06:00:00.704979 :C9A45533: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-09-06 06:00:00.705027 :C9A45536:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-09-06 06:00:00.707557 :C9A4553C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-09-06 06:00:00.710976 :C9A4553D: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-09-06 06:00:00.711108 :C9A45540:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-09-06 06:00:00.711191 :C9A4554D: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 24 (osid: 24112, J000)