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


*** 2025-07-13 06:00:06.296
*** SESSION ID:(193.54767) 2025-07-13 06:00:06.296
 

*** 2025-07-13 06:00:06.296
Process diagnostic dump for ORACLE.EXE (J001), OS id=15576,
pid: 24, proc_ser: 104, sid: 193, sess_ser: 54767 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15576
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.283246 sec, exc=0.283246 sec, total=0.283246 sec
    wait times: max=0.500000 sec, heur=4.298922 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=7 seq_num=8 snap_id=1
    wait times: snap=0.510099 sec, exc=0.510099 sec, total=0.510099 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
 1: 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.000008 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 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.000006 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=4 seq_num=5 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=3 seq_num=4 snap_id=1
    wait times: snap=0.510106 sec, exc=0.510106 sec, total=0.510106 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000005 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=0.500010 sec, exc=0.500010 sec, total=0.500010 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=0.505090 sec, exc=0.505090 sec, total=0.505090 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000006 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.490320 sec, exc=0.490320 sec, total=0.490320 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 54767
---------------------------------------------------
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
---------------------------------------------------
  [5 samples,                                              06:00:01 - 06:00:06]
    idle wait at each sample
  [session created at: 06:00:01]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-13 06:00:06.296
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 15576, J001)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-13 05:47:15.651846 :B14E7A4D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:47:15.641
2025-07-13 05:47:15.651848 :B14E7A4E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18052 pso_num=24 pso_serial#=92
2025-07-13 05:47:15.651982 :B14E7A4F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18052 osp_idx=22 osp_ver=563652 osp_pg=0 (spawn #380243)
2025-07-13 05:47:15.651982 :B14E7A50:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:47:15.651983 :B14E7A51:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=38 time=1066411533
2025-07-13 05:47:15.651983 :B14E7A52:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:47:15.651984 :B14E7A53:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:47:15.651984 :B14E7A54:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:47:15.651984 :B14E7A55:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:47:15.651985 :B14E7A56:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:47:15.652524 :B14E7A57: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-07-13 05:47:45.744123 :B14E845C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:48:15.761002 :B14E8EF1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:48:15.750
2025-07-13 05:48:15.761004 :B14E8EF2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18360 pso_num=24 pso_serial#=93
2025-07-13 05:48:15.761133 :B14E8EF3:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18360 osp_idx=22 osp_ver=563653 osp_pg=0 (spawn #380245)
2025-07-13 05:48:15.761134 :B14E8EF4:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:48:15.761135 :B14E8EF5:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=39 time=1066471642
2025-07-13 05:48:15.761135 :B14E8EF6:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:48:15.761135 :B14E8EF7:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:48:15.761136 :B14E8EF8:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:48:15.761136 :B14E8EF9:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:48:15.761136 :B14E8EFA:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:48:15.761593 :B14E8EFB: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-07-13 05:48:45.825593 :B14E98C8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:49:15.863398 :B14EA2C0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:49:15.852
2025-07-13 05:49:15.863400 :B14EA2C1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11176 pso_num=24 pso_serial#=94
2025-07-13 05:49:15.863534 :B14EA2C2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11176 osp_idx=22 osp_ver=563654 osp_pg=0 (spawn #380247)
2025-07-13 05:49:15.863535 :B14EA2C3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:49:15.863536 :B14EA2C4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=40 time=1066531751
2025-07-13 05:49:15.863536 :B14EA2C5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:49:15.863536 :B14EA2C6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:49:15.863537 :B14EA2C7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:49:15.863537 :B14EA2C8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:49:15.863537 :B14EA2C9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:49:15.864093 :B14EA2CA: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-07-13 05:49:40.887282 :B14EAB23: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-07-13 05:50:11.001906 :B14EB4D1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:50:15.995311 :B14EB6D8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:50:15.983
2025-07-13 05:50:15.995314 :B14EB6D9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12296 pso_num=24 pso_serial#=95
2025-07-13 05:50:15.995429 :B14EB6DA:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12296 osp_idx=22 osp_ver=563655 osp_pg=0 (spawn #380250)
2025-07-13 05:50:15.995430 :B14EB6DB:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:50:15.995430 :B14EB6DC:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=41 time=1066591876
2025-07-13 05:50:15.995431 :B14EB6DD:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:50:15.995431 :B14EB6DE:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:50:15.995432 :B14EB6DF:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:50:15.995432 :B14EB6E0:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:50:15.995432 :B14EB6E1:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:50:15.995951 :B14EB6E2: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-07-13 05:50:46.070913 :B14EC082:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:51:16.080756 :B14ECA32:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:51:16.080
2025-07-13 05:51:16.080758 :B14ECA33:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17400 pso_num=24 pso_serial#=96
2025-07-13 05:51:16.080884 :B14ECA34:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17400 osp_idx=22 osp_ver=563656 osp_pg=0 (spawn #380252)
2025-07-13 05:51:16.080885 :B14ECA35:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:51:16.080886 :B14ECA36:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=42 time=1066651970
2025-07-13 05:51:16.080886 :B14ECA37:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:51:16.080887 :B14ECA38:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:51:16.080887 :B14ECA39:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:51:16.080887 :B14ECA3A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:51:16.080887 :B14ECA3B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:51:16.081406 :B14ECA3C: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-07-13 05:51:46.155056 :B14ED459:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:52:16.173910 :B14EDE43:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:52:16.162
2025-07-13 05:52:16.173912 :B14EDE44:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11212 pso_num=24 pso_serial#=97
2025-07-13 05:52:16.174053 :B14EDE45:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11212 osp_idx=22 osp_ver=563657 osp_pg=0 (spawn #380254)
2025-07-13 05:52:16.174053 :B14EDE46:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:52:16.174054 :B14EDE47:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=43 time=1066712064
2025-07-13 05:52:16.174054 :B14EDE48:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:52:16.174055 :B14EDE49:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:52:16.174055 :B14EDE4A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:52:16.174055 :B14EDE4B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:52:16.174056 :B14EDE4C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:52:16.174555 :B14EDE4D: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-07-13 05:52:46.270021 :B14EE85F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:53:16.260905 :B14EF243:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:53:16.249
2025-07-13 05:53:16.260907 :B14EF244:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16504 pso_num=24 pso_serial#=98
2025-07-13 05:53:16.261046 :B14EF245:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16504 osp_idx=22 osp_ver=563658 osp_pg=0 (spawn #380257)
2025-07-13 05:53:16.261047 :B14EF246:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:53:16.261047 :B14EF247:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=44 time=1066772142
2025-07-13 05:53:16.261048 :B14EF248:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:53:16.261048 :B14EF249:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:53:16.261049 :B14EF24A:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:53:16.261049 :B14EF24B:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:53:16.261050 :B14EF24C:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:53:16.261539 :B14EF24D: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-07-13 05:53:46.337772 :B14EFC22:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:54:16.354789 :B14F05D3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:54:16.343
2025-07-13 05:54:16.354791 :B14F05D4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3264 pso_num=24 pso_serial#=99
2025-07-13 05:54:16.354910 :B14F05D5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3264 osp_idx=22 osp_ver=563659 osp_pg=0 (spawn #380259)
2025-07-13 05:54:16.354910 :B14F05D6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:54:16.354911 :B14F05D7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=45 time=1066832236
2025-07-13 05:54:16.354911 :B14F05D8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:54:16.354912 :B14F05D9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:54:16.354912 :B14F05DA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:54:16.354913 :B14F05DB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:54:16.354913 :B14F05DC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:54:16.355447 :B14F05DD: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-07-13 05:54:46.434546 :B14F0FA3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:55:16.443937 :B14F194A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:55:16.432
2025-07-13 05:55:16.443938 :B14F194B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16132 pso_num=24 pso_serial#=100
2025-07-13 05:55:16.444072 :B14F194C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16132 osp_idx=22 osp_ver=563660 osp_pg=0 (spawn #380261)
2025-07-13 05:55:16.444073 :B14F194D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:55:16.444073 :B14F194E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=46 time=1066892329
2025-07-13 05:55:16.444074 :B14F194F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:55:16.444074 :B14F1950:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:55:16.444074 :B14F1951:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:55:16.444074 :B14F1952:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:55:16.444075 :B14F1953:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:55:16.444651 :B14F1954: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-07-13 05:55:46.492275 :B14F2313:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:56:16.520531 :B14F2CE2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:56:16.509
2025-07-13 05:56:16.520533 :B14F2CE3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3088 pso_num=24 pso_serial#=101
2025-07-13 05:56:16.520663 :B14F2CE4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=3088 osp_idx=22 osp_ver=563661 osp_pg=0 (spawn #380263)
2025-07-13 05:56:16.520663 :B14F2CE5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:56:16.520664 :B14F2CE6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=47 time=1066952408
2025-07-13 05:56:16.520664 :B14F2CE7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:56:16.520665 :B14F2CE8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:56:16.520665 :B14F2CE9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:56:16.520665 :B14F2CEA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:56:16.520666 :B14F2CEB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:56:16.521187 :B14F2CEC: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-07-13 05:56:46.586287 :B14F3781:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:57:16.601734 :B14F4116:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:57:16.590
2025-07-13 05:57:16.601736 :B14F4117:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11720 pso_num=24 pso_serial#=102
2025-07-13 05:57:16.601852 :B14F4118:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11720 osp_idx=22 osp_ver=563662 osp_pg=0 (spawn #380266)
2025-07-13 05:57:16.601853 :B14F4119:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:57:16.601854 :B14F411A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=48 time=1067012486
2025-07-13 05:57:16.601854 :B14F411B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:57:16.601854 :B14F411C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:57:16.601855 :B14F411D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:57:16.601855 :B14F411E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:57:16.601855 :B14F411F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:57:16.602385 :B14F4120: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-07-13 05:57:46.685729 :B14F4B12:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:58:16.693920 :B14F54F4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:58:16.682
2025-07-13 05:58:16.693924 :B14F54F5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12424 pso_num=24 pso_serial#=103
2025-07-13 05:58:16.694059 :B14F54F6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12424 osp_idx=22 osp_ver=563663 osp_pg=0 (spawn #380268)
2025-07-13 05:58:16.694059 :B14F54F7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:58:16.694060 :B14F54F8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=49 time=1067072579
2025-07-13 05:58:16.694060 :B14F54F9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:58:16.694060 :B14F54FA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:58:16.694061 :B14F54FB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:58:16.694061 :B14F54FC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:58:16.694061 :B14F54FD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:58:16.694556 :B14F54FE: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-07-13 05:58:46.778046 :B14F5EC4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-07-13 05:59:16.802412 :B14F68AF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-13 05:59:16.791
2025-07-13 05:59:16.802414 :B14F68B0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15576 pso_num=24 pso_serial#=104
2025-07-13 05:59:16.802539 :B14F68B1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15576 osp_idx=22 osp_ver=563664 osp_pg=0 (spawn #380270)
2025-07-13 05:59:16.802540 :B14F68B2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-13 05:59:16.802540 :B14F68B3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=50 time=1067132689
2025-07-13 05:59:16.802541 :B14F68B4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-07-13 05:59:16.802541 :B14F68B5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-07-13 05:59:16.802542 :B14F68B6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-07-13 05:59:16.802542 :B14F68B7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-13 05:59:16.802542 :B14F68B8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-13 05:59:16.803024 :B14F68B9: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-07-13 05:59:41.828859 :B14F70CB: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-07-13 06:00:01.864468 :B14F78FE: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-07-13 06:00:01.864468*:B14F7926:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1752357600 eid=16784082 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0
2025-07-13 06:00:01.864468*:B14F7927:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0
2025-07-13 06:00:01.864468*:B14F7928:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718
2025-07-13 06:00:01.864468*:B14F7929:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718
2025-07-13 06:00:01.864468*:B14F793F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2
2025-07-13 06:00:01.864468*:B14F7940:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718
2025-07-13 06:00:01.871390 :B14F7941: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-07-13 06:00:01.871448 :B14F7944:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-07-13 06:00:01.883232 :B14F79D1: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-07-13 06:00:01.888724 :B14F7B42: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-07-13 06:00:01.889475 :B14F7B4A: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-07-13 06:00:01.895466 :B14F7B5B: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-07-13 06:00:01.895630 :B14F7B6A: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-07-13 06:00:01.895971 :B14F7B73: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-07-13 06:00:01.895982 :B14F7B75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-07-13 06:00:01.896020 :B14F7B77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=27 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name=   type=0
2025-07-13 06:00:01.896032 :B14F7B79: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-07-13 06:00:01.896143 :B14F7B7B: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-07-13 06:00:01.896589 :B14F7B7C: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-07-13 06:00:01.896811 :B14F7B8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-07-13 06:00:01.897265 :B14F7B9B: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-07-13 06:00:01.898462 :B14F7BAB: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-07-13 06:00:01.898495 :B14F7BAE: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-07-13 06:00:01.898590 :B14F7BBF: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-07-13 06:00:01.989388 :B14F7C97: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-07-13 06:00:01.991516 :B14F7C9C: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-07-13 06:00:01.991554 :B14F7C9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203
2025-07-13 06:00:01.994021 :B14F7CA5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203
2025-07-13 06:00:01.997380 :B14F7CA6: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-07-13 06:00:01.997465 :B14F7CA9: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-07-13 06:00:01.997548 :B14F7CB6: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: 15576, J001)