Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504082024\orcl_j000_10684_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:37569M/63366M, Ph+PgF:45050M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 10684, image: ORACLE.EXE (J000) *** 2025-05-04 08:20:24.944 *** SESSION ID:(177.27819) 2025-05-04 08:20:24.944 *** 2025-05-04 08:20:24.944 Process diagnostic dump for ORACLE.EXE (J000), OS id=10684, pid: 22, proc_ser: 3, sid: 177, sess_ser: 27819 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 10684 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=0.084201 sec, exc=0.084201 sec, total=0.084201 sec wait times: max=0.500000 sec, heur=17.624816 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.000011 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=0.510084 sec, exc=0.510084 sec, total=0.510084 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 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.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=0.510107 sec, exc=0.510107 sec, total=0.510107 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 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=27 seq_num=28 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 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.000009 sec of elapsed time Sampled Session History of session 177 serial 27819 --------------------------------------------------- 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 --------------------------------------------------- [18 samples, 08:20:07 - 08:20:24] idle wait at each sample [session created at: 08:20:07] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-04 08:20:24.944 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 10684, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 08:18:07.155689 :927D5393:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.155694 :927D5394:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.155718 :927D5395:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.155814 :927D5396:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.155841 :927D5397:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.155847 :927D5398:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.155872 :927D5399:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.155966 :927D539A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.155993 :927D539B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.155998 :927D539C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.156023 :927D539D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.156116 :927D539E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.156143 :927D539F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.156179 :927D53A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.156428 :927D53A1: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-05-04 08:18:07.156465 :927D53A4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.168397 :927D53AA: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-05-04 08:18:07.168465 :927D53AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-04 08:18:07.168496 :927D53AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-04 08:18:07.170436 :927D53B4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.170480 :927D53B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.170503 :927D53B6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.170530 :927D53B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.170854 :927D53B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.170890 :927D53B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.171391 :927D53BA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.171424 :927D53BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.171532 :927D53BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.171560 :927D53BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.171653 :927D53BE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.171680 :927D53BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.171803 :927D53C0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.171850 :927D53C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.171994 :927D53C2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.172023 :927D53C3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.172068 :927D53C4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.172320 :927D53C5: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-05-04 08:18:07.172359 :927D53C8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.178652 :927D53CE: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-05-04 08:18:07.178711 :927D53D1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-04 08:18:07.178741 :927D53D2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-04 08:18:07.180521 :927D53D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.180568 :927D53D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.180754 :927D53DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.180969 :927D53DB: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-05-04 08:18:07.181005 :927D53DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.185420 :927D53E4: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-05-04 08:18:07.185480 :927D53E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-04 08:18:07.185514 :927D53E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-04 08:18:07.187340 :927D53EE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.187385 :927D53EF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.187792 :927D53F0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.188039 :927D53F1: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-05-04 08:18:07.188075 :927D53F4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.193966 :927D53FA: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-05-04 08:18:07.194024 :927D53FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-04 08:18:07.194055 :927D53FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-04 08:18:07.195767 :927D5404:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.195810 :927D5405:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.195947 :927D5406:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.195976 :927D5407:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.196001 :927D5408:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.196225 :927D5409: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-05-04 08:18:07.196256 :927D540C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.200793 :927D5412: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-05-04 08:18:07.200850 :927D5415:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-04 08:18:07.200882 :927D5416:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-04 08:18:07.202671 :927D541C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.202716 :927D541D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.205915 :927D541E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.206194 :927D541F: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-05-04 08:18:07.206236 :927D5422:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.211196 :927D5428: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-05-04 08:18:07.211248 :927D542B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-05-04 08:18:07.211278 :927D542C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-05-04 08:18:07.211557 :927D5432:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 08:18:07.211864 :927D5433:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 08:18:07.211978 :927D5434:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.212172 :927D5435: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-05-04 08:18:07.212193 :927D5437:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.213784 :927D543E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:18:07.214066 :927D543F: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-05-04 08:18:07.214093 :927D5442:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:18:07.214934 :927D5448: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-05-04 08:18:07.215052 :927D5451: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-05-04 08:18:37.321673 :927D5E23:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:18:44.829198 :927D636F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:18:44.821 2025-05-04 08:18:44.829200 :927D6370:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12072 pso_num=22 pso_serial#=250 2025-05-04 08:18:44.829337 :927D6371:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12072 (legacy spawn) 2025-05-04 08:18:44.835761 :927D6372: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-05-04 08:18:44.835936 :927D6377: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-05-04 08:18:44.836738 :927D637C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:18:55.170651 :927D6699:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:18:55.161 2025-05-04 08:18:55.170653 :927D669A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9208 pso_num=22 pso_serial#=251 2025-05-04 08:18:55.170782 :927D669B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9208 (legacy spawn) 2025-05-04 08:18:55.177202 :927D669C: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-05-04 08:18:55.177372 :927D66A5: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-05-04 08:18:55.178173 :927D66A6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:18:55.410455 :927D66B5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:18:55.395 2025-05-04 08:18:55.410457 :927D66B6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9492 pso_num=22 pso_serial#=252 2025-05-04 08:18:55.410585 :927D66B7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9492 (legacy spawn) 2025-05-04 08:18:55.417038 :927D66B8: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-05-04 08:18:55.417214 :927D66C1: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-05-04 08:18:55.418021 :927D66C2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:18:55.426584 :927D66C5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:18:55.411 2025-05-04 08:18:55.426586 :927D66C6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12012 pso_num=22 pso_serial#=253 2025-05-04 08:18:55.426728 :927D66C7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12012 (legacy spawn) 2025-05-04 08:18:55.433167 :927D66C8: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-05-04 08:18:55.433328 :927D66D1: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-05-04 08:18:55.434143 :927D66D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:18:58.544101 :927D67C4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:18:58.531 2025-05-04 08:18:58.544104 :927D67C5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8332 pso_num=22 pso_serial#=254 2025-05-04 08:18:58.544239 :927D67C6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8332 (legacy spawn) 2025-05-04 08:18:58.550763 :927D67C7: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-05-04 08:18:58.550950 :927D67CD: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-05-04 08:18:58.551749 :927D67D1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:18:58.560442 :927D67D4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:18:58.547 2025-05-04 08:18:58.560444 :927D67D5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8872 pso_num=22 pso_serial#=255 2025-05-04 08:18:58.560581 :927D67D6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8872 (legacy spawn) 2025-05-04 08:18:58.566998 :927D67D7: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-05-04 08:18:58.567161 :927D67E0: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-05-04 08:18:58.567936 :927D67E1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:19:07.212008 :927D6AB5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:19:07.206 2025-05-04 08:19:07.212010 :927D6AB6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9984 pso_num=22 pso_serial#=1 2025-05-04 08:19:07.212143 :927D6AB7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9984 osp_idx=0 osp_ver=153001 osp_pg=0 (spawn #146799) 2025-05-04 08:19:07.212144 :927D6AB8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:19:07.212144 :927D6AB9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=194 time=3617524375 2025-05-04 08:19:07.212144 :927D6ABA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:19:07.212145 :927D6ABB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:19:07.212145 :927D6ABC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:19:07.212145 :927D6ABD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:19:07.212146 :927D6ABE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:19:07.212628 :927D6ABF: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-05-04 08:19:07.213365 :927D6AD4: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-05-04 08:19:07.220872 :927D6AF0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:19:07.221234 :927D6AF1: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-05-04 08:19:07.221276 :927D6AF4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:19:07.222318 :927D6AFA: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-05-04 08:19:07.222562 :927D6B03: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-05-04 08:19:37.346878 :927D749C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:20:03.472362 :927D7C9B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:20:03.463 2025-05-04 08:20:03.472363 :927D7C9C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9584 pso_num=22 pso_serial#=2 2025-05-04 08:20:03.472505 :927D7C9D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9584 (legacy spawn) 2025-05-04 08:20:03.478961 :927D7C9E: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-05-04 08:20:03.479133 :927D7CA3: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-05-04 08:20:03.479908 :927D7CA8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 08:20:07.307363 :927D7DB4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 08:20:07.301 2025-05-04 08:20:07.307366 :927D7DB5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10684 pso_num=22 pso_serial#=3 2025-05-04 08:20:07.307547 :927D7DB6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10684 osp_idx=0 osp_ver=153003 osp_pg=0 (spawn #146801) 2025-05-04 08:20:07.307548 :927D7DB7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 08:20:07.307548 :927D7DB8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=3617584468 2025-05-04 08:20:07.307549 :927D7DB9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 08:20:07.307550 :927D7DBA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 08:20:07.307550 :927D7DBB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 08:20:07.307550 :927D7DBC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 08:20:07.307551 :927D7DBD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 08:20:07.308052 :927D7DBE: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-05-04 08:20:07.308669 :927D7DD3: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-05-04 08:20:07.319800 :927D7DEF:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 08:20:07.320282 :927D7DF0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-04 08:20:07.322606 :927D7DF1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-05-04 08:20:07.322951 :927D7DF2: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-05-04 08:20:07.322989 :927D7DF5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-05-04 08:20:07.323927 :927D7DFB: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-05-04 08:20:07.324061 :927D7E04: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 10684, J000)