Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250706060009\orcl_j005_15240_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:37125M/63366M, Ph+PgF:43097M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 31
Windows thread id: 15240, image: ORACLE.EXE (J005)


*** 2025-07-06 06:00:09.377
*** SESSION ID:(249.1285) 2025-07-06 06:00:09.377
 

*** 2025-07-06 06:00:09.377
Process diagnostic dump for ORACLE.EXE (J005), OS id=15240,
pid: 31, proc_ser: 115, sid: 249, sess_ser: 1285 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15240
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J005)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=15 seq_num=16 snap_id=1
    wait times: snap=0.239235 sec, exc=0.239235 sec, total=0.239235 sec
    wait times: max=0.500000 sec, heur=7.325754 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=14 seq_num=15 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
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=13 seq_num=14 snap_id=1
    wait times: snap=0.501094 sec, exc=0.501094 sec, total=0.501094 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=12 seq_num=13 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
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 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.000010 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=10 seq_num=11 snap_id=1
    wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=0.500174 sec, exc=0.500174 sec, total=0.500174 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=0.500130 sec, exc=0.500130 sec, total=0.500130 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=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.000010 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 sec of elapsed time
Sampled Session History of session 249 serial 1285
---------------------------------------------------
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
---------------------------------------------------
  [8 samples,                                              06:00:02 - 06:00:09]
    idle wait at each sample
  [session created at: 06:00:02]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-07-06 06:00:09.377
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 31 (osid: 15240, J005)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-07-02 22:00:02.083661 :ACB2931E:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-02 22:00:02.084415 :ACB2931F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-02 22:00:32.174891 :ACB2A768:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-02 23:59:18.404521 :ACBC6F09:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-02 23:59:18.391
2025-07-02 23:59:18.404523 :ACBC6F0A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13548 pso_num=31 pso_serial#=102
2025-07-02 23:59:18.404675 :ACBC6F0B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13548 (legacy spawn)
2025-07-02 23:59:18.420106 :ACBC6F0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-02 23:59:51.721890 :ACBC7B5C:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-02 23:59:55.747826 :ACBC7C74:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-02 23:59:55.745
2025-07-02 23:59:55.747828 :ACBC7C75:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=11032 pso_num=31 pso_serial#=103
2025-07-02 23:59:55.747972 :ACBC7C76:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=11032 (legacy spawn)
2025-07-02 23:59:55.762657 :ACBC7C77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-03 00:00:28.807596 :ACBC89B4:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-03 01:00:19.193361 :ACC13BBC:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-03 01:00:19.191
2025-07-03 01:00:19.193362 :ACC13BBD:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15208 pso_num=31 pso_serial#=104
2025-07-03 01:00:19.193499 :ACC13BBE:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=15208 osp_idx=29 osp_ver=613 osp_pg=0 (spawn #346254)
2025-07-03 01:00:19.193500 :ACC13BBF:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-03 01:00:19.193501 :ACC13BC0:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=190 time=185195079
2025-07-03 01:00:19.193501 :ACC13BC1:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-07-03 01:00:19.193501 :ACC13BC2:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-07-03 01:00:19.193502 :ACC13BC3:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-07-03 01:00:19.193502 :ACC13BC4:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-03 01:00:19.193502 :ACC13BC5:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-03 01:00:19.194005 :ACC13BC6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-03 01:00:19.194051 :ACC13BCC:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-07-03 01:00:19.202365 :ACC13BDE:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-03 22:00:02.060195 :AD227BA2:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-03 22:00:02.051
2025-07-03 22:00:02.060196 :AD227BA3:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17692 pso_num=31 pso_serial#=105
2025-07-03 22:00:02.060334 :AD227BA4:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=17692 osp_idx=29 osp_ver=614 osp_pg=0 (spawn #349164)
2025-07-03 22:00:02.060335 :AD227BA5:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-03 22:00:02.060335 :AD227BA6:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=191 time=260777954
2025-07-03 22:00:02.060335 :AD227BA7:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-07-03 22:00:02.060336 :AD227BA8:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-07-03 22:00:02.060337 :AD227BA9:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-07-03 22:00:02.060337 :AD227BAA:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-03 22:00:02.060337 :AD227BAB:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-03 22:00:02.061313 :AD227BAC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-03 22:00:32.154160 :AD228D62:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-03 23:59:10.056487 :AD2C210A:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-03 23:59:10.050
2025-07-03 23:59:10.056489 :AD2C210B:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=14688 pso_num=31 pso_serial#=106
2025-07-03 23:59:10.056627 :AD2C210C:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=14688 (legacy spawn)
2025-07-03 23:59:10.072034 :AD2C210D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-03 23:59:43.558018 :AD2C2D1D:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-03 23:59:43.725831 :AD2C2D20:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-03 23:59:43.711
2025-07-03 23:59:43.725834 :AD2C2D21:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9536 pso_num=31 pso_serial#=107
2025-07-03 23:59:43.725979 :AD2C2D22:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9536 (legacy spawn)
2025-07-03 23:59:43.740200 :AD2C2D23:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 00:00:16.776341 :AD2C3919:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-04 22:00:02.042004 :AD937078:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-04 22:00:02.040
2025-07-04 22:00:02.042005 :AD937079:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18288 pso_num=31 pso_serial#=108
2025-07-04 22:00:02.042127 :AD93707A:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18288 osp_idx=29 osp_ver=617 osp_pg=0 (spawn #352488)
2025-07-04 22:00:02.042127 :AD93707B:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-04 22:00:02.042127 :AD93707C:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=192 time=347177923
2025-07-04 22:00:02.042128 :AD93707D:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-07-04 22:00:02.042129 :AD93707E:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-07-04 22:00:02.042130 :AD93707F:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-07-04 22:00:02.042130 :AD937080:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-04 22:00:02.042131 :AD937081:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-04 22:00:02.042683 :AD93708A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-04 22:00:02.047490 :AD9370A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:02.047516 :AD9370AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:jslv.c:9203
2025-07-04 22:00:02.052876 :AD9370CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:02.258981 :AD9377B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:02.454960 :AD9377EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:02.591173 :AD93781A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:02.712188 :AD93783F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.713349 :AD937AFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.714363 :AD937B05:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.716138 :AD937B0D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.720142 :AD937B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.723299 :AD937B1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.724886 :AD937B25:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.726337 :AD937B2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.727820 :AD937B37:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.748217 :AD937B3F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.748965 :AD937B47:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.749544 :AD937B4F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.750056 :AD937B57:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.752631 :AD937B5F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.753653 :AD937B67:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.754475 :AD937B68:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.755265 :AD937B69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.755688 :AD937B6A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.755695 :AD937B6B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.756283 :AD937B6C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.756789 :AD937B6D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.757169 :AD937B6E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.811203 :AD937B71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.812246 :AD937B79:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.848177*:AD937BC8:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000003207E9D8 estart=1751637602 eid=16778307 ctx=0x000000077B458BE8 cwh=0x00000000320144E8 cpu=31250 buffg=1436 interb=98304 r=12 rb=98304 w=0 wb=0
2025-07-04 22:00:03.848177*:AD937BC9:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000003207E9D8 curCtx=0x00000000325DF550 pubTabIdxCnt=0
2025-07-04 22:00:03.848177*:AD937BCA:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000003207E9D8
2025-07-04 22:00:03.848177*:AD937BCB:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000003207E9D8
2025-07-04 22:00:03.848177*:AD937BCC:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000003207E9D8 curCtx=0x00000000325DF550 xsc->flg4=65792 flags=2
2025-07-04 22:00:03.848177*:AD937BCD:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000003207E9D8
2025-07-04 22:00:03.852882 :AD937BCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.852930 :AD937BD1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.853845 :AD937BD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.854794 :AD937BDF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.855301 :AD937BE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.876469 :AD937BE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.876522 :AD937BEB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.877489 :AD937BF1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.878382 :AD937BF9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.878897 :AD937BFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.900202 :AD937C02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.900254 :AD937C05:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.901173 :AD937C0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.913063 :AD937C13:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.913589 :AD937C14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.932765 :AD937C1E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.932833 :AD937C21:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.933977 :AD937C27:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.935060 :AD937C2F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.935709 :AD937C30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.968483 :AD937C44:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.968538 :AD937C47:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.969511 :AD937C4D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.970408 :AD937C55:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpus:psdpgi.c:2235
2025-07-04 22:00:03.970919 :AD937C56:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.993379 :AD937C66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.993431 :AD937C69:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:psdpgi.c:2235
2025-07-04 22:00:03.994402 :AD937C6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.994877 :AD937C77:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.995244 :AD937C7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.995427 :AD937C87:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.995566 :AD937C8F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:03.995742 :AD937C97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:31:249] atxpop:jslv.c:9203
2025-07-04 22:00:04.000004 :AD937C98:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 22:00:04.000090 :AD937C9B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name=   type=0
2025-07-04 22:00:04.000182 :AD937CA8:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-04 22:00:34.079864 :AD9388CD:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-04 23:59:09.970606 :AD9D3000:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-04 23:59:09.966
2025-07-04 23:59:09.970607 :AD9D3001:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=13260 pso_num=31 pso_serial#=109
2025-07-04 23:59:09.970742 :AD9D3002:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=13260 (legacy spawn)
2025-07-04 23:59:09.984707 :AD9D3003:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-04 23:59:43.225737 :AD9D3BC9:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-04 23:59:46.809882 :AD9D3D0D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-04 23:59:46.809
2025-07-04 23:59:46.809884 :AD9D3D0E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=7020 pso_num=31 pso_serial#=110
2025-07-04 23:59:46.810039 :AD9D3D0F:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=7020 (legacy spawn)
2025-07-04 23:59:46.824333 :AD9D3D1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 00:00:20.074130 :AD9D49A6:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-05 06:00:01.226247 :ADB9696D:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 06:00:01.221
2025-07-05 06:00:01.226248 :ADB9696E:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=18312 pso_num=31 pso_serial#=111
2025-07-05 06:00:01.226370 :ADB9696F:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=18312 osp_idx=29 osp_ver=620 osp_pg=0 (spawn #353611)
2025-07-05 06:00:01.226371 :ADB96970:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-05 06:00:01.226371 :ADB96971:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=193 time=375977111
2025-07-05 06:00:01.226371 :ADB96972:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-07-05 06:00:01.226372 :ADB96973:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-07-05 06:00:01.226372 :ADB96974:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-07-05 06:00:01.226373 :ADB96975:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-05 06:00:01.226373 :ADB96976:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-05 06:00:01.226965 :ADB9697A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-05 06:00:01.321167 :ADB96C6C:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05 06:00:31.407269 :ADB97F62:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-05 23:59:11.038067 :AE11872C:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 23:59:11.029
2025-07-05 23:59:11.038069 :AE11872D:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=17888 pso_num=31 pso_serial#=112
2025-07-05 23:59:11.038217 :AE11872E:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=17888 (legacy spawn)
2025-07-05 23:59:11.053346 :AE11872F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 23:59:44.214952 :AE1193E2:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-05 23:59:53.128293 :AE119749:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 23:59:53.120
2025-07-05 23:59:53.128296 :AE11974A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15364 pso_num=31 pso_serial#=113
2025-07-05 23:59:53.128440 :AE11974B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=15364 (legacy spawn)
2025-07-05 23:59:53.134970 :AE11974C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-05 23:59:53.135179 :AE119751:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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-05 23:59:53.135988 :AE119756:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-05 23:59:56.040082 :AE119879:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-05 23:59:56.040
2025-07-05 23:59:56.040084 :AE11987A:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=9160 pso_num=31 pso_serial#=114
2025-07-05 23:59:56.040219 :AE11987B:db_trace:kso.c@4070:ksonfy(): [10420:31:0] kso: new process: pid=9160 (legacy spawn)
2025-07-05 23:59:56.055002 :AE11987C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-07-06 00:00:29.396570 :AE11A629:db_trace:kst.c@698:kstpsodel(): [10280:31:0] kst: process state object about to be deleted
2025-07-06 06:00:02.055023 :AE2E882B:db_trace:kst.c@673:kstipg(): [10280:31:0] kst: process state object created on 07-06 06:00:02.047
2025-07-06 06:00:02.055024 :AE2E882C:db_trace:kst.c@679:kstipg(): [10280:31:0] kst: process info: ospid=15240 pso_num=31 pso_serial#=115
2025-07-06 06:00:02.055173 :AE2E882D:db_trace:kso.c@4093:ksonfy(): [10420:31:0] kso: new process: pid=15240 osp_idx=29 osp_ver=624 osp_pg=0 (spawn #356947)
2025-07-06 06:00:02.055173 :AE2E882E:db_trace:kso.c@4099:ksonfy(): [10420:31:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-07-06 06:00:02.055174 :AE2E882F:db_trace:kso.c@4103:ksonfy(): [10420:31:0] kso: new process: pso_index=31 reservation=194 time=462377939
2025-07-06 06:00:02.055175 :AE2E8830:db_trace:kso.c@4109:ksonfy(): [10420:31:0] kso: spawn diagnostics: queue time=0 secs
2025-07-06 06:00:02.055176 :AE2E8831:db_trace:kso.c@4111:ksonfy(): [10420:31:0] kso: spawn diagnostics: fork time=0 secs
2025-07-06 06:00:02.055177 :AE2E8832:db_trace:kso.c@4113:ksonfy(): [10420:31:0] kso: spawn diagnostics: exec time=0 secs
2025-07-06 06:00:02.055178 :AE2E8833:db_trace:kso.c@4115:ksonfy(): [10420:31:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-07-06 06:00:02.055178 :AE2E8834:db_trace:kso.c@4117:ksonfy(): [10420:31:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-07-06 06:00:02.055835 :AE2E8835:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:31:249] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-07-06 06:00:02.153984 :AE2E8AE5:db_trace:ksl2.c@12699:ksliwat(): [10005:31:249] 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 31 (osid: 15240, J005)