Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802180621\orcl_w000_19472_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:35809M/63366M, Ph+PgF:41406M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 22
Windows thread id: 19472, image: ORACLE.EXE (W000)


*** 2025-08-02 18:06:21.525
*** SESSION ID:(177.19635) 2025-08-02 18:06:21.525
 

*** 2025-08-02 18:06:21.525
Process diagnostic dump for ORACLE.EXE (W000), OS id=19472,
pid: 22, proc_ser: 128, sid: 177, sess_ser: 19635 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=59 seq_num=60 snap_id=1
    wait times: snap=2.395622 sec, exc=2.395622 sec, total=2.395622 sec
    wait times: max=5.000000 sec, heur=4 min 52 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.000026 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=58 seq_num=59 snap_id=1
    wait times: snap=5.014726 sec, exc=5.014726 sec, total=5.014726 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000026 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=57 seq_num=58 snap_id=1
    wait times: snap=5.010147 sec, exc=5.010147 sec, total=5.010147 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000028 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=56 seq_num=57 snap_id=1
    wait times: snap=5.014716 sec, exc=5.014716 sec, total=5.014716 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000036 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=55 seq_num=56 snap_id=1
    wait times: snap=5.015237 sec, exc=5.015237 sec, total=5.015237 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=54 seq_num=55 snap_id=1
    wait times: snap=5.003389 sec, exc=5.003389 sec, total=5.003389 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=53 seq_num=54 snap_id=1
    wait times: snap=5.006061 sec, exc=5.006061 sec, total=5.006061 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000042 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=52 seq_num=53 snap_id=1
    wait times: snap=5.014759 sec, exc=5.014759 sec, total=5.014759 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=51 seq_num=52 snap_id=1
    wait times: snap=5.010177 sec, exc=5.010177 sec, total=5.010177 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000025 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=50 seq_num=51 snap_id=1
    wait times: snap=5.004237 sec, exc=5.004237 sec, total=5.004237 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=49 seq_num=50 snap_id=1
    wait times: snap=5.003261 sec, exc=5.003261 sec, total=5.003261 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
Sampled Session History of session 177 serial 19635
---------------------------------------------------
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
---------------------------------------------------
  [121 samples,                                            18:04:21 - 18:06:21]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-02 18:06:21.525
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 19472, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-02 17:00:22.590809 :BA6862D9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:00:22.591657 :BA6862DE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:00:22.609262 :BA6862E1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:00:22.600
2025-08-02 17:00:22.609264 :BA6862E2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17124 pso_num=22 pso_serial#=115
2025-08-02 17:00:22.609408 :BA6862E3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17124 (legacy spawn)
2025-08-02 17:00:22.615849 :BA6862E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:22.616014 :BA6862ED:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:00:22.616861 :BA6862EE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:00:23.067058 :BA6862FD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:00:23.058
2025-08-02 17:00:23.067061 :BA6862FE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20296 pso_num=22 pso_serial#=116
2025-08-02 17:00:23.067231 :BA6862FF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20296 (legacy spawn)
2025-08-02 17:00:23.073686 :BA686300:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:23.073854 :BA686309:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:00:23.074726 :BA68630A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:00:25.536802 :BA6863E2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:00:25.527
2025-08-02 17:00:25.536804 :BA6863E3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18652 pso_num=22 pso_serial#=117
2025-08-02 17:00:25.536940 :BA6863E4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18652 (legacy spawn)
2025-08-02 17:00:25.543424 :BA6863E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:25.543595 :BA6863EA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:00:25.544391 :BA6863EF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:00:25.681260 :BA6863F2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:00:25.668
2025-08-02 17:00:25.681262 :BA6863F3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20200 pso_num=22 pso_serial#=118
2025-08-02 17:00:25.681405 :BA6863F4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20200 (legacy spawn)
2025-08-02 17:00:25.687867 :BA6863F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:25.688027 :BA6863FE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:00:25.688841 :BA6863FF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:00:25.697423 :BA686402:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:00:25.684
2025-08-02 17:00:25.697425 :BA686403:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18928 pso_num=22 pso_serial#=119
2025-08-02 17:00:25.697556 :BA686404:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18928 (legacy spawn)
2025-08-02 17:00:25.704045 :BA686405:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:25.704204 :BA68640E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:00:25.705058 :BA68640F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:00:31.051962 :BA6865F4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:00:31.046
2025-08-02 17:00:31.051964 :BA6865F5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7616 pso_num=22 pso_serial#=120
2025-08-02 17:00:31.052100 :BA6865F6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7616 osp_idx=0 osp_ver=477042 osp_pg=0 (spawn #448451)
2025-08-02 17:00:31.052101 :BA6865F7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 17:00:31.052101 :BA6865F8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=38 time=2834700361
2025-08-02 17:00:31.052102 :BA6865F9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 17:00:31.052102 :BA6865FA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 17:00:31.052102 :BA6865FB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 17:00:31.052103 :BA6865FC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 17:00:31.052103 :BA6865FD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 17:00:31.052604 :BA6865FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.053227 :BA686613:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.064294 :BA68662F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-08-02 17:00:31.064758 :BA686630:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-08-02 17:00:31.067162 :BA686631:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.067572 :BA686632:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.067606 :BA686635:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.070521 :BA68663B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.071309 :BA686643:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.072871 :BA68664B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.077044 :BA686653:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.080580 :BA68665B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.084575 :BA686663:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.088547 :BA68666B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.089139 :BA686673:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.095099 :BA68667B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.095403 :BA686683:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.099430 :BA68668B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.099907 :BA686693:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.103488 :BA68669B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.104490 :BA6866A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.105261 :BA6866A4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.105293 :BA6866A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.105305 :BA6866A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.105454 :BA6866AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.105471 :BA6866B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.105477 :BA6866B2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.105591 :BA6866B8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.105607 :BA6866BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.105612 :BA6866BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.105705 :BA6866C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.105724 :BA6866C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.105729 :BA6866C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.105830 :BA6866CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.105845 :BA6866CF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.105851 :BA6866D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.105949 :BA6866D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.105965 :BA6866D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.106510 :BA6866DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.106676 :BA6866E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.106694 :BA6866E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.110701 :BA6866E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.110782 :BA6866EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-02 17:00:31.111033 :BA6866F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.111054 :BA6866F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-02 17:00:31.113283 :BA6866FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:00:31.113443 :BA686704:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:01:01.172606 :BA6871D3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:01:02.076134 :BA6871ED:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:01:02.063
2025-08-02 17:01:02.076136 :BA6871EE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20316 pso_num=22 pso_serial#=121
2025-08-02 17:01:02.076275 :BA6871EF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20316 (legacy spawn)
2025-08-02 17:01:02.095658 :BA6871F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:01:02.095831 :BA6871F7:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:01:02.096674 :BA6871FA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:01:02.105312 :BA6871FD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:01:02.094
2025-08-02 17:01:02.105314 :BA6871FE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16768 pso_num=22 pso_serial#=122
2025-08-02 17:01:02.105449 :BA6871FF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16768 (legacy spawn)
2025-08-02 17:01:02.111998 :BA687200:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-08-02 17:01:02.112169 :BA687209:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-02 17:01:02.112993 :BA68720A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:01:21.506238 :BA68782D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:01:21.501
2025-08-02 17:01:21.506240 :BA68782E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18260 pso_num=22 pso_serial#=123
2025-08-02 17:01:21.506378 :BA68782F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18260 osp_idx=0 osp_ver=477045 osp_pg=0 (spawn #448456)
2025-08-02 17:01:21.506379 :BA687830:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 17:01:21.506379 :BA687831:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=39 time=2834750814
2025-08-02 17:01:21.506379 :BA687832:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 17:01:21.506380 :BA687833:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 17:01:21.506380 :BA687834:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 17:01:21.506381 :BA687835:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 17:01:21.506381 :BA687836:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 17:01:21.506885 :BA687837:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 17:01:21.506927 :BA68783F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-02 17:11:22.431446 :BA6944BE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:11:22.701809 :BA6944F6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:11:22.696
2025-08-02 17:11:22.701811 :BA6944F7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15432 pso_num=22 pso_serial#=124
2025-08-02 17:11:22.701943 :BA6944F8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15432 osp_idx=0 osp_ver=477046 osp_pg=0 (spawn #448479)
2025-08-02 17:11:22.701944 :BA6944F9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 17:11:22.701945 :BA6944FA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=2835352001
2025-08-02 17:11:22.701945 :BA6944FB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 17:11:22.701946 :BA6944FC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 17:11:22.701946 :BA6944FD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 17:11:22.701947 :BA6944FE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 17:11:22.701947 :BA6944FF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 17:11:22.702432 :BA694500:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 17:11:22.702475 :BA694507:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-02 17:21:23.585760 :BA6A0E40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:21:23.871787 :BA6A0E75:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:21:23.867
2025-08-02 17:21:23.871789 :BA6A0E76:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17888 pso_num=22 pso_serial#=125
2025-08-02 17:21:23.871931 :BA6A0E77:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17888 osp_idx=0 osp_ver=477047 osp_pg=0 (spawn #448502)
2025-08-02 17:21:23.871932 :BA6A0E78:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 17:21:23.871933 :BA6A0E79:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=2835953173
2025-08-02 17:21:23.871933 :BA6A0E7A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 17:21:23.871934 :BA6A0E7B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 17:21:23.871934 :BA6A0E7C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 17:21:23.871934 :BA6A0E7D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 17:21:23.871934 :BA6A0E7E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 17:21:23.872440 :BA6A0E7F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 17:21:23.872496 :BA6A0E85:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-02 17:30:16.599194 :BA6ABECA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
2025-08-02 17:31:25.036892 :BA6AD613:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1
2025-08-02 17:41:25.938116 :BA6B9C86:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:41:26.317817 :BA6B9D05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:41:26.313
2025-08-02 17:41:26.317819 :BA6B9D06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19040 pso_num=22 pso_serial#=126
2025-08-02 17:41:26.317966 :BA6B9D07:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19040 osp_idx=0 osp_ver=477048 osp_pg=0 (spawn #448546)
2025-08-02 17:41:26.317967 :BA6B9D08:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 17:41:26.317967 :BA6B9D09:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=2837155626
2025-08-02 17:41:26.317967 :BA6B9D0A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 17:41:26.317968 :BA6B9D0B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 17:41:26.317968 :BA6B9D0C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 17:41:26.317968 :BA6B9D0D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 17:41:26.317969 :BA6B9D0E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 17:41:26.318467 :BA6B9D0F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 17:41:26.318511 :BA6B9D16:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-02 17:51:27.180836 :BA6C63B8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 17:51:27.529291 :BA6C642A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 17:51:27.525
2025-08-02 17:51:27.529293 :BA6C642B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19880 pso_num=22 pso_serial#=127
2025-08-02 17:51:27.529422 :BA6C642C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19880 osp_idx=0 osp_ver=477049 osp_pg=0 (spawn #448569)
2025-08-02 17:51:27.529423 :BA6C642D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 17:51:27.529423 :BA6C642E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=43 time=2837756829
2025-08-02 17:51:27.529424 :BA6C642F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 17:51:27.529424 :BA6C6430:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 17:51:27.529424 :BA6C6431:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 17:51:27.529425 :BA6C6432:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 17:51:27.529425 :BA6C6433:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 17:51:27.529984 :BA6C6434:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 17:51:27.530029 :BA6C643D:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
2025-08-02 18:01:28.339743 :BA6D2C85:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-02 18:01:28.703753 :BA6D2CF4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 18:01:28.699
2025-08-02 18:01:28.703755 :BA6D2CF5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19472 pso_num=22 pso_serial#=128
2025-08-02 18:01:28.703891 :BA6D2CF6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19472 osp_idx=0 osp_ver=477050 osp_pg=0 (spawn #448594)
2025-08-02 18:01:28.703892 :BA6D2CF7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-08-02 18:01:28.703892 :BA6D2CF8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=44 time=2838358017
2025-08-02 18:01:28.703893 :BA6D2CF9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-02 18:01:28.703893 :BA6D2CFA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-02 18:01:28.703893 :BA6D2CFB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-02 18:01:28.703894 :BA6D2CFC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-02 18:01:28.703894 :BA6D2CFD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-02 18:01:28.704376 :BA6D2CFE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-08-02 18:01:28.704419 :BA6D2D06:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name=   type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 22 (osid: 19472, W000)