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


*** 2025-09-01 22:18:33.542
*** SESSION ID:(177.26237) 2025-09-01 22:18:33.542
 

*** 2025-09-01 22:18:33.542
Process diagnostic dump for ORACLE.EXE (W000), OS id=22340,
pid: 22, proc_ser: 52, sid: 177, sess_ser: 26237 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=66 seq_num=67 snap_id=1
    wait times: snap=4.634164 sec, exc=4.634164 sec, total=4.634164 sec
    wait times: max=5.000000 sec, heur=5 min 30 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.000031 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=65 seq_num=66 snap_id=1
    wait times: snap=5.014758 sec, exc=5.014758 sec, total=5.014758 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000038 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=64 seq_num=65 snap_id=1
    wait times: snap=5.004610 sec, exc=5.004610 sec, total=5.004610 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=63 seq_num=64 snap_id=1
    wait times: snap=5.014703 sec, exc=5.014703 sec, total=5.014703 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000046 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=62 seq_num=63 snap_id=1
    wait times: snap=5.010139 sec, exc=5.010139 sec, total=5.010139 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000038 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=61 seq_num=62 snap_id=1
    wait times: snap=5.005361 sec, exc=5.005361 sec, total=5.005361 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000041 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=60 seq_num=61 snap_id=1
    wait times: snap=5.002208 sec, exc=5.002208 sec, total=5.002208 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000033 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=59 seq_num=60 snap_id=1
    wait times: snap=5.005777 sec, exc=5.005777 sec, total=5.005777 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000037 sec of elapsed time
 7: 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.004643 sec, exc=5.004643 sec, total=5.004643 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 8: 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.009200 sec, exc=5.009200 sec, total=5.009200 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 9: 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.010091 sec, exc=5.010091 sec, total=5.010091 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
Sampled Session History of session 177 serial 26237
---------------------------------------------------
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,                                            22:16:33 - 22:18:33]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-01 22:18:33.542
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 22340, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-01 22:00:31.939581 :C7B541B6: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-09-01 22:00:31.940604 :C7B541BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.940874 :C7B541BF: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-09-01 22:00:31.940905 :C7B541C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.940913 :C7B541C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.941042 :C7B541C9: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-09-01 22:00:31.941059 :C7B541CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.941064 :C7B541CD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.941161 :C7B541D3: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-09-01 22:00:31.941176 :C7B541D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.941182 :C7B541D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.941273 :C7B541DD: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-09-01 22:00:31.941287 :C7B541E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.941293 :C7B541E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.941381 :C7B541E7: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-09-01 22:00:31.941396 :C7B541EA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.941401 :C7B541EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.941493 :C7B541F1: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-09-01 22:00:31.941512 :C7B541F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.942031 :C7B541FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.942144 :C7B541FB: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-09-01 22:00:31.942160 :C7B541FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.946646 :C7B54204: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-09-01 22:00:31.946731 :C7B54207:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:00:31.947009 :C7B5420D: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-09-01 22:00:31.947041 :C7B54210:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:00:31.949351 :C7B54216: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-09-01 22:00:31.949498 :C7B5421F: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-09-01 22:01:02.042857 :C7B54BE7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:05.848073 :C7B54CFC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:05.840
2025-09-01 22:01:05.848075 :C7B54CFD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23152 pso_num=22 pso_serial#=37
2025-09-01 22:01:05.848216 :C7B54CFE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23152 (legacy spawn)
2025-09-01 22:01:05.854884 :C7B54CFF: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-09-01 22:01:05.855098 :C7B54D04: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-09-01 22:01:05.855954 :C7B54D09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:11.901596 :C7B54EEA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:11.887
2025-09-01 22:01:11.901598 :C7B54EEB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23420 pso_num=22 pso_serial#=38
2025-09-01 22:01:11.901742 :C7B54EEC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23420 (legacy spawn)
2025-09-01 22:01:11.908169 :C7B54EED: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-09-01 22:01:11.908344 :C7B54EF2: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-09-01 22:01:11.909146 :C7B54EF7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:11.917865 :C7B54EFA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:11.903
2025-09-01 22:01:11.917867 :C7B54EFB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19760 pso_num=22 pso_serial#=39
2025-09-01 22:01:11.918001 :C7B54EFC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19760 (legacy spawn)
2025-09-01 22:01:11.924439 :C7B54F08: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-09-01 22:01:11.924470 :C7B54F0B: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-09-01 22:01:11.924625 :C7B54F12: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-09-01 22:01:11.925452 :C7B54F1F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:15.010055 :C7B5502C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:14.996
2025-09-01 22:01:15.010057 :C7B5502D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22740 pso_num=22 pso_serial#=40
2025-09-01 22:01:15.010202 :C7B5502E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22740 (legacy spawn)
2025-09-01 22:01:15.029639 :C7B5502F: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-09-01 22:01:15.029811 :C7B55034: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-09-01 22:01:15.030668 :C7B55039:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:15.039377 :C7B5503C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:15.028
2025-09-01 22:01:15.039379 :C7B5503D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16164 pso_num=22 pso_serial#=41
2025-09-01 22:01:15.039517 :C7B5503E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16164 (legacy spawn)
2025-09-01 22:01:15.046059 :C7B5503F: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-09-01 22:01:15.046224 :C7B55048: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-09-01 22:01:15.047012 :C7B55049:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:29.168610 :C7B554C3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:29.155
2025-09-01 22:01:29.168612 :C7B554C4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23508 pso_num=22 pso_serial#=42
2025-09-01 22:01:29.168751 :C7B554C5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23508 (legacy spawn)
2025-09-01 22:01:29.175264 :C7B554C6: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-09-01 22:01:29.175433 :C7B554CB: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-09-01 22:01:29.176239 :C7B554D0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:01:31.977368 :C7B555C4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:01:31.972
2025-09-01 22:01:31.977370 :C7B555C5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22280 pso_num=22 pso_serial#=43
2025-09-01 22:01:31.977508 :C7B555C6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22280 osp_idx=0 osp_ver=563410 osp_pg=0 (spawn #549169)
2025-09-01 22:01:31.977509 :C7B555C7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-01 22:01:31.977509 :C7B555C8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=1149743283
2025-09-01 22:01:31.977510 :C7B555C9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-01 22:01:31.977510 :C7B555CA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-01 22:01:31.977511 :C7B555CB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-01 22:01:31.977511 :C7B555CC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-01 22:01:31.977511 :C7B555CD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-01 22:01:31.978036 :C7B555CE: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-09-01 22:01:31.978685 :C7B555E3: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-09-01 22:01:31.986245 :C7B555FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:01:31.986615 :C7B55600: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-09-01 22:01:31.986653 :C7B55603:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:01:31.987584 :C7B55609: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-09-01 22:01:31.987711 :C7B55612: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-09-01 22:02:02.073363 :C7B55FAC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:03.146351 :C7B5600B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:03.137
2025-09-01 22:02:03.146354 :C7B5600C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19984 pso_num=22 pso_serial#=44
2025-09-01 22:02:03.146489 :C7B5600D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19984 (legacy spawn)
2025-09-01 22:02:03.152998 :C7B5600E: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-09-01 22:02:03.153174 :C7B56013: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-09-01 22:02:03.153972 :C7B56018:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:08.007095 :C7B561A3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:07.999
2025-09-01 22:02:08.007097 :C7B561A4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16632 pso_num=22 pso_serial#=45
2025-09-01 22:02:08.007233 :C7B561A5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16632 (legacy spawn)
2025-09-01 22:02:08.013791 :C7B561A6: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-09-01 22:02:08.013968 :C7B561AB: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-09-01 22:02:08.014814 :C7B561B0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:24.718939 :C7B5670D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:24.715
2025-09-01 22:02:24.718942 :C7B5670E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19788 pso_num=22 pso_serial#=46
2025-09-01 22:02:24.719083 :C7B5670F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19788 (legacy spawn)
2025-09-01 22:02:24.725823 :C7B56710: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-09-01 22:02:24.725988 :C7B56719: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-09-01 22:02:24.726947 :C7B5671A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:24.735551 :C7B5671D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:24.731
2025-09-01 22:02:24.735554 :C7B5671E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23488 pso_num=22 pso_serial#=47
2025-09-01 22:02:24.735707 :C7B5671F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23488 (legacy spawn)
2025-09-01 22:02:24.742170 :C7B56720: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-09-01 22:02:24.742340 :C7B56729: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-09-01 22:02:24.743163 :C7B5672A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:27.837935 :C7B5681E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:27.829
2025-09-01 22:02:27.837938 :C7B5681F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2268 pso_num=22 pso_serial#=48
2025-09-01 22:02:27.838081 :C7B56820:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2268 (legacy spawn)
2025-09-01 22:02:27.844657 :C7B56821: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-09-01 22:02:27.924191 :C7B5682A: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-09-01 22:02:27.925117 :C7B5682B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:27.933920 :C7B5682E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:27.923
2025-09-01 22:02:27.933923 :C7B5682F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21868 pso_num=22 pso_serial#=49
2025-09-01 22:02:27.934076 :C7B56830:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21868 (legacy spawn)
2025-09-01 22:02:27.940659 :C7B56831: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-09-01 22:02:27.940845 :C7B5683A: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-09-01 22:02:27.941673 :C7B5683B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:02:32.059274 :C7B5699E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:02:32.053
2025-09-01 22:02:32.059276 :C7B5699F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22052 pso_num=22 pso_serial#=50
2025-09-01 22:02:32.059416 :C7B569A0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22052 osp_idx=0 osp_ver=563417 osp_pg=0 (spawn #549171)
2025-09-01 22:02:32.059416 :C7B569A1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-01 22:02:32.059417 :C7B569A2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=1149803362
2025-09-01 22:02:32.059417 :C7B569A3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-01 22:02:32.059418 :C7B569A4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-01 22:02:32.059418 :C7B569A5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-01 22:02:32.059418 :C7B569A6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-01 22:02:32.059419 :C7B569A7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-01 22:02:32.059907 :C7B569A8: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-09-01 22:02:32.060547 :C7B569BD: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-09-01 22:02:32.074815 :C7B569D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:02:32.074920 :C7B569DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:02:32.075727 :C7B569DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:02:32.076068 :C7B569DC: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-09-01 22:02:32.076111 :C7B569DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:02:32.084064 :C7B569E5: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-09-01 22:02:32.084128 :C7B569E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879
2025-09-01 22:02:32.084164 :C7B569E9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879
2025-09-01 22:02:32.084569 :C7B569EF:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-01 22:02:32.084945 :C7B569F0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-01 22:02:32.085139 :C7B569F1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:02:32.085396 :C7B569F2: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-09-01 22:02:32.085421 :C7B569F5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:02:32.086989 :C7B569FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-01 22:02:32.087229 :C7B569FC: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-09-01 22:02:32.087270 :C7B569FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-01 22:02:32.088293 :C7B56A05: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-09-01 22:02:32.088413 :C7B56A0D: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-09-01 22:03:02.185080 :C7B573A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:03:02.361625 :C7B573EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:03:02.356
2025-09-01 22:03:02.361627 :C7B573ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22188 pso_num=22 pso_serial#=51
2025-09-01 22:03:02.361763 :C7B573EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22188 osp_idx=0 osp_ver=563418 osp_pg=0 (spawn #549173)
2025-09-01 22:03:02.361764 :C7B573EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-01 22:03:02.361764 :C7B573F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=1149833658
2025-09-01 22:03:02.361765 :C7B573F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-01 22:03:02.361765 :C7B573F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-01 22:03:02.361766 :C7B573F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-01 22:03:02.361766 :C7B573F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-01 22:03:02.361766 :C7B573F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-01 22:03:02.362305 :C7B573F6: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-09-01 22:03:02.362349 :C7B573FD: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-09-01 22:13:03.161241 :C7B649E2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-01 22:13:03.431878 :C7B64A28:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-01 22:13:03.427
2025-09-01 22:13:03.431880 :C7B64A29:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22340 pso_num=22 pso_serial#=52
2025-09-01 22:13:03.432025 :C7B64A2A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22340 osp_idx=0 osp_ver=563419 osp_pg=0 (spawn #549196)
2025-09-01 22:13:03.432025 :C7B64A2B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-01 22:13:03.432026 :C7B64A2C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=43 time=1150434737
2025-09-01 22:13:03.432026 :C7B64A2D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-01 22:13:03.432027 :C7B64A2E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-01 22:13:03.432027 :C7B64A2F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-01 22:13:03.432027 :C7B64A30:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-01 22:13:03.432027 :C7B64A31:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-01 22:13:03.432551 :C7B64A32: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-09-01 22:13:03.432595 :C7B64A39: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: 22340, W000)