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


*** 2025-09-14 22:08:04.581
*** SESSION ID:(177.3511) 2025-09-14 22:08:04.581
 

*** 2025-09-14 22:08:04.581
Process diagnostic dump for ORACLE.EXE (W000), OS id=25272,
pid: 22, proc_ser: 145, sid: 177, sess_ser: 3511 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=94 seq_num=95 snap_id=1
    wait times: snap=2.097808 sec, exc=2.097808 sec, total=2.097808 sec
    wait times: max=5.000000 sec, heur=7 min 12 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=93 seq_num=94 snap_id=1
    wait times: snap=5.004664 sec, exc=5.004664 sec, total=5.004664 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000032 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=92 seq_num=93 snap_id=1
    wait times: snap=5.007245 sec, exc=5.007245 sec, total=5.007245 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000021 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=91 seq_num=92 snap_id=1
    wait times: snap=5.008546 sec, exc=5.008546 sec, total=5.008546 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000029 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=90 seq_num=91 snap_id=1
    wait times: snap=5.010593 sec, exc=5.010593 sec, total=5.010593 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 4: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=89 seq_num=90 snap_id=1
    wait times: snap=5.014688 sec, exc=5.014688 sec, total=5.014688 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000082 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=88 seq_num=89 snap_id=1
    wait times: snap=5.004680 sec, exc=5.004680 sec, total=5.004680 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000035 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=87 seq_num=88 snap_id=1
    wait times: snap=5.014712 sec, exc=5.014712 sec, total=5.014712 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000034 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=86 seq_num=87 snap_id=1
    wait times: snap=5.015250 sec, exc=5.015250 sec, total=5.015250 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000030 sec of elapsed time
 8: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=85 seq_num=86 snap_id=1
    wait times: snap=5.003321 sec, exc=5.003321 sec, total=5.003321 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000037 sec of elapsed time
 9: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=84 seq_num=85 snap_id=1
    wait times: snap=5.005910 sec, exc=5.005910 sec, total=5.005910 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000030 sec of elapsed time
Sampled Session History of session 177 serial 3511
---------------------------------------------------
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:06:04 - 22:08:04]
    idle wait at each sample
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-14 22:08:04.581
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 25272, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-14 21:46:50.087092 :CD7816BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879
2025-09-14 21:46:50.087392 :CD7816C2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-14 21:46:50.087721 :CD7816C3:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-14 21:46:50.087839 :CD7816C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-14 21:46:50.088050 :CD7816C5: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-14 21:46:50.088074 :CD7816C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-14 21:46:50.089741 :CD7816CE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-14 21:46:50.089975 :CD7816CF: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-14 21:46:50.090006 :CD7816D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-14 21:46:50.090940 :CD7816D8: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-14 21:46:50.091060 :CD7816E1: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-14 21:47:20.176476 :CD7820B0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:27.891975 :CD7822F8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:27.877
2025-09-14 21:47:27.891977 :CD7822F9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24028 pso_num=22 pso_serial#=127
2025-09-14 21:47:27.892145 :CD7822FA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24028 (legacy spawn)
2025-09-14 21:47:27.898607 :CD7822FB: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-14 21:47:27.898793 :CD782300: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-14 21:47:27.899583 :CD782305:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:27.908358 :CD782308:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:27.893
2025-09-14 21:47:27.908360 :CD782309:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13480 pso_num=22 pso_serial#=128
2025-09-14 21:47:27.908525 :CD78230A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13480 (legacy spawn)
2025-09-14 21:47:27.914975 :CD78230B: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-14 21:47:27.915139 :CD782313: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-14 21:47:27.915942 :CD782315:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:31.003256 :CD78240D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:30.990
2025-09-14 21:47:31.003257 :CD78240E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23900 pso_num=22 pso_serial#=129
2025-09-14 21:47:31.003396 :CD78240F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23900 (legacy spawn)
2025-09-14 21:47:31.010120 :CD782410: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-14 21:47:31.010322 :CD782415: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-14 21:47:31.011196 :CD78241A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:31.020318 :CD78241D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:31.006
2025-09-14 21:47:31.020320 :CD78241E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25224 pso_num=22 pso_serial#=130
2025-09-14 21:47:31.020457 :CD78241F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25224 (legacy spawn)
2025-09-14 21:47:31.027269 :CD782434: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-14 21:47:31.027439 :CD78243D: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-14 21:47:31.028287 :CD78243E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:34.790180 :CD782530:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:34.776
2025-09-14 21:47:34.790182 :CD782531:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24812 pso_num=22 pso_serial#=131
2025-09-14 21:47:34.790330 :CD782532:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24812 (legacy spawn)
2025-09-14 21:47:34.797068 :CD782533: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-14 21:47:34.797247 :CD782538: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-14 21:47:34.798068 :CD78253D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:34.807309 :CD782540:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:34.808
2025-09-14 21:47:34.807311 :CD782541:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23332 pso_num=22 pso_serial#=132
2025-09-14 21:47:34.807459 :CD782542:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23332 (legacy spawn)
2025-09-14 21:47:34.814120 :CD782543: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-14 21:47:34.814290 :CD78254C: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-14 21:47:34.815158 :CD78254D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:46.444799 :CD78291C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:46.436
2025-09-14 21:47:46.444801 :CD78291D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21880 pso_num=22 pso_serial#=133
2025-09-14 21:47:46.444943 :CD78291E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21880 (legacy spawn)
2025-09-14 21:47:46.451522 :CD78291F: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-14 21:47:46.451697 :CD782924: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-14 21:47:46.452526 :CD782929:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:47:50.074387 :CD782A3A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:47:50.068
2025-09-14 21:47:50.074389 :CD782A3B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24020 pso_num=22 pso_serial#=134
2025-09-14 21:47:50.074528 :CD782A3C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24020 osp_idx=0 osp_ver=609401 osp_pg=0 (spawn #592484)
2025-09-14 21:47:50.074528 :CD782A3D: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-14 21:47:50.074529 :CD782A3E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=87 time=2272121377
2025-09-14 21:47:50.074529 :CD782A3F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 21:47:50.074529 :CD782A40:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 21:47:50.074530 :CD782A41:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 21:47:50.074530 :CD782A42:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 21:47:50.074530 :CD782A43:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 21:47:50.075024 :CD782A44: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-14 21:47:50.075656 :CD782A59: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-14 21:47:50.083462 :CD782A75:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-14 21:47:50.083870 :CD782A76: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-14 21:47:50.083907 :CD782A79:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-14 21:47:50.084805 :CD782A7F: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-14 21:47:50.084938 :CD782A88: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-14 21:48:20.142991 :CD78347F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:26.432159 :CD7836C1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:26.422
2025-09-14 21:48:26.432161 :CD7836C2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25288 pso_num=22 pso_serial#=135
2025-09-14 21:48:26.432302 :CD7836C3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25288 (legacy spawn)
2025-09-14 21:48:26.439080 :CD7836C4: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-14 21:48:26.439253 :CD7836CD: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-14 21:48:26.440105 :CD7836CE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:40.331987 :CD783B06:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:40.319
2025-09-14 21:48:40.331989 :CD783B07:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25440 pso_num=22 pso_serial#=136
2025-09-14 21:48:40.332126 :CD783B08:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25440 (legacy spawn)
2025-09-14 21:48:40.338635 :CD783B09: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-14 21:48:40.338812 :CD783B0E: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-14 21:48:40.339599 :CD783B13:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:40.348444 :CD783B16:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:40.335
2025-09-14 21:48:40.348446 :CD783B17:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25448 pso_num=22 pso_serial#=137
2025-09-14 21:48:40.348584 :CD783B18:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25448 (legacy spawn)
2025-09-14 21:48:40.355324 :CD783B19: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-14 21:48:40.355491 :CD783B22: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-14 21:48:40.356291 :CD783B23:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:43.490251 :CD783C0F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:43.477
2025-09-14 21:48:43.490253 :CD783C10:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24016 pso_num=22 pso_serial#=138
2025-09-14 21:48:43.490391 :CD783C11:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24016 (legacy spawn)
2025-09-14 21:48:43.497027 :CD783C12: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-14 21:48:43.497194 :CD783C17: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-14 21:48:43.497999 :CD783C1C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:43.506687 :CD783C1F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:43.493
2025-09-14 21:48:43.506688 :CD783C20:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21864 pso_num=22 pso_serial#=139
2025-09-14 21:48:43.506823 :CD783C21:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21864 (legacy spawn)
2025-09-14 21:48:43.513446 :CD783C22: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-14 21:48:43.513616 :CD783C2B: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-14 21:48:43.514431 :CD783C2C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:48.573658 :CD783DBE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:48.562
2025-09-14 21:48:48.573660 :CD783DBF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23812 pso_num=22 pso_serial#=140
2025-09-14 21:48:48.573788 :CD783DC0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23812 (legacy spawn)
2025-09-14 21:48:48.595348 :CD783DC1: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-14 21:48:48.595522 :CD783DC6: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-14 21:48:48.596387 :CD783DCB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:48:50.156470 :CD783E41:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:48:50.151
2025-09-14 21:48:50.156472 :CD783E42:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24260 pso_num=22 pso_serial#=141
2025-09-14 21:48:50.156613 :CD783E43:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24260 osp_idx=0 osp_ver=609408 osp_pg=0 (spawn #592487)
2025-09-14 21:48:50.156614 :CD783E44: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-14 21:48:50.156614 :CD783E45:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=88 time=2272181455
2025-09-14 21:48:50.156615 :CD783E46:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 21:48:50.156615 :CD783E47:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 21:48:50.156615 :CD783E48:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 21:48:50.156615 :CD783E49:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 21:48:50.156616 :CD783E4A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 21:48:50.157130 :CD783E4B: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-14 21:48:50.157794 :CD783E60: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-14 21:48:50.168935 :CD783E7D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-14 21:48:50.169407 :CD783E7E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-14 21:48:50.171841 :CD783E7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-14 21:48:50.172199 :CD783E80: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-14 21:48:50.172236 :CD783E83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-14 21:48:50.173108 :CD783E89: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-14 21:48:50.173245 :CD783E92: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-14 21:49:20.268638 :CD784842:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:49:34.532834 :CD784CC0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:49:34.523
2025-09-14 21:49:34.532837 :CD784CC1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24784 pso_num=22 pso_serial#=142
2025-09-14 21:49:34.533027 :CD784CC2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24784 (legacy spawn)
2025-09-14 21:49:34.539596 :CD784CC3: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-14 21:49:34.539767 :CD784CC8: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-14 21:49:34.540552 :CD784CCD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:49:50.263188 :CD7851DD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:49:50.257
2025-09-14 21:49:50.263190 :CD7851DE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25504 pso_num=22 pso_serial#=143
2025-09-14 21:49:50.263334 :CD7851DF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25504 osp_idx=0 osp_ver=609410 osp_pg=0 (spawn #592489)
2025-09-14 21:49:50.263335 :CD7851E0: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-14 21:49:50.263335 :CD7851E1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=89 time=2272241565
2025-09-14 21:49:50.263335 :CD7851E2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 21:49:50.263336 :CD7851E3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 21:49:50.263336 :CD7851E4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 21:49:50.263337 :CD7851E5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 21:49:50.263337 :CD7851E6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 21:49:50.263892 :CD7851E7: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-14 21:49:50.264543 :CD7851FC: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-14 21:49:50.272183 :CD785218:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-09-14 21:49:50.272567 :CD785219: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-14 21:49:50.272628 :CD78521C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-09-14 21:49:50.273568 :CD785231: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-14 21:49:50.273683 :CD78523A: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-14 21:50:20.373761 :CD785C3B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 21:50:20.441036 :CD785C7B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 21:50:20.436
2025-09-14 21:50:20.441039 :CD785C7C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25492 pso_num=22 pso_serial#=144
2025-09-14 21:50:20.441194 :CD785C7D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25492 osp_idx=0 osp_ver=609411 osp_pg=0 (spawn #592491)
2025-09-14 21:50:20.441194 :CD785C7E: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-14 21:50:20.441195 :CD785C7F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=90 time=2272271752
2025-09-14 21:50:20.441195 :CD785C80:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 21:50:20.441196 :CD785C81:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 21:50:20.441196 :CD785C82:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 21:50:20.441196 :CD785C83:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 21:50:20.441197 :CD785C84:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 21:50:20.441730 :CD785C85: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-14 21:50:20.441780 :CD785C8B: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-14 22:00:21.315447 :CD7922F6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-09-14 22:00:21.804776 :CD792349:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-14 22:00:21.800
2025-09-14 22:00:21.804778 :CD79234A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25272 pso_num=22 pso_serial#=145
2025-09-14 22:00:21.804921 :CD79234B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=25272 osp_idx=0 osp_ver=609412 osp_pg=0 (spawn #592514)
2025-09-14 22:00:21.804922 :CD79234C: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-14 22:00:21.804922 :CD79234D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=91 time=2272873112
2025-09-14 22:00:21.804923 :CD79234E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-09-14 22:00:21.804924 :CD79234F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-09-14 22:00:21.804924 :CD792350:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-09-14 22:00:21.804924 :CD792351:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-14 22:00:21.804925 :CD792352:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-14 22:00:21.805447 :CD792353: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-14 22:00:21.805497 :CD79235D: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-14 22:00:51.845789 :CD792E22:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1
2025-09-14 22:00:51.845822 :CD792E27:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1
2025-09-14 22:00:51.845824 :CD792E28:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=33, exc=33, tot=33
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 22 (osid: 25272, W000)