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


*** 2025-08-21 22:00:08.714
*** SESSION ID:(177.36763) 2025-08-21 22:00:08.714
 

*** 2025-08-21 22:00:08.714
Process diagnostic dump for ORACLE.EXE (W000), OS id=22120,
pid: 22, proc_ser: 100, sid: 177, sess_ser: 36763 
-------------------------------------------------------------------------------
current sql: <none>
Current Wait Stack:
 0: waiting for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=9 seq_num=10 snap_id=1
    wait times: snap=3.511599 sec, exc=3.511599 sec, total=3.511599 sec
    wait times: max=5.000000 sec, heur=43.562811 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.000032 sec since current wait
 0: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=8 seq_num=9 snap_id=1
    wait times: snap=5.010155 sec, exc=5.010155 sec, total=5.010155 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000031 sec of elapsed time
 1: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=7 seq_num=8 snap_id=1
    wait times: snap=5.002794 sec, exc=5.002794 sec, total=5.002794 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000024 sec of elapsed time
 2: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=6 seq_num=7 snap_id=1
    wait times: snap=5.000301 sec, exc=5.000301 sec, total=5.000301 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000022 sec of elapsed time
 3: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=5 seq_num=6 snap_id=1
    wait times: snap=5.012439 sec, exc=5.012439 sec, total=5.012439 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=4 seq_num=5 snap_id=1
    wait times: snap=5.007554 sec, exc=5.007554 sec, total=5.007554 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000050 sec of elapsed time
 5: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=3 seq_num=4 snap_id=1
    wait times: snap=5.009240 sec, exc=5.009240 sec, total=5.009240 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000023 sec of elapsed time
 6: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=2 seq_num=3 snap_id=1
    wait times: snap=5.010160 sec, exc=5.010160 sec, total=5.010160 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.000018 sec of elapsed time
 7: waited for 'Space Manager: slave idle wait'
    Slave ID=0x0, =0x0, =0x0
    wait_id=1 seq_num=2 snap_id=1
    wait times: snap=4.998345 sec, exc=4.998345 sec, total=4.998345 sec
    wait times: max=5.000000 sec
    wait counts: calls=1 os=1
    occurred after 0.004121 sec of elapsed time
 8: waited for 'class slave wait'
    slave id=0x126861c0, =0x0, =0x0
    wait_id=0 seq_num=1 snap_id=1
    wait times: snap=0.000038 sec, exc=0.000038 sec, total=0.000038 sec
    wait times: max=infinite
    wait counts: calls=1 os=1
    occurred after 0.000000 sec of elapsed time
Sampled Session History of session 177 serial 36763
---------------------------------------------------
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
---------------------------------------------------
  [44 samples,                                             21:59:25 - 22:00:08]
    idle wait at each sample
  [session created at: 21:59:25]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-08-21 22:00:08.714
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 22 (osid: 22120, W000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-08-21 21:56:17.569049 :C2DC0B8D: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-21 21:56:17.569071 :C2DC0B90:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-21 21:56:17.570609 :C2DC0B96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-21 21:56:17.570812 :C2DC0B97: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-21 21:56:17.570841 :C2DC0B9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-21 21:56:17.571697 :C2DC0BA0: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-21 21:56:47.665139 :C2DC1544:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:56:52.308626 :C2DC169C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:56:52.294
2025-08-21 21:56:52.308629 :C2DC169D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18624 pso_num=22 pso_serial#=78
2025-08-21 21:56:52.308777 :C2DC169E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18624 (legacy spawn)
2025-08-21 21:56:52.315189 :C2DC169F: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-21 21:56:52.315353 :C2DC16A4: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-21 21:56:52.316182 :C2DC16A9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:56:52.324811 :C2DC16AC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:56:52.310
2025-08-21 21:56:52.324813 :C2DC16AD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21760 pso_num=22 pso_serial#=79
2025-08-21 21:56:52.324948 :C2DC16AE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21760 (legacy spawn)
2025-08-21 21:56:52.331393 :C2DC16AF: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-21 21:56:52.331565 :C2DC16B8: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-21 21:56:52.332353 :C2DC16B9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:56:55.411911 :C2DC17B3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:56:55.398
2025-08-21 21:56:55.411914 :C2DC17B4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20256 pso_num=22 pso_serial#=80
2025-08-21 21:56:55.412056 :C2DC17B5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20256 (legacy spawn)
2025-08-21 21:56:55.418912 :C2DC17B6: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-21 21:56:55.419105 :C2DC17BB: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-21 21:56:55.419949 :C2DC17C0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:56:55.429019 :C2DC17C3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:56:55.414
2025-08-21 21:56:55.429024 :C2DC17C4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21836 pso_num=22 pso_serial#=81
2025-08-21 21:56:55.429187 :C2DC17C5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21836 (legacy spawn)
2025-08-21 21:56:55.435874 :C2DC17C6: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-21 21:56:55.436034 :C2DC17CF: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-21 21:56:55.436940 :C2DC17D0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:56:57.114499 :C2DC1854:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:56:57.106
2025-08-21 21:56:57.114501 :C2DC1855:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21036 pso_num=22 pso_serial#=82
2025-08-21 21:56:57.114631 :C2DC1856:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21036 (legacy spawn)
2025-08-21 21:56:57.121117 :C2DC1857: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-21 21:56:57.121306 :C2DC1860: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-21 21:56:57.122123 :C2DC1861:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:57:17.529226 :C2DC1ED6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:57:17.523
2025-08-21 21:57:17.529228 :C2DC1ED7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8216 pso_num=22 pso_serial#=83
2025-08-21 21:57:17.529384 :C2DC1ED8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8216 osp_idx=0 osp_ver=539990 osp_pg=0 (spawn #512469)
2025-08-21 21:57:17.529385 :C2DC1ED9: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-21 21:57:17.529386 :C2DC1EDA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=95 time=199139533
2025-08-21 21:57:17.529386 :C2DC1EDB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-21 21:57:17.529387 :C2DC1EDC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-21 21:57:17.529387 :C2DC1EDD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-21 21:57:17.529387 :C2DC1EDE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-21 21:57:17.529388 :C2DC1EDF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-21 21:57:17.529892 :C2DC1EE0: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-21 21:57:17.530549 :C2DC1EF5: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-21 21:57:17.538168 :C2DC1F11:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-21 21:57:17.538552 :C2DC1F12: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-21 21:57:17.538597 :C2DC1F15:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-21 21:57:17.539416 :C2DC1F1B: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-21 21:57:17.539527 :C2DC1F24: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-21 21:57:47.596878 :C2DC2958:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:57:55.409926 :C2DC2BA4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:57:55.408
2025-08-21 21:57:55.409928 :C2DC2BA5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22496 pso_num=22 pso_serial#=84
2025-08-21 21:57:55.410103 :C2DC2BA6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22496 (legacy spawn)
2025-08-21 21:57:55.416515 :C2DC2BA7: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-21 21:57:55.416710 :C2DC2BAD: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-21 21:57:55.417497 :C2DC2BB1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:57:55.426202 :C2DC2BB4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:57:55.423
2025-08-21 21:57:55.426204 :C2DC2BB5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4764 pso_num=22 pso_serial#=85
2025-08-21 21:57:55.426336 :C2DC2BB6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4764 (legacy spawn)
2025-08-21 21:57:55.432872 :C2DC2BB7: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-21 21:57:55.433057 :C2DC2BBF: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-21 21:57:55.433859 :C2DC2BC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:04.901427 :C2DC2E8D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:04.886
2025-08-21 21:58:04.901429 :C2DC2E8E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21844 pso_num=22 pso_serial#=86
2025-08-21 21:58:04.901566 :C2DC2E8F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21844 (legacy spawn)
2025-08-21 21:58:04.908290 :C2DC2E90: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-21 21:58:04.908465 :C2DC2E95: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-21 21:58:04.909309 :C2DC2E9A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:04.918305 :C2DC2E9D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:04.917
2025-08-21 21:58:04.918307 :C2DC2E9E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21744 pso_num=22 pso_serial#=87
2025-08-21 21:58:04.918444 :C2DC2E9F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21744 (legacy spawn)
2025-08-21 21:58:04.925001 :C2DC2EA0: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-21 21:58:04.925162 :C2DC2EA9: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-21 21:58:04.926046 :C2DC2EAA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:05.238485 :C2DC2EDC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:05.230
2025-08-21 21:58:05.238488 :C2DC2EDD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22448 pso_num=22 pso_serial#=88
2025-08-21 21:58:05.238621 :C2DC2EDE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22448 (legacy spawn)
2025-08-21 21:58:05.262473 :C2DC2EDF: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-21 21:58:05.262644 :C2DC2EE8: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-21 21:58:05.263507 :C2DC2EE9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:08.082996 :C2DC2FDA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:08.070
2025-08-21 21:58:08.082998 :C2DC2FDB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4148 pso_num=22 pso_serial#=89
2025-08-21 21:58:08.083135 :C2DC2FDC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4148 (legacy spawn)
2025-08-21 21:58:08.089658 :C2DC2FDD: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-21 21:58:08.089832 :C2DC2FE2: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-21 21:58:08.090633 :C2DC2FE7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:08.109776 :C2DC2FEA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:08.101
2025-08-21 21:58:08.109778 :C2DC2FEB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17392 pso_num=22 pso_serial#=90
2025-08-21 21:58:08.109912 :C2DC2FEC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17392 (legacy spawn)
2025-08-21 21:58:08.116365 :C2DC2FED: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-21 21:58:08.116560 :C2DC2FF6: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-21 21:58:08.117370 :C2DC2FF7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:17.608200 :C2DC32D3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:17.602
2025-08-21 21:58:17.608203 :C2DC32D4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7572 pso_num=22 pso_serial#=91
2025-08-21 21:58:17.608344 :C2DC32D5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7572 osp_idx=0 osp_ver=539998 osp_pg=0 (spawn #512471)
2025-08-21 21:58:17.608344 :C2DC32D6: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-21 21:58:17.608345 :C2DC32D7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=96 time=199199612
2025-08-21 21:58:17.608345 :C2DC32D8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-21 21:58:17.608346 :C2DC32D9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-21 21:58:17.608346 :C2DC32DA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-21 21:58:17.608347 :C2DC32DB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-21 21:58:17.608347 :C2DC32DC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-21 21:58:17.608870 :C2DC32DD: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-21 21:58:17.609562 :C2DC32F2: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-21 21:58:17.620700 :C2DC330E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-08-21 21:58:17.621193 :C2DC330F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 
2025-08-21 21:58:17.623476 :C2DC3310:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235
2025-08-21 21:58:17.623823 :C2DC3311: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-21 21:58:17.623857 :C2DC3314:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235
2025-08-21 21:58:17.624799 :C2DC331A: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-21 21:58:17.624943 :C2DC3323: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-21 21:58:47.715248 :C2DC3CAC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:58:50.941920 :C2DC3DB6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:58:50.933
2025-08-21 21:58:50.941922 :C2DC3DB7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19972 pso_num=22 pso_serial#=92
2025-08-21 21:58:50.942065 :C2DC3DB8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19972 (legacy spawn)
2025-08-21 21:58:50.948615 :C2DC3DB9: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-21 21:58:50.948786 :C2DC3DC2: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-21 21:58:50.949624 :C2DC3DC3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:13.984058 :C2DC449C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:13.979
2025-08-21 21:59:13.984061 :C2DC449D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12612 pso_num=22 pso_serial#=93
2025-08-21 21:59:13.984213 :C2DC449E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12612 (legacy spawn)
2025-08-21 21:59:13.990733 :C2DC449F: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-21 21:59:13.990764 :C2DC44A2: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-21 21:59:13.990931 :C2DC44A5: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-21 21:59:13.991718 :C2DC44B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:17.694109 :C2DC45D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:17.679
2025-08-21 21:59:17.694112 :C2DC45D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21056 pso_num=22 pso_serial#=94
2025-08-21 21:59:17.694266 :C2DC45DA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21056 (legacy spawn)
2025-08-21 21:59:17.701349 :C2DC45FD: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-21 21:59:17.701534 :C2DC4602: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-21 21:59:17.702419 :C2DC4624:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:17.733878 :C2DC4662:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:17.726
2025-08-21 21:59:17.733880 :C2DC4663:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19496 pso_num=22 pso_serial#=95
2025-08-21 21:59:17.734020 :C2DC4664:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19496 (legacy spawn)
2025-08-21 21:59:17.740859 :C2DC4665: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-21 21:59:17.741042 :C2DC466E: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-21 21:59:17.742058 :C2DC466F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:20.787084 :C2DC4756:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:20.773
2025-08-21 21:59:20.787087 :C2DC4757:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21728 pso_num=22 pso_serial#=96
2025-08-21 21:59:20.787260 :C2DC4758:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21728 (legacy spawn)
2025-08-21 21:59:20.793710 :C2DC4759: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-21 21:59:20.793891 :C2DC4762: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-21 21:59:20.794721 :C2DC4763:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:20.803580 :C2DC4766:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:20.789
2025-08-21 21:59:20.803582 :C2DC4767:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21828 pso_num=22 pso_serial#=97
2025-08-21 21:59:20.803722 :C2DC4768:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21828 (legacy spawn)
2025-08-21 21:59:20.810272 :C2DC4769: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-21 21:59:20.810434 :C2DC4772: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-21 21:59:20.811263 :C2DC4773:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:22.016984 :C2DC47AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:22.008
2025-08-21 21:59:22.016985 :C2DC47AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20724 pso_num=22 pso_serial#=98
2025-08-21 21:59:22.017135 :C2DC47AC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20724 (legacy spawn)
2025-08-21 21:59:22.023682 :C2DC47AD: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-21 21:59:22.023866 :C2DC47B2: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-21 21:59:22.024706 :C2DC47B7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:23.517236 :C2DC48E9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:23.508
2025-08-21 21:59:23.517238 :C2DC48EA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7196 pso_num=22 pso_serial#=99
2025-08-21 21:59:23.517387 :C2DC48EB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7196 (legacy spawn)
2025-08-21 21:59:23.541422 :C2DC48EC: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-21 21:59:23.541615 :C2DC48F5: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-21 21:59:23.542593 :C2DC48F6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted
2025-08-21 21:59:25.154017 :C2DC4982:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-21 21:59:25.149
2025-08-21 21:59:25.154020 :C2DC4983:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22120 pso_num=22 pso_serial#=100
2025-08-21 21:59:25.154155 :C2DC4984:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22120 osp_idx=0 osp_ver=540007 osp_pg=0 (spawn #512475)
2025-08-21 21:59:25.154155 :C2DC4985: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-21 21:59:25.154156 :C2DC4986:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=97 time=199267158
2025-08-21 21:59:25.154157 :C2DC4987:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs
2025-08-21 21:59:25.154157 :C2DC4988:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs
2025-08-21 21:59:25.154157 :C2DC4989:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs
2025-08-21 21:59:25.154158 :C2DC498A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-08-21 21:59:25.154158 :C2DC498B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-08-21 21:59:25.154645 :C2DC498C: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-21 21:59:25.154688 :C2DC4993: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: 22120, W000)