Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250713100256\orcl_j000_14888_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:36382M/63366M, Ph+PgF:42449M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 14888, image: ORACLE.EXE (J000) *** 2025-07-13 10:02:57.073 *** SESSION ID:(177.23443) 2025-07-13 10:02:57.073 *** 2025-07-13 10:02:57.073 Process diagnostic dump for ORACLE.EXE (J000), OS id=14888, pid: 22, proc_ser: 238, sid: 177, sess_ser: 23443 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14888 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.024995 sec, exc=0.024995 sec, total=0.024995 sec wait times: max=0.500000 sec, heur=2.530644 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.000017 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.510122 sec, exc=0.510122 sec, total=0.510122 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=3 seq_num=4 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.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 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=0 seq_num=1 snap_id=1 wait times: snap=0.495481 sec, exc=0.495481 sec, total=0.495481 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 177 serial 23443 --------------------------------------------------- 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, 10:02:54 - 10:02:57] idle wait at each sample [session created at: 10:02:54] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-13 10:02:57.073 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 14888, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-13 10:00:39.953270 :B162C39C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:00:39.953286 :B162C39F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:00:39.953818 :B162C3A5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:00:39.953942 :B162C3A6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:00:39.953961 :B162C3A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:00:39.957906 :B162C3BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:00:39.957986 :B162C3BD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:00:39.958226 :B162C3C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:00:39.958247 :B162C3C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:00:39.960322 :B162C3CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:00:39.960467 :B162C3D5:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:10.019384 :B162CD74:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:23.591615 :B162D149:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:23.588 2025-07-13 10:01:23.591617 :B162D14A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9264 pso_num=22 pso_serial#=223 2025-07-13 10:01:23.591756 :B162D14B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9264 (legacy spawn) 2025-07-13 10:01:23.598174 :B162D14C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:23.598349 :B162D155:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:23.599145 :B162D156:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:23.607879 :B162D159:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:23.604 2025-07-13 10:01:23.607881 :B162D15A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17064 pso_num=22 pso_serial#=224 2025-07-13 10:01:23.608014 :B162D15B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17064 (legacy spawn) 2025-07-13 10:01:23.614497 :B162D15C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:23.614662 :B162D165:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:23.615454 :B162D166:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:25.773368 :B162D23F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:25.765 2025-07-13 10:01:25.773370 :B162D240:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9536 pso_num=22 pso_serial#=225 2025-07-13 10:01:25.773515 :B162D241:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9536 (legacy spawn) 2025-07-13 10:01:25.779994 :B162D242:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:25.780178 :B162D247:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:25.781024 :B162D24C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:26.788421 :B162D296:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:26.775 2025-07-13 10:01:26.788423 :B162D297:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12772 pso_num=22 pso_serial#=226 2025-07-13 10:01:26.788563 :B162D298:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12772 (legacy spawn) 2025-07-13 10:01:26.795088 :B162D299:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:26.795262 :B162D2A1:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:26.796088 :B162D2A3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:26.804708 :B162D2A6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:26.790 2025-07-13 10:01:26.804710 :B162D2A7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14000 pso_num=22 pso_serial#=227 2025-07-13 10:01:26.804849 :B162D2A8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14000 (legacy spawn) 2025-07-13 10:01:26.811274 :B162D2A9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:26.811434 :B162D2B2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:26.812233 :B162D2B3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:27.583351 :B162D2B6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:27.570 2025-07-13 10:01:27.583353 :B162D2B7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16756 pso_num=22 pso_serial#=228 2025-07-13 10:01:27.583500 :B162D2B8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16756 (legacy spawn) 2025-07-13 10:01:27.590264 :B162D306:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:27.590458 :B162D30B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:01:27.591352 :B162D310:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:01:39.982562 :B162D6EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:01:39.976 2025-07-13 10:01:39.982565 :B162D6ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6128 pso_num=22 pso_serial#=229 2025-07-13 10:01:39.982706 :B162D6EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6128 osp_idx=0 osp_ver=395551 osp_pg=0 (spawn #380834) 2025-07-13 10:01:39.982707 :B162D6EF:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 10:01:39.982707 :B162D6F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=205 time=1081675876 2025-07-13 10:01:39.982708 :B162D6F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 10:01:39.982708 :B162D6F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 10:01:39.982709 :B162D6F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 10:01:39.982709 :B162D6F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 10:01:39.982709 :B162D6F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 10:01:39.983214 :B162D6F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 10:01:39.983893 :B162D70B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:39.991455 :B162D727:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:01:39.991837 :B162D728:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:39.991873 :B162D72B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:01:39.992713 :B162D731:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:01:39.992826 :B162D739:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:10.093494 :B162E123:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:13.002804 :B162E208:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:13.000 2025-07-13 10:02:13.002806 :B162E209:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13944 pso_num=22 pso_serial#=230 2025-07-13 10:02:13.002943 :B162E20A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13944 (legacy spawn) 2025-07-13 10:02:13.009396 :B162E20B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:13.009580 :B162E213:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:13.010377 :B162E215:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:13.019150 :B162E218:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:13.016 2025-07-13 10:02:13.019152 :B162E219:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8904 pso_num=22 pso_serial#=231 2025-07-13 10:02:13.019288 :B162E21A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8904 (legacy spawn) 2025-07-13 10:02:13.025796 :B162E21B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:13.025966 :B162E224:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:13.026753 :B162E225:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:27.956542 :B162E695:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:27.949 2025-07-13 10:02:27.956544 :B162E696:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8712 pso_num=22 pso_serial#=232 2025-07-13 10:02:27.956682 :B162E697:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8712 (legacy spawn) 2025-07-13 10:02:27.963155 :B162E698:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:27.963434 :B162E69E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:27.964249 :B162E6A2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:35.931014 :B162E8E3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:35.922 2025-07-13 10:02:35.931016 :B162E8E4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12980 pso_num=22 pso_serial#=233 2025-07-13 10:02:35.931166 :B162E8E5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12980 (legacy spawn) 2025-07-13 10:02:35.937683 :B162E8FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:35.937822 :B162E903:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:35.938622 :B162E918:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:35.983857 :B162E91B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:35.968 2025-07-13 10:02:35.983859 :B162E91C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15712 pso_num=22 pso_serial#=234 2025-07-13 10:02:35.983996 :B162E91D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15712 (legacy spawn) 2025-07-13 10:02:35.990669 :B162E91E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:35.990848 :B162E925:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:35.991627 :B162E928:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:36.000480 :B162E92B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:36.000 2025-07-13 10:02:36.000482 :B162E92C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14456 pso_num=22 pso_serial#=235 2025-07-13 10:02:36.000622 :B162E92D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14456 (legacy spawn) 2025-07-13 10:02:36.007387 :B162E92E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:36.007553 :B162E937:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:36.008414 :B162E938:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:39.197724 :B162EA1A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:39.189 2025-07-13 10:02:39.197726 :B162EA1B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6572 pso_num=22 pso_serial#=236 2025-07-13 10:02:39.197865 :B162EA1C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6572 (legacy spawn) 2025-07-13 10:02:39.204308 :B162EA1D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:39.204480 :B162EA26:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:39.205272 :B162EA27:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:39.213949 :B162EA2A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:39.205 2025-07-13 10:02:39.213951 :B162EA2B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15440 pso_num=22 pso_serial#=237 2025-07-13 10:02:39.214087 :B162EA2C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15440 (legacy spawn) 2025-07-13 10:02:39.220591 :B162EA2D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:39.220757 :B162EA36:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:39.221577 :B162EA37:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-13 10:02:40.101440 :B162EAAF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-13 10:02:40.095 2025-07-13 10:02:40.101443 :B162EAB0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14888 pso_num=22 pso_serial#=238 2025-07-13 10:02:40.101576 :B162EAB1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14888 osp_idx=0 osp_ver=395560 osp_pg=0 (spawn #380836) 2025-07-13 10:02:40.101576 :B162EAB2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-13 10:02:40.101577 :B162EAB3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=206 time=1081736001 2025-07-13 10:02:40.101577 :B162EAB4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-13 10:02:40.101578 :B162EAB5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-13 10:02:40.101578 :B162EAB6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-13 10:02:40.101578 :B162EAB7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-13 10:02:40.101578 :B162EAB8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-13 10:02:40.102067 :B162EAB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-13 10:02:40.102704 :B162EACE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:40.113833 :B162EAEA:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-13 10:02:40.114297 :B162EAEB:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-13 10:02:40.116696 :B162EAEC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:02:40.117045 :B162EAED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:40.117084 :B162EAF0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:02:40.117964 :B162EAF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:40.118100 :B162EAFF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-13 10:02:52.797767 :B162EFE6:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-13 10:02:52.902696 :B162F32F:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-13 10:02:52.902696*:B162F33E:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCDC718 estart=1752372172 eid=16784096 ctx=0x000000077FD6A000 cwh=0x000000000FCD0068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-13 10:02:52.902696*:B162F33F:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 pubTabIdxCnt=0 2025-07-13 10:02:52.902696*:B162F340:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCDC718 2025-07-13 10:02:52.902696*:B162F341:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCDC718 2025-07-13 10:02:52.902696*:B162F342:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCDC718 curCtx=0x000000000FCD8938 xsc->flg4=65792 flags=2 2025-07-13 10:02:52.902696*:B162F343:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCDC718 2025-07-13 10:02:52.904697 :B162F344:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:52.904741 :B162F347:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:jslv.c:9203 2025-07-13 10:02:52.909725 :B162F361:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:53.033703 :B162F375:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:53.167797 :B162F37D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:53.297582 :B162F385:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:53.417119 :B162F38D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.430803 :B162F3DC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.431795 :B162F3E4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.435606 :B162F3EC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.451610 :B162F3F4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.452208 :B162F3FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.452710 :B162F404:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.453206 :B162F40C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.455829 :B162F414:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.456737 :B162F41C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:02:54.457452 :B162F41D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:02:54.458279 :B162F41E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:02:54.458697 :B162F41F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:02:54.458703 :B162F420:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:02:54.459276 :B162F421:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:02:54.459768 :B162F422:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:02:54.460038 :B162F423:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:02:54.461027 :B162F424:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-13 10:02:54.499048 :B162F425:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.499099 :B162F428:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-13 10:02:54.499957 :B162F42E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.543764 :B162F475:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.544224 :B162F47D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.544565 :B162F485:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.544730 :B162F48D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.544865 :B162F495:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.545021 :B162F49D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:jslv.c:9203 2025-07-13 10:02:54.549093 :B162F49E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-13 10:02:54.549207 :B162F4A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 14888, J000)