Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915082514\orcl_j000_24108_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:34735M/63366M, Ph+PgF:39315M/72582M 
Instance name: orcl
Redo thread mounted by this instance: 1
Oracle process number: 24
Windows thread id: 24108, image: ORACLE.EXE (J000)


*** 2025-09-15 08:25:14.244
*** SESSION ID:(193.40675) 2025-09-15 08:25:14.244
 

*** 2025-09-15 08:25:14.244
Process diagnostic dump for ORACLE.EXE (J000), OS id=24108,
pid: 24, proc_ser: 201, sid: 193, sess_ser: 40675 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 24108
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=49 seq_num=50 snap_id=1
    wait times: snap=0.203382 sec, exc=0.203382 sec, total=0.203382 sec
    wait times: max=0.500000 sec, heur=24.764562 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.000019 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=48 seq_num=49 snap_id=1
    wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000014 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=47 seq_num=48 snap_id=1
    wait times: snap=0.499983 sec, exc=0.499983 sec, total=0.499983 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000019 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=46 seq_num=47 snap_id=1
    wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000018 sec of elapsed time
 3: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=45 seq_num=46 snap_id=1
    wait times: snap=0.499985 sec, exc=0.499985 sec, total=0.499985 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000012 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=44 seq_num=45 snap_id=1
    wait times: snap=0.510106 sec, exc=0.510106 sec, total=0.510106 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000014 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=43 seq_num=44 snap_id=1
    wait times: snap=0.499986 sec, exc=0.499986 sec, total=0.499986 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=42 seq_num=43 snap_id=1
    wait times: snap=0.499949 sec, exc=0.499949 sec, total=0.499949 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000064 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=41 seq_num=42 snap_id=1
    wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=40 seq_num=41 snap_id=1
    wait times: snap=0.499983 sec, exc=0.499983 sec, total=0.499983 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000019 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=39 seq_num=40 snap_id=1
    wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 sec of elapsed time
Sampled Session History of session 193 serial 40675
---------------------------------------------------
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
---------------------------------------------------
  [25 samples,                                             08:24:49 - 08:25:14]
    idle wait at each sample
  [session created at: 08:24:49]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-15 08:25:14.244
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 24108, J000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-15 08:21:49.211258 :CDAA26AC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:21:49.206
2025-09-15 08:21:49.211260 :CDAA26AD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20568 pso_num=24 pso_serial#=181
2025-09-15 08:21:49.211401 :CDAA26AE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20568 osp_idx=22 osp_ver=894820 osp_pg=0 (spawn #593951)
2025-09-15 08:21:49.211401 :CDAA26AF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 08:21:49.211402 :CDAA26B0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=79 time=2310160518
2025-09-15 08:21:49.211402 :CDAA26B1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 08:21:49.211403 :CDAA26B2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 08:21:49.211403 :CDAA26B3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 08:21:49.211403 :CDAA26B4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 08:21:49.211403 :CDAA26B5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 08:21:49.211903 :CDAA26B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 08:21:49.212555 :CDAA26CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:21:49.220127 :CDAA26E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-15 08:21:49.220502 :CDAA26E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:21:49.220540 :CDAA26EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-15 08:21:49.221480 :CDAA26F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:21:49.221606 :CDAA26FA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:22:19.304489 :CDAA30FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:22:28.566034 :CDAA33C8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:22:28.550
2025-09-15 08:22:28.566036 :CDAA33C9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21540 pso_num=24 pso_serial#=182
2025-09-15 08:22:28.566183 :CDAA33CA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21540 (legacy spawn)
2025-09-15 08:22:28.572773 :CDAA33CB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:28.572944 :CDAA33D0:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:22:28.573781 :CDAA33D5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:22:35.882655 :CDAA35CE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:22:35.875
2025-09-15 08:22:35.882658 :CDAA35CF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25360 pso_num=24 pso_serial#=183
2025-09-15 08:22:35.882802 :CDAA35D0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25360 (legacy spawn)
2025-09-15 08:22:35.889795 :CDAA35D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:35.889987 :CDAA35D6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:22:35.890921 :CDAA35DB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:22:35.900559 :CDAA35DE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:22:35.891
2025-09-15 08:22:35.900561 :CDAA35DF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16548 pso_num=24 pso_serial#=184
2025-09-15 08:22:35.900708 :CDAA35E0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16548 (legacy spawn)
2025-09-15 08:22:35.907717 :CDAA35E1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:35.907898 :CDAA35EA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:22:35.908782 :CDAA35EB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:22:48.522318 :CDAA398D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:22:48.508
2025-09-15 08:22:48.522320 :CDAA398E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19212 pso_num=24 pso_serial#=185
2025-09-15 08:22:48.522461 :CDAA398F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19212 (legacy spawn)
2025-09-15 08:22:48.528989 :CDAA3990:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:48.529184 :CDAA3995:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:22:48.529988 :CDAA399A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:22:48.538753 :CDAA39AE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:22:48.539
2025-09-15 08:22:48.538754 :CDAA39AF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23152 pso_num=24 pso_serial#=186
2025-09-15 08:22:48.538898 :CDAA39B0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23152 (legacy spawn)
2025-09-15 08:22:48.545419 :CDAA39B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:48.545583 :CDAA39BA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:22:48.546389 :CDAA39BB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:22:49.304882 :CDAA3A7B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:22:49.299
2025-09-15 08:22:49.304885 :CDAA3A7C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23320 pso_num=24 pso_serial#=187
2025-09-15 08:22:49.305024 :CDAA3A7D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=23320 osp_idx=22 osp_ver=894826 osp_pg=0 (spawn #593953)
2025-09-15 08:22:49.305024 :CDAA3A7E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 08:22:49.305025 :CDAA3A7F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=80 time=2310220612
2025-09-15 08:22:49.305025 :CDAA3A80:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 08:22:49.305025 :CDAA3A81:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 08:22:49.305026 :CDAA3A82:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 08:22:49.305026 :CDAA3A83:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 08:22:49.305028 :CDAA3A84:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 08:22:49.305518 :CDAA3A85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 08:22:49.306175 :CDAA3A9A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:49.317247 :CDAA3AB6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-15 08:22:49.317723 :CDAA3AB7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-15 08:22:49.320100 :CDAA3AB8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-15 08:22:49.320442 :CDAA3AB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:49.320479 :CDAA3ABC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-15 08:22:49.321363 :CDAA3AC2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:22:49.321491 :CDAA3ACB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:19.395491 :CDAA4497:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:24.918720 :CDAA45FF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:24.915
2025-09-15 08:23:24.918721 :CDAA4600:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19144 pso_num=24 pso_serial#=188
2025-09-15 08:23:24.918860 :CDAA4601:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19144 (legacy spawn)
2025-09-15 08:23:24.925370 :CDAA4602:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:24.925554 :CDAA4608:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:24.926355 :CDAA460C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:24.935008 :CDAA460F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:24.931
2025-09-15 08:23:24.935011 :CDAA4610:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23808 pso_num=24 pso_serial#=189
2025-09-15 08:23:24.935157 :CDAA4611:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23808 (legacy spawn)
2025-09-15 08:23:24.941605 :CDAA4612:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:24.941637 :CDAA4615:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:24.941797 :CDAA461B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:24.942611 :CDAA4629:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:28.000898 :CDAA4778:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:27.988
2025-09-15 08:23:28.000900 :CDAA4779:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25120 pso_num=24 pso_serial#=190
2025-09-15 08:23:28.001042 :CDAA477A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25120 (legacy spawn)
2025-09-15 08:23:28.007613 :CDAA477B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:28.007792 :CDAA4780:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:28.008593 :CDAA4785:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:28.017333 :CDAA4788:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:28.003
2025-09-15 08:23:28.017335 :CDAA4789:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25312 pso_num=24 pso_serial#=191
2025-09-15 08:23:28.017475 :CDAA478A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25312 (legacy spawn)
2025-09-15 08:23:28.024059 :CDAA478B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:28.024228 :CDAA4793:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:28.025070 :CDAA4795:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:36.374961 :CDAA49B2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:36.366
2025-09-15 08:23:36.374963 :CDAA49B3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23064 pso_num=24 pso_serial#=192
2025-09-15 08:23:36.375106 :CDAA49B4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23064 (legacy spawn)
2025-09-15 08:23:36.381626 :CDAA49B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:36.381799 :CDAA49BA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:36.382639 :CDAA49BF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:46.963192 :CDAA4D68:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:46.954
2025-09-15 08:23:46.963194 :CDAA4D69:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24480 pso_num=24 pso_serial#=193
2025-09-15 08:23:46.963339 :CDAA4D6A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24480 (legacy spawn)
2025-09-15 08:23:46.987539 :CDAA4D6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:46.987714 :CDAA4D74:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:23:46.988581 :CDAA4D75:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:23:49.366600 :CDAA4E66:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:23:49.361
2025-09-15 08:23:49.366602 :CDAA4E67:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24920 pso_num=24 pso_serial#=194
2025-09-15 08:23:49.366751 :CDAA4E68:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24920 osp_idx=22 osp_ver=894833 osp_pg=0 (spawn #593955)
2025-09-15 08:23:49.366752 :CDAA4E69:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 08:23:49.366752 :CDAA4E6A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=81 time=2310280674
2025-09-15 08:23:49.366752 :CDAA4E6B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 08:23:49.366753 :CDAA4E6C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 08:23:49.366753 :CDAA4E6D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 08:23:49.366753 :CDAA4E6E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 08:23:49.366754 :CDAA4E6F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 08:23:49.367256 :CDAA4E70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 08:23:49.367921 :CDAA4E85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:49.375512 :CDAA4EA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-15 08:23:49.375886 :CDAA4EA2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:49.375921 :CDAA4EA5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-15 08:23:49.376871 :CDAA4EAB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:23:49.377000 :CDAA4EB4:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:19.465761 :CDAA58D3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:37.589859 :CDAA5E45:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:37.586
2025-09-15 08:24:37.589861 :CDAA5E46:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12108 pso_num=24 pso_serial#=195
2025-09-15 08:24:37.590007 :CDAA5E47:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12108 (legacy spawn)
2025-09-15 08:24:37.596497 :CDAA5E48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:37.596665 :CDAA5E51:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:37.597474 :CDAA5E52:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:37.606219 :CDAA5E55:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:37.602
2025-09-15 08:24:37.606221 :CDAA5E56:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23704 pso_num=24 pso_serial#=196
2025-09-15 08:24:37.606355 :CDAA5E57:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23704 (legacy spawn)
2025-09-15 08:24:37.612842 :CDAA5E58:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:37.612875 :CDAA5E5B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:37.613029 :CDAA5E62:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:37.613843 :CDAA5E6F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:40.887139 :CDAA5F72:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:40.872
2025-09-15 08:24:40.887141 :CDAA5F73:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25436 pso_num=24 pso_serial#=197
2025-09-15 08:24:40.887288 :CDAA5F74:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25436 (legacy spawn)
2025-09-15 08:24:40.894177 :CDAA5F75:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:40.894374 :CDAA5F7D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:40.895299 :CDAA5F7F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:40.904954 :CDAA5F82:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:40.904
2025-09-15 08:24:40.904956 :CDAA5F83:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24268 pso_num=24 pso_serial#=198
2025-09-15 08:24:40.905101 :CDAA5F84:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24268 (legacy spawn)
2025-09-15 08:24:40.911973 :CDAA5F85:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:40.912141 :CDAA5F8E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:40.913017 :CDAA5F8F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:44.547849 :CDAA608F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:44.536
2025-09-15 08:24:44.547851 :CDAA6090:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19728 pso_num=24 pso_serial#=199
2025-09-15 08:24:44.548033 :CDAA6091:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19728 (legacy spawn)
2025-09-15 08:24:44.554606 :CDAA6092:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:44.554639 :CDAA6095:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:44.554814 :CDAA609A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:44.555672 :CDAA60A9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:49.119422 :CDAA61E8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:49.112
2025-09-15 08:24:49.119424 :CDAA61E9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23612 pso_num=24 pso_serial#=200
2025-09-15 08:24:49.119573 :CDAA61EA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23612 (legacy spawn)
2025-09-15 08:24:49.126168 :CDAA61EB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:49.126198 :CDAA61EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:49.126348 :CDAA61F1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-15 08:24:49.127150 :CDAA6202:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-15 08:24:49.461026 :CDAA6296:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-15 08:24:49.456
2025-09-15 08:24:49.461028 :CDAA6297:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24108 pso_num=24 pso_serial#=201
2025-09-15 08:24:49.461160 :CDAA6298:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24108 osp_idx=22 osp_ver=894840 osp_pg=0 (spawn #593957)
2025-09-15 08:24:49.461161 :CDAA6299:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A
2025-09-15 08:24:49.461161 :CDAA629A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=82 time=2310340768
2025-09-15 08:24:49.461161 :CDAA629B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-15 08:24:49.461162 :CDAA629C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-15 08:24:49.461162 :CDAA629D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-15 08:24:49.461162 :CDAA629E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-15 08:24:49.461162 :CDAA629F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-15 08:24:49.461637 :CDAA62A0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name=   type=0
2025-09-15 08:24:49.462266 :CDAA62B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:49.473304 :CDAA62D1:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-15 08:24:49.473759 :CDAA62D2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-15 08:24:49.476112 :CDAA62D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-15 08:24:49.476447 :CDAA62D4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:49.476483 :CDAA62D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-15 08:24:49.477361 :CDAA62DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name=   type=0
2025-09-15 08:24:49.477487 :CDAA62E6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1
-------------------------------------------------------------------------------
Trace Bucket Dump End: default bucket for process 24 (osid: 24108, J000)