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


*** 2025-06-08 06:00:04.626
*** SESSION ID:(193.3635) 2025-06-08 06:00:04.626
 

*** 2025-06-08 06:00:04.626
Process diagnostic dump for ORACLE.EXE (J001), OS id=15148,
pid: 24, proc_ser: 174, sid: 193, sess_ser: 3635 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15148
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001)
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.236679 sec, exc=0.236679 sec, total=0.236679 sec
    wait times: max=0.500000 sec, heur=3.727127 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.000008 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.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.000011 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.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
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 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=3 seq_num=4 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
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 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.500203 sec, exc=0.500203 sec, total=0.500203 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 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.490194 sec, exc=0.490194 sec, total=0.490194 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 3635
---------------------------------------------------
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-06-08 06:00:04.626
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 15148, J001)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-06-08 05:49:29.881833 :A1D3E441:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:49:29.881833 :A1D3E442:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=158 time=2337513063
2025-06-08 05:49:29.881834 :A1D3E443:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:49:29.881834 :A1D3E444:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:49:29.881835 :A1D3E445:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:49:29.881835 :A1D3E446:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:49:29.881835 :A1D3E447:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:49:29.882332 :A1D3E448: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-06-08 05:49:59.944421 :A1D3EE6D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:50:29.924698 :A1D3F826:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:50:29.914
2025-06-08 05:50:29.924701 :A1D3F827:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14596 pso_num=24 pso_serial#=164
2025-06-08 05:50:29.924860 :A1D3F828:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14596 osp_idx=22 osp_ver=374268 osp_pg=0 (spawn #263565)
2025-06-08 05:50:29.924860 :A1D3F829:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:50:29.924861 :A1D3F82A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=159 time=2337573110
2025-06-08 05:50:29.924861 :A1D3F82B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:50:29.924862 :A1D3F82C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:50:29.924862 :A1D3F82D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:50:29.924863 :A1D3F82E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:50:29.924863 :A1D3F82F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:50:29.925355 :A1D3F830: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-06-08 05:51:00.017510 :A1D401D2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:51:30.030006 :A1D40C35:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:51:30.019
2025-06-08 05:51:30.030007 :A1D40C36:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14664 pso_num=24 pso_serial#=165
2025-06-08 05:51:30.030140 :A1D40C37:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14664 osp_idx=22 osp_ver=374269 osp_pg=0 (spawn #263567)
2025-06-08 05:51:30.030141 :A1D40C38:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:51:30.030141 :A1D40C39:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=160 time=2337633219
2025-06-08 05:51:30.030141 :A1D40C3A:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:51:30.030142 :A1D40C3B:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:51:30.030142 :A1D40C3C:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:51:30.030143 :A1D40C3D:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:51:30.030143 :A1D40C3E:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:51:30.030604 :A1D40C3F: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-06-08 05:52:00.126161 :A1D4166C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:52:30.122407 :A1D42026:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:52:30.112
2025-06-08 05:52:30.122409 :A1D42027:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16000 pso_num=24 pso_serial#=166
2025-06-08 05:52:30.122544 :A1D42028:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16000 osp_idx=22 osp_ver=374270 osp_pg=0 (spawn #263569)
2025-06-08 05:52:30.122544 :A1D42029:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:52:30.122545 :A1D4202A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=161 time=2337693313
2025-06-08 05:52:30.122545 :A1D4202B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:52:30.122546 :A1D4202C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:52:30.122546 :A1D4202D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:52:30.122546 :A1D4202E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:52:30.122546 :A1D4202F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:52:30.123022 :A1D42030: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-06-08 05:53:00.193942 :A1D429E3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:53:30.216030 :A1D43390:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:53:30.205
2025-06-08 05:53:30.216032 :A1D43391:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16104 pso_num=24 pso_serial#=167
2025-06-08 05:53:30.216158 :A1D43392:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16104 osp_idx=22 osp_ver=374271 osp_pg=0 (spawn #263571)
2025-06-08 05:53:30.216159 :A1D43393:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:53:30.216161 :A1D43394:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=162 time=2337753407
2025-06-08 05:53:30.216161 :A1D43395:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:53:30.216161 :A1D43396:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:53:30.216162 :A1D43397:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:53:30.216162 :A1D43398:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:53:30.216162 :A1D43399:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:53:30.216636 :A1D4339A: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-06-08 05:54:00.280391 :A1D43D39:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:54:30.267085 :A1D44729:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:54:30.256
2025-06-08 05:54:30.267087 :A1D4472A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13012 pso_num=24 pso_serial#=168
2025-06-08 05:54:30.267224 :A1D4472B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13012 osp_idx=22 osp_ver=374272 osp_pg=0 (spawn #263573)
2025-06-08 05:54:30.267225 :A1D4472C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:54:30.267225 :A1D4472D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=163 time=2337813454
2025-06-08 05:54:30.267226 :A1D4472E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:54:30.267227 :A1D4472F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:54:30.267227 :A1D44730:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:54:30.267227 :A1D44731:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:54:30.267227 :A1D44732:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:54:30.267713 :A1D44733: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-06-08 05:54:55.285998 :A1D44F54: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-06-08 05:55:25.357029 :A1D458FF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:55:30.362773 :A1D45AAB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:55:30.351
2025-06-08 05:55:30.362775 :A1D45AAC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13924 pso_num=24 pso_serial#=169
2025-06-08 05:55:30.362897 :A1D45AAD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13924 osp_idx=22 osp_ver=374273 osp_pg=0 (spawn #263576)
2025-06-08 05:55:30.362898 :A1D45AAE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:55:30.362898 :A1D45AAF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=164 time=2337873547
2025-06-08 05:55:30.362899 :A1D45AB0:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:55:30.362899 :A1D45AB1:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:55:30.362900 :A1D45AB2:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:55:30.362900 :A1D45AB3:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:55:30.362900 :A1D45AB4:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:55:30.363387 :A1D45AB5: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-06-08 05:56:00.445146 :A1D4645A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:56:30.446101 :A1D46E48:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:56:30.435
2025-06-08 05:56:30.446103 :A1D46E49:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15204 pso_num=24 pso_serial#=170
2025-06-08 05:56:30.446239 :A1D46E4A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15204 osp_idx=22 osp_ver=374274 osp_pg=0 (spawn #263578)
2025-06-08 05:56:30.446240 :A1D46E4B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:56:30.446240 :A1D46E4C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=165 time=2337933625
2025-06-08 05:56:30.446241 :A1D46E4D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:56:30.446242 :A1D46E4E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:56:30.446242 :A1D46E4F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:56:30.446243 :A1D46E50:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:56:30.446243 :A1D46E51:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:56:30.446749 :A1D46E52: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-06-08 05:57:00.526099 :A1D478E5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:57:12.847595 :A1D47D0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:57:12.835
2025-06-08 05:57:12.847597 :A1D47D0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14876 pso_num=24 pso_serial#=171
2025-06-08 05:57:12.847740 :A1D47D10:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14876 osp_idx=22 osp_ver=374275 osp_pg=0 (spawn #263580)
2025-06-08 05:57:12.847741 :A1D47D11:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:57:12.847741 :A1D47D12:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=166 time=2337976032
2025-06-08 05:57:12.847741 :A1D47D13:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:57:12.847741 :A1D47D14:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:57:12.847742 :A1D47D15:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:57:12.847742 :A1D47D16:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:57:12.847742 :A1D47D17:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:57:12.848248 :A1D47D18: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-06-08 05:57:12.848286 :A1D47D1E: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-06-08 05:57:12.852374 :A1D47D37:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517
2025-06-08 05:57:12.852429 :A1D47D38:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517
2025-06-08 05:57:12.852682 :A1D47D39:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517
2025-06-08 05:57:12.852698 :A1D47D3A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517
2025-06-08 05:57:12.852909 :A1D47D3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517
2025-06-08 05:57:12.852922 :A1D47D3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517
2025-06-08 05:57:12.853124 :A1D47D3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517
2025-06-08 05:57:12.853137 :A1D47D3E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517
2025-06-08 05:57:12.853336 :A1D47D3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517
2025-06-08 05:57:12.853349 :A1D47D40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517
2025-06-08 05:57:12.853552 :A1D47D41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:kelt.c:517
2025-06-08 05:57:12.853564 :A1D47D42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:kelt.c:517
2025-06-08 05:57:12.855987 :A1D47D43:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:57:30.568326 :A1D48317:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:57:30.557
2025-06-08 05:57:30.568328 :A1D48318:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14796 pso_num=24 pso_serial#=172
2025-06-08 05:57:30.568462 :A1D48319:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14796 osp_idx=22 osp_ver=374276 osp_pg=0 (spawn #263582)
2025-06-08 05:57:30.568463 :A1D4831A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:57:30.568463 :A1D4831B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=167 time=2337993750
2025-06-08 05:57:30.568463 :A1D4831C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:57:30.568464 :A1D4831D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:57:30.568464 :A1D4831E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:57:30.568464 :A1D4831F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:57:30.568465 :A1D48320:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:57:30.568942 :A1D48321: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-06-08 05:58:00.665199 :A1D48CA7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:58:30.671343 :A1D4966F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:58:30.660
2025-06-08 05:58:30.671345 :A1D49670:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12012 pso_num=24 pso_serial#=173
2025-06-08 05:58:30.671475 :A1D49671:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12012 osp_idx=22 osp_ver=374277 osp_pg=0 (spawn #263584)
2025-06-08 05:58:30.671476 :A1D49672:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:58:30.671476 :A1D49673:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=168 time=2338053860
2025-06-08 05:58:30.671476 :A1D49674:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:58:30.671477 :A1D49675:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:58:30.671477 :A1D49676:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:58:30.671478 :A1D49677:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:58:30.671478 :A1D49678:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:58:30.671978 :A1D49679: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-06-08 05:59:00.730284 :A1D4A02B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-06-08 05:59:30.723312 :A1D4A9EC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-08 05:59:30.712
2025-06-08 05:59:30.723314 :A1D4A9ED:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15148 pso_num=24 pso_serial#=174
2025-06-08 05:59:30.723493 :A1D4A9EE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15148 osp_idx=22 osp_ver=374278 osp_pg=0 (spawn #263586)
2025-06-08 05:59:30.723494 :A1D4A9EF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-06-08 05:59:30.723494 :A1D4A9F0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=169 time=2338113907
2025-06-08 05:59:30.723495 :A1D4A9F1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-06-08 05:59:30.723495 :A1D4A9F2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-06-08 05:59:30.723495 :A1D4A9F3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-06-08 05:59:30.723495 :A1D4A9F4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-06-08 05:59:30.723496 :A1D4A9F5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-06-08 05:59:30.723997 :A1D4A9F6: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-06-08 06:00:00.766954 :A1D4B582: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-06-08 06:00:00.766954*:A1D4B591:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1749333600 eid=16781929 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0
2025-06-08 06:00:00.766954*:A1D4B592:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0
2025-06-08 06:00:00.766954*:A1D4B593:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718
2025-06-08 06:00:00.766954*:A1D4B594:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718
2025-06-08 06:00:00.766954*:A1D4B595:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2
2025-06-08 06:00:00.766954*:A1D4B596:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718
2025-06-08 06:00:00.772604 :A1D4B5AE: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-06-08 06:00:00.772649 :A1D4B5B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-06-08 06:00:00.784486 :A1D4B612: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-06-08 06:00:00.789502 :A1D4B790: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-06-08 06:00:00.790295 :A1D4B79C: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-06-08 06:00:00.796133 :A1D4B7CA: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-06-08 06:00:00.796287 :A1D4B7D9: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-06-08 06:00:00.797088 :A1D4B7E1: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-06-08 06:00:00.797295 :A1D4B7EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-06-08 06:00:00.797811 :A1D4B7EC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.797897 :A1D4B7EE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.798408 :A1D4B7F0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.798468 :A1D4B7F2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.798505 :A1D4B7F4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-06-08 06:00:00.799001 :A1D4B7F8: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-06-08 06:00:00.799139 :A1D4B80D: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-06-08 06:00:00.891646 :A1D4B8FB: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-06-08 06:00:00.893801 :A1D4B900: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-06-08 06:00:00.893845 :A1D4B903:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-06-08 06:00:00.896368 :A1D4B909:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-06-08 06:00:00.899717 :A1D4B90A: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-06-08 06:00:00.899812 :A1D4B90D: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-06-08 06:00:00.899896 :A1D4B91A: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: 15148, J001)