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


*** 2025-09-09 22:00:08.632
*** SESSION ID:(193.46365) 2025-09-09 22:00:08.632
 

*** 2025-09-09 22:00:08.632
Process diagnostic dump for ORACLE.EXE (J001), OS id=23756,
pid: 24, proc_ser: 73, sid: 193, sess_ser: 46365 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23756
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=14 seq_num=15 snap_id=1
    wait times: snap=0.140255 sec, exc=0.140255 sec, total=0.140255 sec
    wait times: max=0.500000 sec, heur=6.598532 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=13 seq_num=14 snap_id=1
    wait times: snap=0.501106 sec, exc=0.501106 sec, total=0.501106 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000006 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=12 seq_num=13 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.000007 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 snap_id=1
    wait times: snap=0.499973 sec, exc=0.499973 sec, total=0.499973 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000017 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 snap_id=1
    wait times: snap=0.500016 sec, exc=0.500016 sec, total=0.500016 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=9 seq_num=10 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.000009 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.500113 sec, exc=0.500113 sec, total=0.500113 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 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
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=6 seq_num=7 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.000007 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.499132 sec, exc=0.499132 sec, total=0.499132 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000004 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 snap_id=1
    wait times: snap=0.360246 sec, exc=0.360246 sec, total=0.360246 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 sec of elapsed time
Sampled Session History of session 193 serial 46365
---------------------------------------------------
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
---------------------------------------------------
  [7 samples,                                              22:00:02 - 22:00:08]
    idle wait at each sample
  [session created at: 22:00:02]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.015000 sec
  (max dump time=30.000000 sec)

*** 2025-09-09 22:00:08.647
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 23756, J001)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-09 21:47:33.172592 :CB421697:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:47:33.173110 :CB421698: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-09 21:48:03.206530 :CB42203B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:48:33.254533 :CB422A0D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:48:33.243
2025-09-09 21:48:33.254536 :CB422A0E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23824 pso_num=24 pso_serial#=62
2025-09-09 21:48:33.254657 :CB422A0F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23824 osp_idx=22 osp_ver=869201 osp_pg=0 (spawn #575803)
2025-09-09 21:48:33.254657 :CB422A10: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-09 21:48:33.254658 :CB422A11:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=100 time=1840164549
2025-09-09 21:48:33.254658 :CB422A12:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:48:33.254659 :CB422A13:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:48:33.254659 :CB422A14:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:48:33.254659 :CB422A15:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:48:33.254660 :CB422A16:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:48:33.255119 :CB422A17: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-09 21:49:03.312976 :CB423409:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:49:33.336070 :CB423DE9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:49:33.325
2025-09-09 21:49:33.336072 :CB423DEA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23680 pso_num=24 pso_serial#=63
2025-09-09 21:49:33.336217 :CB423DEB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23680 osp_idx=22 osp_ver=869202 osp_pg=0 (spawn #575805)
2025-09-09 21:49:33.336218 :CB423DEC: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-09 21:49:33.336218 :CB423DED:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=101 time=1840224627
2025-09-09 21:49:33.336219 :CB423DEE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:49:33.336219 :CB423DEF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:49:33.336219 :CB423DF0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:49:33.336220 :CB423DF1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:49:33.336220 :CB423DF2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:49:33.336706 :CB423DF3: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-09 21:50:03.432880 :CB424852:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:50:33.407109 :CB425246:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:50:33.396
2025-09-09 21:50:33.407111 :CB425247:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23480 pso_num=24 pso_serial#=64
2025-09-09 21:50:33.407227 :CB425248:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23480 osp_idx=22 osp_ver=869203 osp_pg=0 (spawn #575807)
2025-09-09 21:50:33.407228 :CB425249: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-09 21:50:33.407228 :CB42524A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=102 time=1840284705
2025-09-09 21:50:33.407229 :CB42524B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:50:33.407229 :CB42524C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:50:33.407229 :CB42524D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:50:33.407230 :CB42524E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:50:33.407230 :CB42524F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:50:33.407694 :CB425250: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-09 21:51:03.467291 :CB425BF7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:51:33.508334 :CB4265D9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:51:33.497
2025-09-09 21:51:33.508336 :CB4265DA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20352 pso_num=24 pso_serial#=65
2025-09-09 21:51:33.508473 :CB4265DB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20352 osp_idx=22 osp_ver=869204 osp_pg=0 (spawn #575809)
2025-09-09 21:51:33.508474 :CB4265DC: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-09 21:51:33.508474 :CB4265DD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=103 time=1840344799
2025-09-09 21:51:33.508474 :CB4265DE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:51:33.508475 :CB4265DF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:51:33.508475 :CB4265E0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:51:33.508475 :CB4265E1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:51:33.508476 :CB4265E2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:51:33.508957 :CB4265E3: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-09 21:52:03.574502 :CB427095:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:52:33.578290 :CB427A92:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:52:33.566
2025-09-09 21:52:33.578292 :CB427A93:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24452 pso_num=24 pso_serial#=66
2025-09-09 21:52:33.578405 :CB427A94:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24452 osp_idx=22 osp_ver=869205 osp_pg=0 (spawn #575811)
2025-09-09 21:52:33.578407 :CB427A95: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-09 21:52:33.578407 :CB427A96:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=104 time=1840404877
2025-09-09 21:52:33.578407 :CB427A97:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:52:33.578408 :CB427A98:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:52:33.578408 :CB427A99:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:52:33.578408 :CB427A9A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:52:33.578408 :CB427A9B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:52:33.578949 :CB427A9C: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-09 21:53:03.687484 :CB42847A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:53:33.677958 :CB428E9F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:53:33.666
2025-09-09 21:53:33.677960 :CB428EA0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20520 pso_num=24 pso_serial#=67
2025-09-09 21:53:33.678105 :CB428EA1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20520 osp_idx=22 osp_ver=869206 osp_pg=0 (spawn #575814)
2025-09-09 21:53:33.678106 :CB428EA2: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-09 21:53:33.678106 :CB428EA3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=105 time=1840464971
2025-09-09 21:53:33.678107 :CB428EA4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:53:33.678107 :CB428EA5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:53:33.678108 :CB428EA6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:53:33.678108 :CB428EA7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:53:33.678108 :CB428EA8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:53:33.678594 :CB428EA9: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-09 21:54:03.734322 :CB429837:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:54:18.714759 :CB429D7A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:54:18.703
2025-09-09 21:54:18.714761 :CB429D7B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19496 pso_num=24 pso_serial#=68
2025-09-09 21:54:18.714897 :CB429D7C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19496 osp_idx=22 osp_ver=869207 osp_pg=0 (spawn #575816)
2025-09-09 21:54:18.714898 :CB429D7D: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-09 21:54:18.714898 :CB429D7E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=106 time=1840510018
2025-09-09 21:54:18.714898 :CB429D7F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:54:18.714899 :CB429D80:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:54:18.714899 :CB429D81:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:54:18.714900 :CB429D82:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:54:18.714900 :CB429D83:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:54:18.715377 :CB429D84: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-09 21:54:33.730641 :CB42A264: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-09 21:55:03.829095 :CB42AC62:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:55:33.785511 :CB42B688:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:55:33.774
2025-09-09 21:55:33.785513 :CB42B689:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21068 pso_num=24 pso_serial#=69
2025-09-09 21:55:33.785644 :CB42B68A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21068 osp_idx=22 osp_ver=869208 osp_pg=0 (spawn #575818)
2025-09-09 21:55:33.785645 :CB42B68B: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-09 21:55:33.785645 :CB42B68C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=107 time=1840585080
2025-09-09 21:55:33.785646 :CB42B68D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:55:33.785646 :CB42B68E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:55:33.785646 :CB42B68F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:55:33.785646 :CB42B690:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:55:33.785647 :CB42B691:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:55:33.786132 :CB42B692: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-09 21:56:03.888285 :CB42C08A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:56:33.867821 :CB42CA42:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:56:33.857
2025-09-09 21:56:33.867823 :CB42CA43:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21576 pso_num=24 pso_serial#=70
2025-09-09 21:56:33.867959 :CB42CA44:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21576 osp_idx=22 osp_ver=869209 osp_pg=0 (spawn #575821)
2025-09-09 21:56:33.867960 :CB42CA45: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-09 21:56:33.867961 :CB42CA46:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=108 time=1840645158
2025-09-09 21:56:33.867961 :CB42CA47:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:56:33.867961 :CB42CA48:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:56:33.867962 :CB42CA49:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:56:33.867962 :CB42CA4A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:56:33.867962 :CB42CA4B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:56:33.868448 :CB42CA4C: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-09 21:57:03.988206 :CB42D514:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:57:33.954973 :CB42DEF9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:57:33.944
2025-09-09 21:57:33.954976 :CB42DEFA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20996 pso_num=24 pso_serial#=71
2025-09-09 21:57:33.955114 :CB42DEFB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20996 osp_idx=22 osp_ver=869210 osp_pg=0 (spawn #575823)
2025-09-09 21:57:33.955115 :CB42DEFC: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-09 21:57:33.955115 :CB42DEFD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=109 time=1840705252
2025-09-09 21:57:33.955117 :CB42DEFE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:57:33.955117 :CB42DEFF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:57:33.955117 :CB42DF00:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:57:33.955118 :CB42DF01:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:57:33.955118 :CB42DF02:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:57:33.955606 :CB42DF03: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-09 21:58:04.044652 :CB42E8E3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:58:34.063777 :CB42F31C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:58:34.062
2025-09-09 21:58:34.063778 :CB42F31D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21304 pso_num=24 pso_serial#=72
2025-09-09 21:58:34.063861 :CB42F31E:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21304 osp_idx=22 osp_ver=869211 osp_pg=0 (spawn #575826)
2025-09-09 21:58:34.063862 :CB42F31F: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-09 21:58:34.063862 :CB42F320:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=110 time=1840765362
2025-09-09 21:58:34.063862 :CB42F321:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:58:34.063862 :CB42F322:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:58:34.063863 :CB42F323:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:58:34.063863 :CB42F324:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:58:34.063863 :CB42F325:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:58:34.064166 :CB42F326: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-09 21:59:04.129921 :CB42FD01:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-09 21:59:34.198162 :CB4306ED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-09 21:59:34.187
2025-09-09 21:59:34.198164 :CB4306EE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23756 pso_num=24 pso_serial#=73
2025-09-09 21:59:34.198294 :CB4306EF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23756 osp_idx=22 osp_ver=869212 osp_pg=0 (spawn #575828)
2025-09-09 21:59:34.198295 :CB4306F0: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-09 21:59:34.198295 :CB4306F1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=111 time=1840825502
2025-09-09 21:59:34.198296 :CB4306F2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-09 21:59:34.198297 :CB4306F3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-09 21:59:34.198297 :CB4306F4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-09 21:59:34.198298 :CB4306F5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-09 21:59:34.198298 :CB4306F6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-09 21:59:34.198779 :CB4306F7: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-09 22:00:02.021153 :CB4311C4: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-09 22:00:02.021153*:CB4311D3:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000026B6B898 estart=1757426402 eid=16787689 ctx=0x000000077FD6A000 cwh=0x0000000026B60068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0
2025-09-09 22:00:02.021153*:CB4311D5:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000026B6B898 curCtx=0x0000000026B67AB8 pubTabIdxCnt=0
2025-09-09 22:00:02.021153*:CB4311D6:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000026B6B898
2025-09-09 22:00:02.021153*:CB4311D8:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000026B6B898
2025-09-09 22:00:02.025687 :CB4311D9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=22 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.025780 :CB4311DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=22 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.025780*:CB4311DD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000026B6B898 curCtx=0x0000000026B67AB8 xsc->flg4=65792 flags=2
2025-09-09 22:00:02.025780*:CB4311DE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000026B6B898
2025-09-09 22:00:02.026427 :CB4311EB: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-09 22:00:02.026458 :CB4311EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-09-09 22:00:02.031189 :CB43120A:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7
2025-09-09 22:00:02.031774 :CB43120D:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 30b5120000000000
2025-09-09 22:00:02.033575 :CB431234:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.033686 :CB431241:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.033697 :CB431248:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 8397264 1)
2025-09-09 22:00:02.033703 :CB431249:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.033733 :CB431255:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.034391 :CB431261:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (4 0 237026122)
2025-09-09 22:00:02.034396 :CB431262:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.034420 :CB431271:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.034429 :CB43127A:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (6 65560 1)
2025-09-09 22:00:02.034432 :CB43127B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.034461 :CB431287:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.034465 :CB431292:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCICKP [2] with args (1 2 65560)
2025-09-09 22:00:02.034469 :CB431294:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.034519 :CB4312B5:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.035195 :CB43136B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksq.h LINE:1877 ID:ksqrcl' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.035216 :CB431374:KFNU:kfn.c@2200:kfnPrepareASM(): kfnPrepareASM force=0 state_kfnsg=0x7
2025-09-09 22:00:02.036054 :CB43137B:db_trace:kcc.c@13684:kccwbp(): [10021:24:193:1] 0115000000000000 0115000000000000 31b5120000000000
2025-09-09 22:00:02.036453 :CB43137C:db_trace:ksb.c@6610:ksbcic_int(): [10254:24:193] KSBCIC: (USER) starting cic on opcode KSBCIBBS [20] with args (2 0 237026122)
2025-09-09 22:00:02.036458 :CB43137D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.036480 :CB431388:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-09-09 22:00:02.045448 :CB4313F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-09-09 22:00:02.046660 :CB4313F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name=   type=0
2025-09-09 22:00:02.047162 :CB4313FF: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-09 22:00:02.047212 :CB431402: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-09 22:00:02.047266 :CB43140F: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-09 22:00:02.146080 :CB431520: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-09 22:00:04.005781 :CB431935: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
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 24 (osid: 23756, J001)