Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250703220006\orcl_j000_15932_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:37001M/63366M, Ph+PgF:42935M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15932, image: ORACLE.EXE (J000) *** 2025-07-03 22:00:06.504 *** SESSION ID:(193.54341) 2025-07-03 22:00:06.504 *** 2025-07-03 22:00:06.504 Process diagnostic dump for ORACLE.EXE (J000), OS id=15932, pid: 24, proc_ser: 101, sid: 193, sess_ser: 54341 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15932 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.486359 sec, exc=0.486359 sec, total=0.486359 sec wait times: max=0.500000 sec, heur=2.482904 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.510089 sec, exc=0.510089 sec, total=0.510089 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.486425 sec, exc=0.486425 sec, total=0.486425 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 193 serial 54341 --------------------------------------------------- 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 --------------------------------------------------- [3 samples, 22:00:04 - 22:00:06] idle wait at each sample [session created at: 22:00:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-03 22:00:06.504 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15932, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-03 21:56:32.115099 :AD22348C: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-03 21:56:32.126713 :AD2234A8:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-03 21:56:32.127268 :AD2234A9:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-03 21:56:32.130120 :AD2234AA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-03 21:56:32.130525 :AD2234AB: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-03 21:56:32.130568 :AD2234AE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-03 21:56:32.131601 :AD2234B4: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-03 21:56:32.131738 :AD2234BD: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-03 21:57:02.227258 :AD223E54:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:08.158106 :AD22401A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:08.150 2025-07-03 21:57:08.158108 :AD22401B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2768 pso_num=24 pso_serial#=89 2025-07-03 21:57:08.158246 :AD22401C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=2768 (legacy spawn) 2025-07-03 21:57:08.164651 :AD22401D: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-03 21:57:08.164825 :AD224022: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-03 21:57:08.165638 :AD224027:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:16.691670 :AD2242DA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:16.677 2025-07-03 21:57:16.691671 :AD2242DB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17316 pso_num=24 pso_serial#=90 2025-07-03 21:57:16.691804 :AD2242DC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17316 (legacy spawn) 2025-07-03 21:57:16.698335 :AD2242DD: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-03 21:57:16.698521 :AD2242E2: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-03 21:57:16.699323 :AD2242E7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:16.707999 :AD2242EA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:16.693 2025-07-03 21:57:16.708002 :AD2242EB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13156 pso_num=24 pso_serial#=91 2025-07-03 21:57:16.708138 :AD2242EC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13156 (legacy spawn) 2025-07-03 21:57:16.728894 :AD2242ED: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-03 21:57:16.729058 :AD2242F6: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-03 21:57:16.729852 :AD2242F7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:20.091214 :AD2243F1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:20.078 2025-07-03 21:57:20.091217 :AD2243F2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17988 pso_num=24 pso_serial#=92 2025-07-03 21:57:20.091353 :AD2243F3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17988 (legacy spawn) 2025-07-03 21:57:20.097830 :AD2243F4: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-03 21:57:20.098001 :AD2243F9: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-03 21:57:20.098783 :AD2243FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:20.107476 :AD224401:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:20.094 2025-07-03 21:57:20.107478 :AD224402:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8928 pso_num=24 pso_serial#=93 2025-07-03 21:57:20.107620 :AD224403:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8928 (legacy spawn) 2025-07-03 21:57:20.114052 :AD224404: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-03 21:57:20.114223 :AD22440C: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-03 21:57:20.115017 :AD22440E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:29.984300 :AD224763:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:29.983 2025-07-03 21:57:29.984303 :AD224764:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18408 pso_num=24 pso_serial#=94 2025-07-03 21:57:29.984500 :AD224765:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18408 (legacy spawn) 2025-07-03 21:57:29.991406 :AD224766: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-03 21:57:30.016469 :AD22476D: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-03 21:57:30.017513 :AD224770:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:57:32.203895 :AD2247D6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:57:32.197 2025-07-03 21:57:32.203897 :AD2247D7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14356 pso_num=24 pso_serial#=95 2025-07-03 21:57:32.204042 :AD2247D8:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14356 osp_idx=22 osp_ver=507810 osp_pg=0 (spawn #349152) 2025-07-03 21:57:32.204042 :AD2247D9: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-03 21:57:32.204043 :AD2247DA:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=77 time=260628095 2025-07-03 21:57:32.204043 :AD2247DB:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-03 21:57:32.204044 :AD2247DC:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-03 21:57:32.204044 :AD2247DD:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-03 21:57:32.204045 :AD2247DE:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-03 21:57:32.204045 :AD2247DF:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-03 21:57:32.204588 :AD2247E0: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-03 21:57:32.205286 :AD2247F5: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-03 21:57:32.213189 :AD224811:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-03 21:57:32.213619 :AD224812: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-03 21:57:32.213657 :AD224815:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-03 21:57:32.214565 :AD22481B: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-03 21:57:32.214688 :AD224824: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-03 21:58:02.301372 :AD225237:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:58:04.038479 :AD22530A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:58:04.030 2025-07-03 21:58:04.038481 :AD22530B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12372 pso_num=24 pso_serial#=96 2025-07-03 21:58:04.038622 :AD22530C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12372 (legacy spawn) 2025-07-03 21:58:04.045197 :AD22530D: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-03 21:58:04.045403 :AD225312: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-03 21:58:04.046236 :AD225317:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:58:10.425600 :AD2254E6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:58:10.416 2025-07-03 21:58:10.425602 :AD2254E7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11176 pso_num=24 pso_serial#=97 2025-07-03 21:58:10.425745 :AD2254E8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11176 (legacy spawn) 2025-07-03 21:58:10.432502 :AD2254E9: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-03 21:58:10.432681 :AD2254EE: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-03 21:58:10.433524 :AD2254F3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:58:29.625190 :AD225AA6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:58:29.611 2025-07-03 21:58:29.625192 :AD225AA7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13480 pso_num=24 pso_serial#=98 2025-07-03 21:58:29.625326 :AD225AA8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13480 (legacy spawn) 2025-07-03 21:58:29.631873 :AD225AA9: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-03 21:58:29.632051 :AD225AB2: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-03 21:58:29.632815 :AD225AB3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:58:29.641542 :AD225AB6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:58:29.636 2025-07-03 21:58:29.641544 :AD225AB7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18360 pso_num=24 pso_serial#=99 2025-07-03 21:58:29.641684 :AD225AB8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=18360 (legacy spawn) 2025-07-03 21:58:29.648134 :AD225AB9: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-03 21:58:29.648162 :AD225ABC: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-03 21:58:29.648305 :AD225AC3: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-03 21:58:29.649102 :AD225AD0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:58:32.298929 :AD225BBA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:58:32.293 2025-07-03 21:58:32.298931 :AD225BBB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17380 pso_num=24 pso_serial#=100 2025-07-03 21:58:32.299066 :AD225BBC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17380 osp_idx=22 osp_ver=507815 osp_pg=0 (spawn #349155) 2025-07-03 21:58:32.299067 :AD225BBD: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-03 21:58:32.299067 :AD225BBE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=78 time=260688189 2025-07-03 21:58:32.299067 :AD225BBF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-03 21:58:32.299068 :AD225BC0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-03 21:58:32.299068 :AD225BC1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-03 21:58:32.299068 :AD225BC2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-03 21:58:32.299069 :AD225BC3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-03 21:58:32.299607 :AD225BC4: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-03 21:58:32.300259 :AD225BD9: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-03 21:58:32.311430 :AD225BF6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-03 21:58:32.311939 :AD225BF7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-03 21:58:32.314367 :AD225BF8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-03 21:58:32.314695 :AD225BF9: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-03 21:58:32.314732 :AD225BFC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-03 21:58:32.315624 :AD225C02: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-03 21:58:32.315761 :AD225C0B: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-03 21:58:57.346583 :AD226439:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-03 21:58:57.346607*:AD22643D:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D4BB58 estart=1751551137 eid=16939747 ctx=0x000000077BD98A08 cwh=0x00000000232802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-03 21:58:57.346607*:AD22643E:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 pubTabIdxCnt=0 2025-07-03 21:58:57.346607*:AD22643F:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D4BB58 2025-07-03 21:58:57.346607*:AD226440:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D4BB58 2025-07-03 21:58:57.346607*:AD226441:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D4BB58 curCtx=0x000000002328F300 xsc->flg4=65792 flags=2 2025-07-03 21:58:57.346607*:AD226442:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D4BB58 2025-07-03 21:58:57.347186 :AD226443: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-03 21:58:57.352644 :AD22644B: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-03 21:58:57.353494 :AD226453: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-03 21:58:57.353624 :AD22645B: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-03 21:59:27.423887 :AD226E25:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-07-03 21:59:32.402029 :AD226F44:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 07-03 21:59:32.396 2025-07-03 21:59:32.402031 :AD226F45:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15932 pso_num=24 pso_serial#=101 2025-07-03 21:59:32.402173 :AD226F46:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15932 osp_idx=22 osp_ver=507816 osp_pg=0 (spawn #349157) 2025-07-03 21:59:32.402174 :AD226F47: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-03 21:59:32.402174 :AD226F48:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=79 time=260748298 2025-07-03 21:59:32.402175 :AD226F49:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-07-03 21:59:32.402175 :AD226F4A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-07-03 21:59:32.402175 :AD226F4B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-07-03 21:59:32.402175 :AD226F4C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-03 21:59:32.402175 :AD226F4D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-03 21:59:32.402692 :AD226F4E: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-03 21:59:32.403326 :AD226F63: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-03 21:59:32.410905 :AD226F7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-07-03 21:59:32.411271 :AD226F80: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-03 21:59:32.411306 :AD226F83:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-07-03 21:59:32.412171 :AD226F89: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-03 21:59:32.412285 :AD226F92: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-03 22:00:02.036609 :AD227AD2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-03 22:00:02.036609*:AD227AE3:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1751551201 eid=16783502 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-03 22:00:02.036609*:AD227AE4:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-07-03 22:00:02.036609*:AD227AE5:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-07-03 22:00:02.036609*:AD227AE6:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-07-03 22:00:02.036609*:AD227AE7:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-07-03 22:00:02.036609*:AD227AE8:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-07-03 22:00:02.042286 :AD227AF4: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-03 22:00:02.042339 :AD227AFA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-03 22:00:02.053894 :AD227B51: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-03 22:00:02.059078 :AD227B78: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-03 22:00:02.059492 :AD227B81:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=30 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-03 22:00:02.059916 :AD227B8C: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-03 22:00:02.065554 :AD227BE6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-03 22:00:02.065725 :AD227BF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-03 22:00:02.066702 :AD227C1D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-03 22:00:02.068502 :AD227C2C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-03 22:00:02.069241 :AD227C46: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-03 22:00:02.161638 :AD227DAF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-03 22:00:02.163783 :AD227DB1: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-03 22:00:02.163822 :AD227DB4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-03 22:00:02.166340 :AD227DBA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-03 22:00:02.169634 :AD227DBB: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-03 22:00:02.169717 :AD227DBE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-03 22:00:02.169805 :AD227DCB: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-03 22:00:04.009932 :AD22822B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-03 22:00:04.009932*:AD228232:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1751551203 eid=16783508 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-03 22:00:04.009932*:AD228233:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-07-03 22:00:04.009932*:AD228234:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-07-03 22:00:04.009932*:AD228235:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-07-03 22:00:04.009932*:AD228236:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-07-03 22:00:04.009932*:AD228237:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-07-03 22:00:04.012136 :AD228238: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-03 22:00:04.012185 :AD22823B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-07-03 22:00:04.018756 :AD228241: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-03 22:00:04.019432 :AD228249: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-03 22:00:04.020122 :AD228251: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-03 22:00:04.020349 :AD228259:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-07-03 22:00:04.021783 :AD22825A: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-03 22:00:04.021824 :AD22825D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-07-03 22:00:04.021909 :AD22826A: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: 15932, J000)