Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250426060008\orcl_j000_2960_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:37608M/63366M, Ph+PgF:45321M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 2960, image: ORACLE.EXE (J000) *** 2025-04-26 06:00:08.581 *** SESSION ID:(193.11925) 2025-04-26 06:00:08.581 *** 2025-04-26 06:00:08.581 Process diagnostic dump for ORACLE.EXE (J000), OS id=2960, pid: 24, proc_ser: 221, sid: 193, sess_ser: 11925 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 2960 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.208113 sec, exc=0.208113 sec, total=0.208113 sec wait times: max=0.500000 sec, heur=8.227160 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=15 seq_num=16 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500117 sec, exc=0.500117 sec, total=0.500117 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500005 sec, exc=0.500005 sec, total=0.500005 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000013 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.508019 sec, exc=0.508019 sec, total=0.508019 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.510109 sec, exc=0.510109 sec, total=0.510109 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time Sampled Session History of session 193 serial 11925 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 06:00:00 - 06:00:08] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-04-26 06:00:08.581 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 2960, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-04-26 05:57:03.673587 :8EE2419C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3616 (legacy spawn) 2025-04-26 05:57:03.680015 :8EE2419D: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-04-26 05:57:03.680197 :8EE241A2: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-04-26 05:57:03.680981 :8EE241A7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:57:03.697834 :8EE241AA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:57:03.690 2025-04-26 05:57:03.697836 :8EE241AB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7340 pso_num=24 pso_serial#=206 2025-04-26 05:57:03.697971 :8EE241AC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7340 (legacy spawn) 2025-04-26 05:57:03.704359 :8EE241AD: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-04-26 05:57:03.704519 :8EE241B6: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-04-26 05:57:03.705321 :8EE241B7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:57:06.979254 :8EE242FE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:57:06.966 2025-04-26 05:57:06.979256 :8EE242FF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7728 pso_num=24 pso_serial#=207 2025-04-26 05:57:06.979389 :8EE24300:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7728 (legacy spawn) 2025-04-26 05:57:06.986107 :8EE24301: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-04-26 05:57:06.986276 :8EE24306: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-04-26 05:57:06.987097 :8EE2430B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:57:06.995671 :8EE2430E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:57:06.982 2025-04-26 05:57:06.995673 :8EE2430F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4620 pso_num=24 pso_serial#=208 2025-04-26 05:57:06.995805 :8EE24310:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4620 (legacy spawn) 2025-04-26 05:57:07.002102 :8EE24311: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-04-26 05:57:07.002280 :8EE2431A: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-04-26 05:57:07.003109 :8EE2431B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:57:23.147554 :8EE248B8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:57:23.135 2025-04-26 05:57:23.147557 :8EE248B9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3352 pso_num=24 pso_serial#=209 2025-04-26 05:57:23.147690 :8EE248BA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3352 (legacy spawn) 2025-04-26 05:57:23.154270 :8EE248BB: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-04-26 05:57:23.154451 :8EE248C0: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-04-26 05:57:23.155306 :8EE248C5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:57:30.007043 :8EE24B02:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:57:30.001 2025-04-26 05:57:30.007045 :8EE24B03:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8872 pso_num=24 pso_serial#=210 2025-04-26 05:57:30.007180 :8EE24B04:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=8872 osp_idx=22 osp_ver=175394 osp_pg=0 (spawn #119326) 2025-04-26 05:57:30.007181 :8EE24B05:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 05:57:30.007182 :8EE24B06:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=197 time=2917851796 2025-04-26 05:57:30.007182 :8EE24B07:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 05:57:30.007183 :8EE24B08:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 05:57:30.007183 :8EE24B09:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 05:57:30.007183 :8EE24B0A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 05:57:30.007183 :8EE24B0B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 05:57:30.007679 :8EE24B0C: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-04-26 05:57:30.008322 :8EE24B21: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-04-26 05:57:30.015771 :8EE24B3D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 05:57:30.016149 :8EE24B3E: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-04-26 05:57:30.016185 :8EE24B41:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 05:57:30.017054 :8EE24B47: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-04-26 05:57:30.017167 :8EE24B50: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-04-26 05:58:00.102034 :8EE25551:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:58:03.798301 :8EE25647:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:58:03.790 2025-04-26 05:58:03.798303 :8EE25648:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9064 pso_num=24 pso_serial#=211 2025-04-26 05:58:03.798436 :8EE25649:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9064 (legacy spawn) 2025-04-26 05:58:03.804830 :8EE2564A: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-04-26 05:58:03.805126 :8EE2564F: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-04-26 05:58:03.805967 :8EE25654:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:58:15.518757 :8EE25A0E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:58:15.515 2025-04-26 05:58:15.518759 :8EE25A0F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9940 pso_num=24 pso_serial#=212 2025-04-26 05:58:15.518897 :8EE25A10:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9940 (legacy spawn) 2025-04-26 05:58:15.525347 :8EE25A11: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-04-26 05:58:15.525522 :8EE25A1A: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-04-26 05:58:15.526307 :8EE25A1B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:58:15.534980 :8EE25A1E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:58:15.531 2025-04-26 05:58:15.534982 :8EE25A1F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7880 pso_num=24 pso_serial#=213 2025-04-26 05:58:15.535119 :8EE25A20:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7880 (legacy spawn) 2025-04-26 05:58:15.541549 :8EE25A21: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-04-26 05:58:15.541723 :8EE25A2A: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-04-26 05:58:15.542535 :8EE25A2B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:58:18.704995 :8EE25B24:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:58:18.692 2025-04-26 05:58:18.704998 :8EE25B25:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8792 pso_num=24 pso_serial#=214 2025-04-26 05:58:18.705139 :8EE25B26:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8792 (legacy spawn) 2025-04-26 05:58:18.711589 :8EE25B27: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-04-26 05:58:18.711765 :8EE25B2C: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-04-26 05:58:18.712531 :8EE25B31:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:58:18.721059 :8EE25B34:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:58:18.707 2025-04-26 05:58:18.721061 :8EE25B35:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11132 pso_num=24 pso_serial#=215 2025-04-26 05:58:18.721197 :8EE25B36:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11132 (legacy spawn) 2025-04-26 05:58:18.727620 :8EE25B37: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-04-26 05:58:18.727774 :8EE25B40: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-04-26 05:58:18.728576 :8EE25B41:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:58:25.087464 :8EE25D9D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:58:25.081 2025-04-26 05:58:25.087465 :8EE25D9E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4320 pso_num=24 pso_serial#=216 2025-04-26 05:58:25.087602 :8EE25D9F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4320 osp_idx=22 osp_ver=175400 osp_pg=0 (spawn #119328) 2025-04-26 05:58:25.087603 :8EE25DA0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 05:58:25.087603 :8EE25DA1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=198 time=2917906875 2025-04-26 05:58:25.087604 :8EE25DA2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 05:58:25.087604 :8EE25DA3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 05:58:25.087605 :8EE25DA4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 05:58:25.087605 :8EE25DA5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 05:58:25.087605 :8EE25DA6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 05:58:25.088089 :8EE25DA7: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-04-26 05:58:25.088717 :8EE25DBC: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-04-26 05:58:25.094076 :8EE25DD8: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-04-26 05:58:25.095146 :8EE25DE0: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-04-26 05:58:25.095283 :8EE25DE9: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-04-26 05:58:30.087675 :8EE25F64: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-04-26 05:58:30.087700*:8EE25F69:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002494BB58 estart=1745618309 eid=16830803 ctx=0x000000077BD98A08 cwh=0x00000000232802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-26 05:58:30.087700*:8EE25F6A:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002494BB58 curCtx=0x000000002328F300 pubTabIdxCnt=0 2025-04-26 05:58:30.087700*:8EE25F6B:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002494BB58 2025-04-26 05:58:30.087700*:8EE25F6C:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002494BB58 2025-04-26 05:58:30.087700*:8EE25F6D:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002494BB58 curCtx=0x000000002328F300 xsc->flg4=65792 flags=2 2025-04-26 05:58:30.087700*:8EE25F6E:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002494BB58 2025-04-26 05:58:30.088204 :8EE25F6F: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-04-26 05:58:30.099021 :8EE25F77:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-26 05:58:30.099485 :8EE25F78:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-04-26 05:58:30.101814 :8EE25F79:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 05:58:30.102180 :8EE25F7A: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-04-26 05:58:30.102215 :8EE25F7D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 05:58:30.103089 :8EE25F83: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-04-26 05:58:30.103215 :8EE25F8C: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-04-26 05:59:00.211983 :8EE26988:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:59:05.547170 :8EE26B3D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:59:05.537 2025-04-26 05:59:05.547172 :8EE26B3E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9292 pso_num=24 pso_serial#=217 2025-04-26 05:59:05.547304 :8EE26B3F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9292 (legacy spawn) 2025-04-26 05:59:05.553777 :8EE26B40: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-04-26 05:59:05.553954 :8EE26B45: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-04-26 05:59:05.554755 :8EE26B58:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:59:06.013647 :8EE26B67:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:59:06.006 2025-04-26 05:59:06.013649 :8EE26B68:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10264 pso_num=24 pso_serial#=218 2025-04-26 05:59:06.013778 :8EE26B69:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10264 (legacy spawn) 2025-04-26 05:59:06.020197 :8EE26B6A: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-04-26 05:59:06.020361 :8EE26B6F: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-04-26 05:59:06.021125 :8EE26B74:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:59:28.282494 :8EE27285:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:59:28.279 2025-04-26 05:59:28.282496 :8EE27286:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8028 pso_num=24 pso_serial#=219 2025-04-26 05:59:28.282635 :8EE27287:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8028 (legacy spawn) 2025-04-26 05:59:28.289099 :8EE27288: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-04-26 05:59:28.289268 :8EE2728D: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-04-26 05:59:28.290069 :8EE27292:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:59:28.298689 :8EE27295:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:59:28.295 2025-04-26 05:59:28.298691 :8EE27296:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10628 pso_num=24 pso_serial#=220 2025-04-26 05:59:28.298825 :8EE27297:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10628 (legacy spawn) 2025-04-26 05:59:28.305192 :8EE27298: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-04-26 05:59:28.305355 :8EE272A1: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-04-26 05:59:28.306151 :8EE272A2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-04-26 05:59:30.186099 :8EE27367:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 04-26 05:59:30.180 2025-04-26 05:59:30.186101 :8EE27368:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=2960 pso_num=24 pso_serial#=221 2025-04-26 05:59:30.186233 :8EE27369:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=2960 osp_idx=22 osp_ver=175405 osp_pg=0 (spawn #119331) 2025-04-26 05:59:30.186234 :8EE2736A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-04-26 05:59:30.186234 :8EE2736B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=199 time=2917971984 2025-04-26 05:59:30.186234 :8EE2736C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-04-26 05:59:30.186234 :8EE2736D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-04-26 05:59:30.186234 :8EE2736E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-04-26 05:59:30.186235 :8EE2736F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-04-26 05:59:30.186235 :8EE27370:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-04-26 05:59:30.186720 :8EE27371: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-04-26 05:59:30.187369 :8EE27386: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-04-26 05:59:30.194916 :8EE273A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-04-26 05:59:30.195279 :8EE273A3: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-04-26 05:59:30.195315 :8EE273A6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-04-26 05:59:30.196123 :8EE273AC: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-04-26 05:59:30.196234 :8EE273B5: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-04-26 06:00:00.222147 :8EE27EEC: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-04-26 06:00:00.222147*:8EE27EFE:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000002495C718 estart=1745618400 eid=16779278 ctx=0x000000077FD6A000 cwh=0x0000000024950068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-04-26 06:00:00.222147*:8EE27EFF:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000002495C718 curCtx=0x0000000024958938 pubTabIdxCnt=0 2025-04-26 06:00:00.222147*:8EE27F01:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000002495C718 2025-04-26 06:00:00.222147*:8EE27F04:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000002495C718 2025-04-26 06:00:00.226690 :8EE27F06:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=25 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 06:00:00.226785 :8EE27F08:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=25 loc='kcb2.h LINE:3844 ID:kcbzww' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-04-26 06:00:00.226785*:8EE27F0B:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000002495C718 curCtx=0x0000000024958938 xsc->flg4=65792 flags=2 2025-04-26 06:00:00.226785*:8EE27F0C:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000002495C718 2025-04-26 06:00:00.227421 :8EE27F17: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-04-26 06:00:00.227459 :8EE27F1B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-04-26 06:00:00.239236 :8EE28094: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-04-26 06:00:00.244251 :8EE280B9: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-04-26 06:00:00.245042 :8EE280D9: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-04-26 06:00:00.250826 :8EE2812D: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-04-26 06:00:00.250975 :8EE2813F: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-04-26 06:00:00.251251 :8EE28157:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.251688 :8EE28169: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-04-26 06:00:00.251864 :8EE2817E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-04-26 06:00:00.253420 :8EE281B7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='kcrf.h LINE:3299 ID:kcrfw_redo_gen: wake LGWR after redo copy' id1=0 id2=0 name= type=0 2025-04-26 06:00:00.253465 :8EE281B9: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-04-26 06:00:00.253501 :8EE281C1: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-04-26 06:00:00.253601 :8EE281D2: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-04-26 06:00:00.346727 :8EE281F5: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-04-26 06:00:00.348825 :8EE281F7: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-04-26 06:00:00.348871 :8EE281FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-04-26 06:00:00.351303 :8EE28200:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-04-26 06:00:00.354574 :8EE28201: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-04-26 06:00:00.354654 :8EE28204: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-04-26 06:00:00.354766 :8EE28211: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: 2960, J000)