Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250711081122\orcl_j000_4800_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:36439M/63366M, Ph+PgF:42490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 4800, image: ORACLE.EXE (J000) *** 2025-07-11 08:11:22.307 *** SESSION ID:(193.34587) 2025-07-11 08:11:22.307 *** 2025-07-11 08:11:22.307 Process diagnostic dump for ORACLE.EXE (J000), OS id=4800, pid: 24, proc_ser: 167, sid: 193, sess_ser: 34587 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4800 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=0.264794 sec, exc=0.264794 sec, total=0.264794 sec wait times: max=0.500000 sec, heur=23.326626 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.000008 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=0.500006 sec, exc=0.500006 sec, total=0.500006 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=44 seq_num=45 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.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=0.510094 sec, exc=0.510094 sec, total=0.510094 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=41 seq_num=42 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 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=40 seq_num=41 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.000013 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=39 seq_num=40 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 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=38 seq_num=39 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.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=0.510091 sec, exc=0.510091 sec, total=0.510091 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=36 seq_num=37 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.000010 sec of elapsed time Sampled Session History of session 193 serial 34587 --------------------------------------------------- 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 --------------------------------------------------- [24 samples, 08:10:58 - 08:11:22] idle wait at each sample [session created at: 08:10:58] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-11 08:11:22.307 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 4800, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-11 08:07:58.608118 :B0760F46: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-07-11 08:07:58.615678 :B0760F62:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-11 08:07:58.616048 :B0760F63: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-07-11 08:07:58.616090 :B0760F66:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-11 08:07:58.616958 :B0760F77: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-07-11 08:07:58.617083 :B0760F80: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-07-11 08:08:28.721954 :B0761947:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:47.534919 :B0761F09:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:47.521 2025-07-11 08:08:47.534921 :B0761F0A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1400 pso_num=24 pso_serial#=146 2025-07-11 08:08:47.535074 :B0761F0B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=1400 (legacy spawn) 2025-07-11 08:08:47.541881 :B0761F0C: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-07-11 08:08:47.542067 :B0761F11: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-07-11 08:08:47.542997 :B0761F16:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:52.048917 :B0762093:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:52.035 2025-07-11 08:08:52.048919 :B0762094:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13448 pso_num=24 pso_serial#=147 2025-07-11 08:08:52.049061 :B0762095:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13448 (legacy spawn) 2025-07-11 08:08:52.055515 :B0762096: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-07-11 08:08:52.055680 :B076209E: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-07-11 08:08:52.056499 :B07620A0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:52.065214 :B07620A3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:52.051 2025-07-11 08:08:52.065216 :B07620A4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=1396 pso_num=24 pso_serial#=148 2025-07-11 08:08:52.065355 :B07620A5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=1396 (legacy spawn) 2025-07-11 08:08:52.072124 :B07620A6: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-07-11 08:08:52.072287 :B07620AF: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-07-11 08:08:52.073258 :B07620B0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:54.612235 :B076217C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:54.598 2025-07-11 08:08:54.612237 :B076217D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=608 pso_num=24 pso_serial#=149 2025-07-11 08:08:54.612375 :B076217E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=608 (legacy spawn) 2025-07-11 08:08:54.619018 :B076217F: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-07-11 08:08:54.619183 :B0762184: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-07-11 08:08:54.619994 :B0762189:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:54.628755 :B076218C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:54.614 2025-07-11 08:08:54.628758 :B076218D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8140 pso_num=24 pso_serial#=150 2025-07-11 08:08:54.628911 :B076218E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8140 (legacy spawn) 2025-07-11 08:08:54.635356 :B076218F: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-07-11 08:08:54.635522 :B0762198: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-07-11 08:08:54.636371 :B07621A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:57.690403 :B076228F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:57.677 2025-07-11 08:08:57.690405 :B0762290:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18200 pso_num=24 pso_serial#=151 2025-07-11 08:08:57.690549 :B0762291:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18200 (legacy spawn) 2025-07-11 08:08:57.697094 :B0762292: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-07-11 08:08:57.755694 :B07622A7: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-07-11 08:08:57.756618 :B07622A8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:58.108089 :B07622B7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:58.099 2025-07-11 08:08:58.108091 :B07622B8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2848 pso_num=24 pso_serial#=152 2025-07-11 08:08:58.108232 :B07622B9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2848 (legacy spawn) 2025-07-11 08:08:58.114682 :B07622BA: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-07-11 08:08:58.114851 :B07622C3: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-07-11 08:08:58.115612 :B07622C4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:08:58.749554 :B076232D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:08:58.740 2025-07-11 08:08:58.749555 :B076232E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4160 pso_num=24 pso_serial#=153 2025-07-11 08:08:58.749685 :B076232F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4160 osp_idx=22 osp_ver=555043 osp_pg=0 (spawn #373898) 2025-07-11 08:08:58.749685 :B0762330:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 08:08:58.749686 :B0762331:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=146 time=902114642 2025-07-11 08:08:58.749686 :B0762332:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 08:08:58.749686 :B0762333:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 08:08:58.749687 :B0762334:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 08:08:58.749687 :B0762335:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 08:08:58.749688 :B0762336:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 08:08:58.750157 :B0762337: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-07-11 08:08:58.750797 :B076234C: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-07-11 08:08:58.761916 :B0762373:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 08:08:58.762380 :B0762374:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 08:08:58.764751 :B0762375:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-11 08:08:58.765089 :B0762376: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-07-11 08:08:58.765129 :B0762379:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-11 08:08:58.765995 :B076237F: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-07-11 08:08:58.766128 :B0762388: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-07-11 08:09:28.863207 :B0762D19:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:30.361004 :B0762D57:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:30.348 2025-07-11 08:09:30.361007 :B0762D58:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14548 pso_num=24 pso_serial#=154 2025-07-11 08:09:30.361152 :B0762D59:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14548 (legacy spawn) 2025-07-11 08:09:30.367623 :B0762D5A: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-07-11 08:09:30.367803 :B0762D5F: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-07-11 08:09:30.368636 :B0762D64:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:31.403190 :B0762DDD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:31.395 2025-07-11 08:09:31.403193 :B0762DDE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18400 pso_num=24 pso_serial#=155 2025-07-11 08:09:31.403327 :B0762DDF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18400 (legacy spawn) 2025-07-11 08:09:31.410227 :B0762DE0: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-07-11 08:09:31.410411 :B0762DE5: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-07-11 08:09:31.411359 :B0762DEA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:31.420564 :B0762DED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:31.410 2025-07-11 08:09:31.420567 :B0762DEE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15712 pso_num=24 pso_serial#=156 2025-07-11 08:09:31.420707 :B0762DEF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15712 (legacy spawn) 2025-07-11 08:09:31.427745 :B0762DF0: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-07-11 08:09:31.427957 :B0762DF9: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-07-11 08:09:31.428749 :B0762DFA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:34.596806 :B0762EED:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:34.584 2025-07-11 08:09:34.596808 :B0762EEE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9468 pso_num=24 pso_serial#=157 2025-07-11 08:09:34.596943 :B0762EEF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9468 (legacy spawn) 2025-07-11 08:09:34.603482 :B0762EF0: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-07-11 08:09:34.603652 :B0762EF6: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-07-11 08:09:34.604431 :B0762EFA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:34.613028 :B0762EFD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:34.599 2025-07-11 08:09:34.613030 :B0762EFE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4288 pso_num=24 pso_serial#=158 2025-07-11 08:09:34.613162 :B0762EFF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4288 (legacy spawn) 2025-07-11 08:09:34.619649 :B0762F2E: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-07-11 08:09:34.619815 :B0762F37: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-07-11 08:09:34.620657 :B0762F38:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:55.633375 :B07635A2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:55.624 2025-07-11 08:09:55.633377 :B07635A3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17996 pso_num=24 pso_serial#=159 2025-07-11 08:09:55.633529 :B07635A4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17996 (legacy spawn) 2025-07-11 08:09:55.640038 :B07635A5: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-07-11 08:09:55.640220 :B07635AE: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-07-11 08:09:55.641050 :B07635AF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:09:58.860769 :B07636DC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:09:58.855 2025-07-11 08:09:58.860771 :B07636DD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13944 pso_num=24 pso_serial#=160 2025-07-11 08:09:58.860912 :B07636DE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13944 osp_idx=22 osp_ver=555050 osp_pg=0 (spawn #373900) 2025-07-11 08:09:58.860912 :B07636DF:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 08:09:58.860913 :B07636E0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=147 time=902174751 2025-07-11 08:09:58.860913 :B07636E1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 08:09:58.860914 :B07636E2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 08:09:58.860914 :B07636E3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 08:09:58.860914 :B07636E4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 08:09:58.860915 :B07636E5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 08:09:58.861422 :B07636E6: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-07-11 08:09:58.862050 :B07636FB: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-07-11 08:09:58.869651 :B0763718:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-11 08:09:58.870486 :B0763724: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-07-11 08:09:58.870520 :B0763727:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-11 08:09:58.871405 :B076372D: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-07-11 08:09:58.871550 :B0763736: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-07-11 08:10:28.961089 :B0764134:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:30.046750 :B0764143:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:30.033 2025-07-11 08:10:30.046752 :B0764144:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2860 pso_num=24 pso_serial#=161 2025-07-11 08:10:30.046883 :B0764145:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2860 (legacy spawn) 2025-07-11 08:10:30.053328 :B0764146: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-07-11 08:10:30.053504 :B076414B: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-07-11 08:10:30.054336 :B0764150:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:32.540436 :B0764239:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:32.532 2025-07-11 08:10:32.540439 :B076423A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17132 pso_num=24 pso_serial#=162 2025-07-11 08:10:32.540627 :B076423B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17132 (legacy spawn) 2025-07-11 08:10:32.547106 :B076423C: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-07-11 08:10:32.547297 :B0764241: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-07-11 08:10:32.548135 :B0764246:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:43.909444 :B07645E6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:43.894 2025-07-11 08:10:43.909446 :B07645E7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17140 pso_num=24 pso_serial#=163 2025-07-11 08:10:43.909631 :B07645E8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17140 (legacy spawn) 2025-07-11 08:10:43.915989 :B07645E9: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-07-11 08:10:43.916168 :B07645F1: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-07-11 08:10:43.916995 :B07645F3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:43.925631 :B07645F6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:43.910 2025-07-11 08:10:43.925633 :B07645F7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17352 pso_num=24 pso_serial#=164 2025-07-11 08:10:43.925773 :B07645F8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17352 (legacy spawn) 2025-07-11 08:10:43.932194 :B0764606: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-07-11 08:10:43.932225 :B0764609: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-07-11 08:10:43.932376 :B0764611: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-07-11 08:10:43.933156 :B076461D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:47.007122 :B076470D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:46.993 2025-07-11 08:10:47.007124 :B076470E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17476 pso_num=24 pso_serial#=165 2025-07-11 08:10:47.007267 :B076470F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17476 (legacy spawn) 2025-07-11 08:10:47.014087 :B0764710: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-07-11 08:10:47.014264 :B0764719: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-07-11 08:10:47.015126 :B076471A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:47.024154 :B076471D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:47.009 2025-07-11 08:10:47.024156 :B076471E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8628 pso_num=24 pso_serial#=166 2025-07-11 08:10:47.024306 :B076471F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8628 (legacy spawn) 2025-07-11 08:10:47.031022 :B0764720: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-07-11 08:10:47.031204 :B0764728: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-07-11 08:10:47.032102 :B076472A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-11 08:10:58.966236 :B0764AF4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-11 08:10:58.960 2025-07-11 08:10:58.966237 :B0764AF5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4800 pso_num=24 pso_serial#=167 2025-07-11 08:10:58.966370 :B0764AF6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4800 osp_idx=22 osp_ver=555057 osp_pg=0 (spawn #373902) 2025-07-11 08:10:58.966370 :B0764AF7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 08:10:58.966371 :B0764AF8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=148 time=902234861 2025-07-11 08:10:58.966371 :B0764AF9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 08:10:58.966372 :B0764AFA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 08:10:58.966372 :B0764AFB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 08:10:58.966372 :B0764AFC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 08:10:58.966373 :B0764AFD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 08:10:58.966910 :B0764AFE: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-07-11 08:10:58.967561 :B0764B13: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-07-11 08:10:58.978640 :B0764B3A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 08:10:58.979135 :B0764B3B:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 08:10:58.981433 :B0764B3C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-11 08:10:58.981797 :B0764B3D: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-07-11 08:10:58.981834 :B0764B40:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-11 08:10:58.982769 :B0764B46: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-07-11 08:10:58.982914 :B0764B4F: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: 4800, J000)