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


*** 2025-09-17 08:26:41.497
*** SESSION ID:(193.653) 2025-09-17 08:26:41.497
 

*** 2025-09-17 08:26:41.497
Process diagnostic dump for ORACLE.EXE (J000), OS id=22380,
pid: 24, proc_ser: 179, sid: 193, sess_ser: 653 
-------------------------------------------------------------------------------
current sql: <none>
client details:
  O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 22380
  machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000)
Current Wait Stack:
 0: waiting for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=54 seq_num=55 snap_id=1
    wait times: snap=0.219476 sec, exc=0.219476 sec, total=0.219476 sec
    wait times: max=0.500000 sec, heur=27.315260 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.000016 sec since current wait
 0: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=53 seq_num=54 snap_id=1
    wait times: snap=0.500004 sec, exc=0.500004 sec, total=0.500004 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000013 sec of elapsed time
 1: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=52 seq_num=53 snap_id=1
    wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000018 sec of elapsed time
 2: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=51 seq_num=52 snap_id=1
    wait times: snap=0.499981 sec, exc=0.499981 sec, total=0.499981 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=50 seq_num=51 snap_id=1
    wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000016 sec of elapsed time
 4: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=49 seq_num=50 snap_id=1
    wait times: snap=0.499982 sec, exc=0.499982 sec, total=0.499982 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 5: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=48 seq_num=49 snap_id=1
    wait times: snap=0.500136 sec, exc=0.500136 sec, total=0.500136 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000020 sec of elapsed time
 6: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=47 seq_num=48 snap_id=1
    wait times: snap=0.500012 sec, exc=0.500012 sec, total=0.500012 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000007 sec of elapsed time
 7: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=46 seq_num=47 snap_id=1
    wait times: snap=0.499977 sec, exc=0.499977 sec, total=0.499977 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000015 sec of elapsed time
 8: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=45 seq_num=46 snap_id=1
    wait times: snap=0.500014 sec, exc=0.500014 sec, total=0.500014 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000006 sec of elapsed time
 9: waited for 'jobq slave wait'
    =0x0, =0x0, =0x0
    wait_id=44 seq_num=45 snap_id=1
    wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec
    wait times: max=0.500000 sec
    wait counts: calls=1 os=1
    occurred after 0.000010 sec of elapsed time
Sampled Session History of session 193 serial 653
---------------------------------------------------
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
---------------------------------------------------
  [28 samples,                                             08:26:14 - 08:26:41]
    idle wait at each sample
  [session created at: 08:26:14]
-------------------------------------------------------------------------------
Process diagnostic dump actual duration=0.000000 sec
  (max dump time=30.000000 sec)

*** 2025-09-17 08:26:41.497
 
-------------------------------------------------------------------------------
Trace Bucket Dump Begin: default bucket for process 24 (osid: 22380, J000)
TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA
-------------------------------------------------------------------------------
2025-09-17 08:23:11.326040 :CE8E421D: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-17 08:23:11.326957 :CE8E4222:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:23:13.893312 :CE8E4322:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:23:13.888
2025-09-17 08:23:13.893315 :CE8E4323:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24292 pso_num=24 pso_serial#=161
2025-09-17 08:23:13.893456 :CE8E4324:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24292 osp_idx=22 osp_ver=905765 osp_pg=0 (spawn #600625)
2025-09-17 08:23:13.893457 :CE8E4325: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-17 08:23:13.893457 :CE8E4326:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=152 time=2483045190
2025-09-17 08:23:13.893458 :CE8E4327:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 08:23:13.893458 :CE8E4328:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 08:23:13.893458 :CE8E4329:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 08:23:13.893459 :CE8E432A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 08:23:13.893459 :CE8E432B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 08:23:13.893993 :CE8E432C: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-17 08:23:13.894693 :CE8E4341: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-17 08:23:13.902720 :CE8E435D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:23:13.903101 :CE8E435E: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-17 08:23:13.903137 :CE8E4361:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:23:13.904017 :CE8E4367: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-17 08:23:13.904141 :CE8E4370: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-17 08:23:44.011363 :CE8E4D92:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:23:46.493229 :CE8E4E38:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:23:46.480
2025-09-17 08:23:46.493231 :CE8E4E39:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25468 pso_num=24 pso_serial#=162
2025-09-17 08:23:46.493365 :CE8E4E3A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25468 (legacy spawn)
2025-09-17 08:23:46.499985 :CE8E4E3B: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-17 08:23:46.500157 :CE8E4E44: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-17 08:23:46.500940 :CE8E4E45:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:23:46.509616 :CE8E4E48:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:23:46.495
2025-09-17 08:23:46.509618 :CE8E4E49:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25388 pso_num=24 pso_serial#=163
2025-09-17 08:23:46.509747 :CE8E4E4A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25388 (legacy spawn)
2025-09-17 08:23:46.516314 :CE8E4E4B: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-17 08:23:46.516466 :CE8E4E54: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-17 08:23:46.517320 :CE8E4E55:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:02.294441 :CE8E52FF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:02.280
2025-09-17 08:24:02.294443 :CE8E5300:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24708 pso_num=24 pso_serial#=164
2025-09-17 08:24:02.294610 :CE8E5301:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24708 (legacy spawn)
2025-09-17 08:24:02.301116 :CE8E5302: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-17 08:24:02.301302 :CE8E5307: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-17 08:24:02.302091 :CE8E530C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:02.310811 :CE8E530F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:02.295
2025-09-17 08:24:02.310813 :CE8E5310:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24996 pso_num=24 pso_serial#=165
2025-09-17 08:24:02.310979 :CE8E5311:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24996 (legacy spawn)
2025-09-17 08:24:02.317568 :CE8E5312: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-17 08:24:02.317729 :CE8E531B: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-17 08:24:02.318525 :CE8E531C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:05.485050 :CE8E540B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:05.472
2025-09-17 08:24:05.485051 :CE8E540C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25100 pso_num=24 pso_serial#=166
2025-09-17 08:24:05.485190 :CE8E540D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25100 (legacy spawn)
2025-09-17 08:24:05.505017 :CE8E540E: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-17 08:24:05.505189 :CE8E5413: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-17 08:24:05.506050 :CE8E5418:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:05.514842 :CE8E541B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:05.503
2025-09-17 08:24:05.514845 :CE8E541C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23728 pso_num=24 pso_serial#=167
2025-09-17 08:24:05.514974 :CE8E541D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23728 (legacy spawn)
2025-09-17 08:24:05.521580 :CE8E541E: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-17 08:24:05.521742 :CE8E5427: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-17 08:24:05.522549 :CE8E5428:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:13.479780 :CE8E569E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:13.471
2025-09-17 08:24:13.479782 :CE8E569F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23940 pso_num=24 pso_serial#=168
2025-09-17 08:24:13.479935 :CE8E56A0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23940 (legacy spawn)
2025-09-17 08:24:13.486594 :CE8E56A1: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-17 08:24:13.486785 :CE8E56AA: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-17 08:24:13.487636 :CE8E56AB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:13.977099 :CE8E5700:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:13.971
2025-09-17 08:24:13.977100 :CE8E5701:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24308 pso_num=24 pso_serial#=169
2025-09-17 08:24:13.977244 :CE8E5702:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=24308 osp_idx=22 osp_ver=905773 osp_pg=0 (spawn #600627)
2025-09-17 08:24:13.977245 :CE8E5703: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-17 08:24:13.977245 :CE8E5704:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=153 time=2483105283
2025-09-17 08:24:13.977246 :CE8E5705:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 08:24:13.977246 :CE8E5706:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 08:24:13.977247 :CE8E5707:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 08:24:13.977247 :CE8E5708:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 08:24:13.977247 :CE8E5709:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 08:24:13.977781 :CE8E570A: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-17 08:24:13.978443 :CE8E571F: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-17 08:24:13.989745 :CE8E573B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-17 08:24:13.990217 :CE8E573C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-17 08:24:13.992769 :CE8E573D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:24:13.993127 :CE8E573E: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-17 08:24:13.993169 :CE8E5741:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:24:13.994089 :CE8E5747: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-17 08:24:13.994209 :CE8E5750: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-17 08:24:44.072248 :CE8E60AB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:44.549243 :CE8E60AE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:44.541
2025-09-17 08:24:44.549245 :CE8E60AF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24632 pso_num=24 pso_serial#=170
2025-09-17 08:24:44.549383 :CE8E60B0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24632 (legacy spawn)
2025-09-17 08:24:44.556039 :CE8E60B1: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-17 08:24:44.556232 :CE8E60B6: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-17 08:24:44.557073 :CE8E60BB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:24:50.834807 :CE8E6292:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:24:50.826
2025-09-17 08:24:50.834809 :CE8E6293:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=6364 pso_num=24 pso_serial#=171
2025-09-17 08:24:50.834942 :CE8E6294:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=6364 (legacy spawn)
2025-09-17 08:24:50.841763 :CE8E6295: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-17 08:24:50.841939 :CE8E629A: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-17 08:24:50.842788 :CE8E629F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:14.072291 :CE8E69C9:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:14.065
2025-09-17 08:25:14.072292 :CE8E69CA:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11176 pso_num=24 pso_serial#=172
2025-09-17 08:25:14.072455 :CE8E69CB:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11176 osp_idx=22 osp_ver=905776 osp_pg=0 (spawn #600629)
2025-09-17 08:25:14.072456 :CE8E69CC: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-17 08:25:14.072456 :CE8E69CD:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=154 time=2483165377
2025-09-17 08:25:14.072457 :CE8E69CE:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 08:25:14.072457 :CE8E69CF:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 08:25:14.072458 :CE8E69D0:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 08:25:14.072458 :CE8E69D1:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 08:25:14.072459 :CE8E69D2:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 08:25:14.073107 :CE8E69D3: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-17 08:25:14.073837 :CE8E69E8: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-17 08:25:14.082413 :CE8E6A04:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:25:14.082851 :CE8E6A05: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-17 08:25:14.082887 :CE8E6A08:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:25:14.083928 :CE8E6A0E: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-17 08:25:14.084077 :CE8E6A17: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-17 08:25:44.174140 :CE8E73B8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:46.722915 :CE8E747A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:46.710
2025-09-17 08:25:46.722917 :CE8E747B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25488 pso_num=24 pso_serial#=173
2025-09-17 08:25:46.723078 :CE8E747C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25488 (legacy spawn)
2025-09-17 08:25:46.729701 :CE8E747D: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-17 08:25:46.729872 :CE8E7485: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-17 08:25:46.730684 :CE8E7487:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:51.704734 :CE8E75B0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:51.693
2025-09-17 08:25:51.704736 :CE8E75B1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25268 pso_num=24 pso_serial#=174
2025-09-17 08:25:51.704873 :CE8E75B2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25268 (legacy spawn)
2025-09-17 08:25:51.711517 :CE8E75B3: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-17 08:25:51.711693 :CE8E75B8: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-17 08:25:51.712526 :CE8E75BD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:51.721319 :CE8E75C0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:51.709
2025-09-17 08:25:51.721321 :CE8E75C1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20856 pso_num=24 pso_serial#=175
2025-09-17 08:25:51.721461 :CE8E75C2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20856 (legacy spawn)
2025-09-17 08:25:51.728010 :CE8E75C3: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-17 08:25:51.728175 :CE8E75CB: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-17 08:25:51.728971 :CE8E75CD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:54.889026 :CE8E76BD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:54.875
2025-09-17 08:25:54.889028 :CE8E76BE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=25440 pso_num=24 pso_serial#=176
2025-09-17 08:25:54.889176 :CE8E76BF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=25440 (legacy spawn)
2025-09-17 08:25:54.895826 :CE8E76C0: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-17 08:25:54.895996 :CE8E76C5: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-17 08:25:54.896793 :CE8E76CA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:54.914533 :CE8E76CD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:54.906
2025-09-17 08:25:54.914535 :CE8E76CE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=24532 pso_num=24 pso_serial#=177
2025-09-17 08:25:54.914670 :CE8E76CF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=24532 (legacy spawn)
2025-09-17 08:25:54.921319 :CE8E76D0: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-17 08:25:54.921496 :CE8E76D9: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-17 08:25:54.922352 :CE8E76DA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:25:58.932355 :CE8E7881:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:25:58.924
2025-09-17 08:25:58.932357 :CE8E7882:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=23864 pso_num=24 pso_serial#=178
2025-09-17 08:25:58.932496 :CE8E7883:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=23864 (legacy spawn)
2025-09-17 08:25:58.939221 :CE8E7884: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-17 08:25:58.939392 :CE8E788D: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-17 08:25:58.940220 :CE8E788E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted
2025-09-17 08:26:14.149598 :CE8E7D44:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 09-17 08:26:14.143
2025-09-17 08:26:14.149599 :CE8E7D45:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22380 pso_num=24 pso_serial#=179
2025-09-17 08:26:14.149743 :CE8E7D46:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=22380 osp_idx=22 osp_ver=905783 osp_pg=0 (spawn #600632)
2025-09-17 08:26:14.149744 :CE8E7D47: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-17 08:26:14.149744 :CE8E7D48:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=155 time=2483225455
2025-09-17 08:26:14.149745 :CE8E7D49:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs
2025-09-17 08:26:14.149745 :CE8E7D4A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs
2025-09-17 08:26:14.149746 :CE8E7D4B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs
2025-09-17 08:26:14.149746 :CE8E7D4C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs
2025-09-17 08:26:14.149746 :CE8E7D4D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs
2025-09-17 08:26:14.150348 :CE8E7D4E: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-17 08:26:14.151077 :CE8E7D63: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-17 08:26:14.166309 :CE8E7D7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:26:14.166395 :CE8E7D80:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:26:14.167350 :CE8E7D81:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:26:14.167691 :CE8E7D82: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-17 08:26:14.167730 :CE8E7D85:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:26:14.176369 :CE8E7D8B: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-17 08:26:14.176439 :CE8E7D8E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879
2025-09-17 08:26:14.176479 :CE8E7D8F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879
2025-09-17 08:26:14.176817 :CE8E7D95:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-17 08:26:14.177161 :CE8E7D96:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 
2025-09-17 08:26:14.177340 :CE8E7D97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:26:14.177601 :CE8E7D98: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-17 08:26:14.177641 :CE8E7D9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:26:14.179323 :CE8E7DA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235
2025-09-17 08:26:14.179566 :CE8E7DA2: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-17 08:26:14.179597 :CE8E7DA5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235
2025-09-17 08:26:14.180549 :CE8E7DAB: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-17 08:26:14.180665 :CE8E7DB4: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: 22380, J000)