Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250831081748\orcl_j000_19784_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:35070M/63366M, Ph+PgF:40024M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 19784, image: ORACLE.EXE (J000) *** 2025-08-31 08:17:48.157 *** SESSION ID:(201.9589) 2025-08-31 08:17:48.157 *** 2025-08-31 08:17:48.157 Process diagnostic dump for ORACLE.EXE (J000), OS id=19784, pid: 25, proc_ser: 80, sid: 201, sess_ser: 9589 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19784 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=52 seq_num=53 snap_id=1 wait times: snap=0.022393 sec, exc=0.022393 sec, total=0.022393 sec wait times: max=0.500000 sec, heur=26.090567 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=51 seq_num=52 snap_id=1 wait times: snap=0.510102 sec, exc=0.510102 sec, total=0.510102 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=50 seq_num=51 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.000019 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=49 seq_num=50 snap_id=1 wait times: snap=0.499976 sec, exc=0.499976 sec, total=0.499976 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=48 seq_num=49 snap_id=1 wait times: snap=0.499984 sec, exc=0.499984 sec, total=0.499984 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=47 seq_num=48 snap_id=1 wait times: snap=0.499946 sec, exc=0.499946 sec, total=0.499946 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000068 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 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.000016 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000016 sec of elapsed time Sampled Session History of session 201 serial 9589 --------------------------------------------------- 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 --------------------------------------------------- [27 samples, 08:17:22 - 08:17:48] idle wait at each sample [session created at: 08:17:22] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-31 08:17:48.157 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 19784, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-31 08:13:56.964875 :C7026849:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:13:58.790184 :C70268EA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:13:58.777 2025-08-31 08:13:58.790186 :C70268EB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21564 pso_num=25 pso_serial#=62 2025-08-31 08:13:58.790326 :C70268EC:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21564 (legacy spawn) 2025-08-31 08:13:58.796832 :C70268ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:13:58.797004 :C70268F2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:13:58.797822 :C70268F7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:13:58.806495 :C70268FA:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:13:58.792 2025-08-31 08:13:58.806496 :C70268FB:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23224 pso_num=25 pso_serial#=63 2025-08-31 08:13:58.806642 :C70268FC:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23224 (legacy spawn) 2025-08-31 08:13:58.828267 :C70268FD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:13:58.828440 :C7026906:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:13:58.829331 :C7026907:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:14:02.749450 :C7026A29:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:14:02.745 2025-08-31 08:14:02.749452 :C7026A2A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22256 pso_num=25 pso_serial#=64 2025-08-31 08:14:02.749591 :C7026A2B:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22256 (legacy spawn) 2025-08-31 08:14:02.756064 :C7026A2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:02.756238 :C7026A31:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:14:02.757048 :C7026A36:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:14:21.769867 :C7027009:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:14:21.764 2025-08-31 08:14:21.769869 :C702700A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14400 pso_num=25 pso_serial#=65 2025-08-31 08:14:21.770009 :C702700B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14400 osp_idx=23 osp_ver=271752 osp_pg=0 (spawn #543915) 2025-08-31 08:14:21.770009 :C702700C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:14:21.770010 :C702700D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=85 time=1013713080 2025-08-31 08:14:21.770010 :C702700E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:14:21.770011 :C702700F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:14:21.770011 :C7027010:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:14:21.770011 :C7027011:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:14:21.770012 :C7027012:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:14:21.770505 :C7027013:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 08:14:21.771177 :C7027028:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:21.782234 :C7027044:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-31 08:14:21.782689 :C7027045:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-31 08:14:21.785035 :C7027046:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 08:14:21.785376 :C7027047:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:21.785410 :C702704A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 08:14:21.786271 :C7027050:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:21.786398 :C7027059:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:14:51.854488 :C7027A03:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:14:59.148767 :C7027C7B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:14:59.140 2025-08-31 08:14:59.148769 :C7027C7C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12980 pso_num=25 pso_serial#=66 2025-08-31 08:14:59.148917 :C7027C7D:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=12980 (legacy spawn) 2025-08-31 08:14:59.155443 :C7027C7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:59.155624 :C7027C83:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:14:59.156439 :C7027C88:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:14:59.524393 :C7027C8B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:14:59.515 2025-08-31 08:14:59.524396 :C7027C8C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23300 pso_num=25 pso_serial#=67 2025-08-31 08:14:59.524537 :C7027C8D:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23300 (legacy spawn) 2025-08-31 08:14:59.530989 :C7027C8E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:59.531016 :C7027C91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:14:59.531150 :C7027C98:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:14:59.531918 :C7027CA5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:15:06.847225 :C7027ED8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:15:06.841 2025-08-31 08:15:06.847228 :C7027ED9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2268 pso_num=25 pso_serial#=68 2025-08-31 08:15:06.847387 :C7027EDA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=2268 osp_idx=23 osp_ver=271755 osp_pg=0 (spawn #543917) 2025-08-31 08:15:06.847387 :C7027EDB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:15:06.847388 :C7027EDC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=86 time=1013758143 2025-08-31 08:15:06.847389 :C7027EDD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:15:06.847389 :C7027EDE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:15:06.847390 :C7027EDF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:15:06.847390 :C7027EE0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:15:06.847390 :C7027EE1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:15:06.847916 :C7027EE2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 08:15:06.848589 :C7027EF7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:15:06.854178 :C7027F13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:15:06.855188 :C7027F1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:15:06.855317 :C7027F24:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:15:21.858505 :C70283EF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-31 08:15:51.970866 :C7028E4E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:01.332142 :C7029116:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:01.323 2025-08-31 08:16:01.332144 :C7029117:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19576 pso_num=25 pso_serial#=69 2025-08-31 08:16:01.332283 :C7029118:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=19576 (legacy spawn) 2025-08-31 08:16:01.338792 :C7029119:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:01.338961 :C7029122:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:01.339843 :C7029123:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:07.831911 :C702935A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:07.832 2025-08-31 08:16:07.831913 :C702935B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23100 pso_num=25 pso_serial#=70 2025-08-31 08:16:07.832055 :C702935C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=23100 (legacy spawn) 2025-08-31 08:16:07.838839 :C702935D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:07.839023 :C7029366:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:07.839859 :C7029367:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:20.884538 :C7029754:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:20.870 2025-08-31 08:16:20.884540 :C7029755:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22372 pso_num=25 pso_serial#=71 2025-08-31 08:16:20.884677 :C7029756:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22372 (legacy spawn) 2025-08-31 08:16:20.891137 :C7029757:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:20.891167 :C702975A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:20.891317 :C702975D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:20.892083 :C702976E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:20.900827 :C7029771:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:20.886 2025-08-31 08:16:20.900829 :C7029772:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22880 pso_num=25 pso_serial#=72 2025-08-31 08:16:20.900967 :C7029773:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22880 (legacy spawn) 2025-08-31 08:16:20.907503 :C7029774:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:20.907667 :C702977D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:20.908470 :C702977E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:21.969981 :C70297E0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:21.964 2025-08-31 08:16:21.969983 :C70297E1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20104 pso_num=25 pso_serial#=73 2025-08-31 08:16:21.970127 :C70297E2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=20104 osp_idx=23 osp_ver=271760 osp_pg=0 (spawn #543921) 2025-08-31 08:16:21.970128 :C70297E3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:16:21.970128 :C70297E4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=87 time=1013833268 2025-08-31 08:16:21.970129 :C70297E5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:16:21.970129 :C70297E6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:16:21.970129 :C70297E7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:16:21.970129 :C70297E8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:16:21.970130 :C70297E9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:16:21.970643 :C70297EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.971279 :C70297FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.985473 :C702981B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 08:16:21.985599 :C702981C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 08:16:21.986424 :C702981D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 08:16:21.986771 :C702981E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.986810 :C7029821:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 08:16:21.994877 :C7029827:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.994946 :C702982A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-08-31 08:16:21.994983 :C702982B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-08-31 08:16:21.995367 :C7029831:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-31 08:16:21.995720 :C7029832:db_trace:kwqid.c@2146:kwqidrdq(): [10960:25:201] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-31 08:16:21.995910 :C7029833:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 08:16:21.996152 :C7029834:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.996180 :C7029837:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 08:16:21.997732 :C702983D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 08:16:21.997973 :C702983E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.998016 :C7029841:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 08:16:21.998998 :C7029847:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:21.999125 :C7029850:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:52.088510 :C702A1F3:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:57.198656 :C702A393:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:57.184 2025-08-31 08:16:57.198658 :C702A394:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=20068 pso_num=25 pso_serial#=74 2025-08-31 08:16:57.198801 :C702A395:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=20068 (legacy spawn) 2025-08-31 08:16:57.205352 :C702A396:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:57.205526 :C702A39F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:57.206308 :C702A3A0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:16:57.215039 :C702A3A3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:16:57.200 2025-08-31 08:16:57.215041 :C702A3A4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22184 pso_num=25 pso_serial#=75 2025-08-31 08:16:57.215177 :C702A3A5:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22184 (legacy spawn) 2025-08-31 08:16:57.221689 :C702A3A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:16:57.221854 :C702A3AF:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:16:57.222672 :C702A3B0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:17:00.301267 :C702A49B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:17:00.288 2025-08-31 08:17:00.301269 :C702A49C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22456 pso_num=25 pso_serial#=76 2025-08-31 08:17:00.301403 :C702A49D:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22456 (legacy spawn) 2025-08-31 08:17:00.307908 :C702A49E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:00.308085 :C702A4A7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:17:00.308911 :C702A4A8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:17:00.327630 :C702A4AB:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:17:00.319 2025-08-31 08:17:00.327632 :C702A4AC:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21392 pso_num=25 pso_serial#=77 2025-08-31 08:17:00.327770 :C702A4AD:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=21392 (legacy spawn) 2025-08-31 08:17:00.334238 :C702A4AE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:00.334404 :C702A4B7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:17:00.335304 :C702A4B8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:17:03.509661 :C702A5D7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:17:03.501 2025-08-31 08:17:03.509663 :C702A5D8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22520 pso_num=25 pso_serial#=78 2025-08-31 08:17:03.509804 :C702A5D9:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22520 (legacy spawn) 2025-08-31 08:17:03.516324 :C702A5DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:03.516496 :C702A5E3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:17:03.517306 :C702A5E4:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:17:16.031243 :C702A994:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:17:16.022 2025-08-31 08:17:16.031246 :C702A995:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17248 pso_num=25 pso_serial#=79 2025-08-31 08:17:16.031385 :C702A996:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=17248 (legacy spawn) 2025-08-31 08:17:16.038011 :C702A997:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:16.038180 :C702A9A0:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-31 08:17:16.039029 :C702A9A1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-08-31 08:17:22.061405 :C702AB89:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 08-31 08:17:22.056 2025-08-31 08:17:22.061406 :C702AB8A:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=19784 pso_num=25 pso_serial#=80 2025-08-31 08:17:22.061543 :C702AB8B:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=19784 osp_idx=23 osp_ver=271767 osp_pg=0 (spawn #543923) 2025-08-31 08:17:22.061544 :C702AB8C:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-31 08:17:22.061544 :C702AB8D:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=88 time=1013893362 2025-08-31 08:17:22.061545 :C702AB8E:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-08-31 08:17:22.061545 :C702AB8F:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-08-31 08:17:22.061545 :C702AB90:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-08-31 08:17:22.061546 :C702AB91:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-31 08:17:22.061546 :C702AB92:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-31 08:17:22.062038 :C702AB93:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-31 08:17:22.062728 :C702ABA8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:22.070254 :C702ABC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:psdpgi.c:2235 2025-08-31 08:17:22.070611 :C702ABC5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:22.070646 :C702ABC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:psdpgi.c:2235 2025-08-31 08:17:22.071588 :C702ABCE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-31 08:17:22.071711 :C702ABD7:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 19784, J000)