Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823100033\orcl_j002_21780_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:35302M/63366M, Ph+PgF:40475M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 29
Windows thread id: 21780, image: ORACLE.EXE (J002)


*** 2025-08-23 10:00:33.321
*** SESSION ID:(233.32685) 2025-08-23 10:00:33.321
 

*** 2025-08-23 10:00:33.321
Process diagnostic dump for ORACLE.EXE (J002), OS id=21780,
pid: 29, proc_ser: 211, sid: 233, sess_ser: 32685 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 21780
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002)
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.011294 sec, exc=0.011294 sec, total=0.011294 sec
    wait times: max=0.500000 sec, heur=7.534101 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.500152 sec, exc=0.500152 sec, total=0.500152 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 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.500004 sec, exc=0.500004 sec, total=0.500004 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 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.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
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=11 seq_num=12 snap_id=1
    wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000009 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.500003 sec, exc=0.500003 sec, total=0.500003 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 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.499987 sec, exc=0.499987 sec, total=0.499987 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000011 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.514244 sec, exc=0.514244 sec, total=0.514244 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 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.499989 sec, exc=0.499989 sec, total=0.499989 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 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.500004 sec, exc=0.500004 sec, total=0.500004 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000008 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.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.000009 sec of elapsed time
Sampled Session History of session 233 serial 32685
---------------------------------------------------
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,                                              10:00:25 - 10:00:33]
    idle wait at each sample
  [session created at: 10:00:25]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-23 10:00:33.321
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 29 (osid: 21780, J002)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-23 01:19:17.545132 :C35C8C55:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=8240 osp_idx=27 osp_ver=16265 osp_pg=0 (spawn #516274)
2025-08-23 01:19:17.545133 :C35C8C56:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 01:19:17.545134 :C35C8C57:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=106 time=297659549
2025-08-23 01:19:17.545135 :C35C8C58:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 01:19:17.545135 :C35C8C59:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 01:19:17.545136 :C35C8C5A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 01:19:17.545136 :C35C8C5B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 01:19:17.545136 :C35C8C5C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 01:19:17.545661 :C35C8C5D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 01:19:17.545711 :C35C8C63:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 01:19:17.550006 :C35C8C8C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:19:17.550048 :C35C8C8D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:19:17.550302 :C35C8C8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:19:17.550317 :C35C8C8F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:19:17.550537 :C35C8C90:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:19:17.550558 :C35C8C91:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:19:17.550834 :C35C8C92:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:19:17.550847 :C35C8C93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:19:17.551056 :C35C8C94:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:19:17.551069 :C35C8C95:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:19:17.551277 :C35C8C96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:19:17.551290 :C35C8C97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:19:17.553599 :C35C8C98:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 01:49:21.276703 :C35EF521:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 01:49:21.264
2025-08-23 01:49:21.276705 :C35EF522:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21992 pso_num=29 pso_serial#=202
2025-08-23 01:49:21.276840 :C35EF523:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21992 osp_idx=27 osp_ver=16266 osp_pg=0 (spawn #516342)
2025-08-23 01:49:21.276840 :C35EF524:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 01:49:21.276841 :C35EF525:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=107 time=299463283
2025-08-23 01:49:21.276841 :C35EF526:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 01:49:21.276841 :C35EF527:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 01:49:21.276842 :C35EF528:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 01:49:21.276842 :C35EF529:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 01:49:21.276842 :C35EF52A:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 01:49:21.277329 :C35EF52B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 01:49:21.277381 :C35EF531:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 01:49:21.281572 :C35EF54A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:49:21.281617 :C35EF54B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:49:21.281947 :C35EF54C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:49:21.281963 :C35EF54D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:49:21.282178 :C35EF54E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:49:21.282193 :C35EF54F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:49:21.282406 :C35EF550:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:49:21.282419 :C35EF551:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:49:21.282629 :C35EF552:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:49:21.282641 :C35EF553:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:49:21.282851 :C35EF554:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:kelt.c:517
2025-08-23 01:49:21.282865 :C35EF555:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:kelt.c:517
2025-08-23 01:49:21.285083 :C35EF556:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:02.052529 :C372B575:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:02.046
2025-08-23 06:00:02.052531 :C372B576:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19356 pso_num=29 pso_serial#=203
2025-08-23 06:00:02.052675 :C372B577:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=19356 osp_idx=27 osp_ver=16267 osp_pg=0 (spawn #516925)
2025-08-23 06:00:02.052676 :C372B578:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 06:00:02.052676 :C372B579:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=108 time=314504065
2025-08-23 06:00:02.052677 :C372B57A:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 06:00:02.052677 :C372B57B:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 06:00:02.052678 :C372B57C:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 06:00:02.052678 :C372B57D:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 06:00:02.052678 :C372B57E:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 06:00:02.053205 :C372B57F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.058302 :C372B5A5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.058347 :C372B5A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:jslv.c:9203
2025-08-23 06:00:02.076264 :C372B763:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.076418 :C372B774:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] 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-08-23 06:00:02.076446 :C372B776:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.076606 :C372B788:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.076785 :C372B79B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.076963 :C372B7A3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.077110 :C372B7AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.077262 :C372B7B3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.077410 :C372B7BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.077568 :C372B7C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.077714 :C372B7CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.077853 :C372B7D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078079 :C372B7DB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078247 :C372B7E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078393 :C372B7EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078542 :C372B7F3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078688 :C372B7FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078834 :C372B803:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.078977 :C372B80B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.079118 :C372B813:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.079272 :C372B81B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.079410 :C372B823:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.079608 :C372B82B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.079902 :C372B833:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.080071 :C372B83B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:02.099378 :C372B843:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.552462*:C372BACE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000030183AD0 estart=1755900002 eid=21624265 ctx=0x0000000777968158 cwh=0x00000000301B09C8 cpu=0 buffg=2 interb=0 r=0 rb=0 w=0 wb=0
2025-08-23 06:00:03.552462*:C372BAD0:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000030183AD0 curCtx=0x0000000059237918 pubTabIdxCnt=0
2025-08-23 06:00:03.552462*:C372BAD1:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000030183AD0
2025-08-23 06:00:03.552462*:C372BAD4:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000030183AD0
2025-08-23 06:00:03.552462*:C372BAD6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x00000000301FB6F0 estart=1755900001 eid=16777536 ctx=0x000000077B54EAC8 cwh=0x00000000301B0518 cpu=1265625 buffg=70269 interb=196608 r=24 rb=196608 w=0 wb=0
2025-08-23 06:00:03.552462*:C372BAD8:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x00000000301FB6F0 curCtx=0x00000000591187D0 pubTabIdxCnt=0
2025-08-23 06:00:03.552462*:C372BAD9:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x00000000301FB6F0
2025-08-23 06:00:03.552462*:C372BADA:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x00000000301FB6F0
2025-08-23 06:00:03.552462*:C372BADD:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000030183AD0 curCtx=0x0000000059237918 xsc->flg4=65792 flags=1
2025-08-23 06:00:03.552462*:C372BADE:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000030183AD0
2025-08-23 06:00:03.739971*:C372BBC6:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x00000000301FB6F0 curCtx=0x00000000591187D0 xsc->flg4=65792 flags=2
2025-08-23 06:00:03.739971*:C372BBC7:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x00000000301FB6F0
2025-08-23 06:00:03.744418 :C372BBC8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.764832 :C372BBEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.766785 :C372BBF2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpus:psdpgi.c:2235
2025-08-23 06:00:03.766978 :C372BBF3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:psdpgi.c:2235
2025-08-23 06:00:03.767808 :C372BBF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.767897 :C372BBF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.768743 :C372BC09:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.770213 :C372BC11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.770503 :C372BC19:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:03.833721*:SQL_Analyze:qksan.c@3402:qksanAnalyzeSql(begin): qksanAnalyzeSql(enter): goal=15 options=16778048 pgadep=0 binds#=0 byPos=0 timeLim=0 timeFired=0
2025-08-23 06:00:03.833721*:SQL_Analyze:qksan.c@936:qksanBindSql(begin): binding for SQL analyze
2025-08-23 06:00:03.833721*:SQL_Analyze:qksan.c@1344:qksanBindSql(end): Done binding: status=0 ret=0 bind#=0 byPos=1 wasBoundP=0x0000000000000000 bindIndP=0x0000000000000000
2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@6338:qksanAvgExecStats(): The number of total executions: 1 and execution count: 0
2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@4776:qksanPostAnalyzeDump(begin):   qksanPostAnalyzeDump(): Dumping analyze context after compilation  qksanStatus=SUCCESS ecode=0
2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@4800:qksanPostAnalyzeDump(end): 2025-08-23 06:00:03.880599*:SQL_Analyze:qksan.c@3690:qksanAnalyzeSql(end): exit: ecode=0 status=0
2025-08-23 06:00:03.937707 :C372BC66:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:05.864602 :C372BD5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:05.864617 :C372BD5B:db_trace:ktur.c@3079:ktuabt(): [10444:29:233] ABORT TRANSACTION - xid: 0x0008.016.0000d26b
2025-08-23 06:00:08.395688 :C372BED2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:08.397176 :C372BEDA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:08.407467 :C372BEE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:08.436025 :C372BEEA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:09.633873 :C372C009:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:29:233] atxpop:jslv.c:9203
2025-08-23 06:00:09.644431 :C372C00A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:0] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:09.646069 :C372C012:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:11.066905 :C372C0F6:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:11.053
2025-08-23 06:00:11.066907 :C372C0F7:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=11176 pso_num=29 pso_serial#=204
2025-08-23 06:00:11.067089 :C372C0F8:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=11176 (legacy spawn)
2025-08-23 06:00:11.075508 :C372C0F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:11.075697 :C372C0FE:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 06:00:11.076469 :C372C103:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:11.085310 :C372C111:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:11.085
2025-08-23 06:00:11.085312 :C372C112:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20640 pso_num=29 pso_serial#=205
2025-08-23 06:00:11.085449 :C372C113:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20640 (legacy spawn)
2025-08-23 06:00:11.091911 :C372C114:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:11.092082 :C372C11C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 06:00:11.092878 :C372C11D:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:26.569983 :C372C714:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:26.555
2025-08-23 06:00:26.569985 :C372C715:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=19000 pso_num=29 pso_serial#=206
2025-08-23 06:00:26.570128 :C372C716:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=19000 (legacy spawn)
2025-08-23 06:00:26.576575 :C372C717:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:26.576759 :C372C71C:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 06:00:26.577603 :C372C721:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:26.586286 :C372C724:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:26.571
2025-08-23 06:00:26.586288 :C372C725:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=22328 pso_num=29 pso_serial#=207
2025-08-23 06:00:26.586424 :C372C726:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=22328 (legacy spawn)
2025-08-23 06:00:26.592985 :C372C727:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:26.593147 :C372C730:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 06:00:26.593962 :C372C731:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:29.766439 :C372C834:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:29.753
2025-08-23 06:00:29.766441 :C372C835:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21784 pso_num=29 pso_serial#=208
2025-08-23 06:00:29.766573 :C372C836:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=21784 (legacy spawn)
2025-08-23 06:00:29.773162 :C372C84A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:29.773338 :C372C853:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 06:00:29.774162 :C372C854:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 06:00:29.782901 :C372C857:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 06:00:29.769
2025-08-23 06:00:29.782903 :C372C858:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=17880 pso_num=29 pso_serial#=209
2025-08-23 06:00:29.783046 :C372C859:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=17880 (legacy spawn)
2025-08-23 06:00:29.789621 :C372C85A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 06:00:29.789786 :C372C863:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 06:00:29.790608 :C372C864:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 08:11:26.870128 :C37D672F:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 08:11:26.861
2025-08-23 08:11:26.870130 :C37D6730:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=20888 pso_num=29 pso_serial#=210
2025-08-23 08:11:26.870268 :C37D6731:db_trace:kso.c@4070:ksonfy(): [10420:29:0] kso: new process: pid=20888 (legacy spawn)
2025-08-23 08:11:26.876730 :C37D673A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-23 08:11:26.876903 :C37D6743:db_trace:ksl2.c@12699:ksliwat(): [10005:29:233] 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-08-23 08:11:26.877796 :C37D6744:db_trace:kst.c@698:kstpsodel(): [10280:29:0] kst: process state object about to be deleted
2025-08-23 10:00:25.796577 :C385FF93:db_trace:kst.c@673:kstipg(): [10280:29:0] kst: process state object created on 08-23 10:00:25.796
2025-08-23 10:00:25.796578 :C385FF94:db_trace:kst.c@679:kstipg(): [10280:29:0] kst: process info: ospid=21780 pso_num=29 pso_serial#=211
2025-08-23 10:00:25.796712 :C385FF95:db_trace:kso.c@4093:ksonfy(): [10420:29:0] kso: new process: pid=21780 osp_idx=27 osp_ver=16275 osp_pg=0 (spawn #517492)
2025-08-23 10:00:25.796713 :C385FF96:db_trace:kso.c@4099:ksonfy(): [10420:29:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-23 10:00:25.796714 :C385FF97:db_trace:kso.c@4103:ksonfy(): [10420:29:0] kso: new process: pso_index=29 reservation=109 time=328927799
2025-08-23 10:00:25.796714 :C385FF98:db_trace:kso.c@4109:ksonfy(): [10420:29:0] kso: spawn diagnostics: queue time=0 secs
2025-08-23 10:00:25.796715 :C385FF99:db_trace:kso.c@4111:ksonfy(): [10420:29:0] kso: spawn diagnostics: fork time=0 secs
2025-08-23 10:00:25.796715 :C385FF9A:db_trace:kso.c@4113:ksonfy(): [10420:29:0] kso: spawn diagnostics: exec time=0 secs
2025-08-23 10:00:25.796715 :C385FF9B:db_trace:kso.c@4115:ksonfy(): [10420:29:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-23 10:00:25.796716 :C385FF9C:db_trace:kso.c@4117:ksonfy(): [10420:29:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-23 10:00:25.797224 :C385FFA6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:29:233] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 29 (osid: 21780, J002)