Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817060006\orcl_w000_18016_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:35427M/63366M, Ph+PgF:40726M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 18016, image: ORACLE.EXE (W000) *** 2025-08-17 06:00:06.833 *** SESSION ID:(177.4577) 2025-08-17 06:00:06.833 *** 2025-08-17 06:00:06.833 Process diagnostic dump for ORACLE.EXE (W000), OS id=18016, pid: 22, proc_ser: 177, sid: 177, sess_ser: 4577 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=65 seq_num=66 snap_id=1 wait times: snap=1.737310 sec, exc=1.737310 sec, total=1.737310 sec wait times: max=5.000000 sec, heur=5 min 22 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.000036 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=64 seq_num=65 snap_id=1 wait times: snap=5.010168 sec, exc=5.010168 sec, total=5.010168 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=63 seq_num=64 snap_id=1 wait times: snap=5.004127 sec, exc=5.004127 sec, total=5.004127 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=5.007191 sec, exc=5.007191 sec, total=5.007191 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.004628 sec, exc=5.004628 sec, total=5.004628 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=60 seq_num=61 snap_id=1 wait times: snap=5.004619 sec, exc=5.004619 sec, total=5.004619 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=5.009205 sec, exc=5.009205 sec, total=5.009205 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=58 seq_num=59 snap_id=1 wait times: snap=5.010154 sec, exc=5.010154 sec, total=5.010154 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=57 seq_num=58 snap_id=1 wait times: snap=5.012399 sec, exc=5.012399 sec, total=5.012399 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=56 seq_num=57 snap_id=1 wait times: snap=5.001324 sec, exc=5.001324 sec, total=5.001324 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=55 seq_num=56 snap_id=1 wait times: snap=5.010411 sec, exc=5.010411 sec, total=5.010411 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time Sampled Session History of session 177 serial 4577 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 05:58:06 - 06:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-17 06:00:06.833 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 18016, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 05:41:46.206106 :C0CF2666: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-08-17 05:41:46.207008 :C0CF2667:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:46.216283 :C0CF266A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:46.214 2025-08-17 05:41:46.216286 :C0CF266B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22344 pso_num=22 pso_serial#=159 2025-08-17 05:41:46.216435 :C0CF266C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22344 (legacy spawn) 2025-08-17 05:41:46.223386 :C0CF266D: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-08-17 05:41:46.223597 :C0CF2676: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-08-17 05:41:46.224514 :C0CF2677:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:49.287312 :C0CF2767:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:49.279 2025-08-17 05:41:49.287314 :C0CF2768:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21664 pso_num=22 pso_serial#=160 2025-08-17 05:41:49.287456 :C0CF2769:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21664 (legacy spawn) 2025-08-17 05:41:49.293926 :C0CF276A: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-08-17 05:41:49.294103 :C0CF2770: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-08-17 05:41:49.294930 :C0CF2774:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:49.303563 :C0CF2777:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:49.295 2025-08-17 05:41:49.303565 :C0CF2778:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20656 pso_num=22 pso_serial#=161 2025-08-17 05:41:49.303721 :C0CF2779:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20656 (legacy spawn) 2025-08-17 05:41:49.310169 :C0CF277A: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-08-17 05:41:49.310334 :C0CF2783: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-08-17 05:41:49.311176 :C0CF2784:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:50.177829 :C0CF279E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:50.170 2025-08-17 05:41:50.177831 :C0CF279F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21092 pso_num=22 pso_serial#=162 2025-08-17 05:41:50.177972 :C0CF27A0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21092 (legacy spawn) 2025-08-17 05:41:50.184459 :C0CF27A1: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-08-17 05:41:50.184633 :C0CF27A6: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-08-17 05:41:50.185459 :C0CF27AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:52.385956 :C0CF287B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:52.378 2025-08-17 05:41:52.385958 :C0CF287C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21044 pso_num=22 pso_serial#=163 2025-08-17 05:41:52.386095 :C0CF287D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21044 (legacy spawn) 2025-08-17 05:41:52.392578 :C0CF287E: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-08-17 05:41:52.392760 :C0CF2883: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-08-17 05:41:52.393584 :C0CF2888:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:52.402261 :C0CF288B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:52.394 2025-08-17 05:41:52.402263 :C0CF288C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22180 pso_num=22 pso_serial#=164 2025-08-17 05:41:52.402394 :C0CF288D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22180 (legacy spawn) 2025-08-17 05:41:52.409137 :C0CF288E: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-08-17 05:41:52.409293 :C0CF2897: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-08-17 05:41:52.410380 :C0CF2898:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:41:57.758493 :C0CF2A17:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:41:57.752 2025-08-17 05:41:57.758495 :C0CF2A18:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20172 pso_num=22 pso_serial#=165 2025-08-17 05:41:57.758647 :C0CF2A19:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20172 osp_idx=0 osp_ver=526302 osp_pg=0 (spawn #496884) 2025-08-17 05:41:57.758647 :C0CF2A1A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 05:41:57.758648 :C0CF2A1B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=207 time=4089987064 2025-08-17 05:41:57.758648 :C0CF2A1C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:41:57.758648 :C0CF2A1D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:41:57.758649 :C0CF2A1E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:41:57.758649 :C0CF2A1F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:41:57.758650 :C0CF2A20:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:41:57.759189 :C0CF2A21: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-08-17 05:41:57.759998 :C0CF2A36: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-08-17 05:41:57.768221 :C0CF2A52:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-17 05:41:57.768649 :C0CF2A53: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-08-17 05:41:57.768691 :C0CF2A56:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-17 05:41:57.769669 :C0CF2A5C: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-08-17 05:41:57.769827 :C0CF2A65: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-08-17 05:42:27.844727 :C0CF3426:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:42:48.937216 :C0CF3A9B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:42:48.924 2025-08-17 05:42:48.937218 :C0CF3A9C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22160 pso_num=22 pso_serial#=166 2025-08-17 05:42:48.937362 :C0CF3A9D:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22160 (legacy spawn) 2025-08-17 05:42:48.943837 :C0CF3A9E: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-08-17 05:42:48.944010 :C0CF3AA6: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-08-17 05:42:48.944783 :C0CF3AA8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:42:52.366133 :C0CF3BF8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:42:52.358 2025-08-17 05:42:52.366134 :C0CF3BF9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21604 pso_num=22 pso_serial#=167 2025-08-17 05:42:52.366267 :C0CF3BFA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21604 (legacy spawn) 2025-08-17 05:42:52.372720 :C0CF3BFB: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-08-17 05:42:52.372897 :C0CF3C00: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-08-17 05:42:52.373729 :C0CF3C05:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:42:57.841350 :C0CF3D82:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:42:57.835 2025-08-17 05:42:57.841352 :C0CF3D83:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15096 pso_num=22 pso_serial#=168 2025-08-17 05:42:57.841489 :C0CF3D84:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15096 osp_idx=0 osp_ver=526305 osp_pg=0 (spawn #496886) 2025-08-17 05:42:57.841489 :C0CF3D85:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 05:42:57.841490 :C0CF3D86:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=208 time=4090047142 2025-08-17 05:42:57.841490 :C0CF3D87:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:42:57.841490 :C0CF3D88:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:42:57.841491 :C0CF3D89:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:42:57.841491 :C0CF3D8A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:42:57.841492 :C0CF3D8B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:42:57.841976 :C0CF3D8C: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-08-17 05:42:57.842600 :C0CF3DA1: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-08-17 05:42:57.857131 :C0CF3DBE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-17 05:42:57.857214 :C0CF3DBF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-17 05:42:57.857998 :C0CF3DC0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-17 05:42:57.858327 :C0CF3DC1: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-08-17 05:42:57.858364 :C0CF3DC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-17 05:42:57.866345 :C0CF3DCA: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-08-17 05:42:57.866408 :C0CF3DCD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-08-17 05:42:57.866445 :C0CF3DCE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-08-17 05:42:57.866848 :C0CF3DD4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-17 05:42:57.867184 :C0CF3DD5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-17 05:42:57.867377 :C0CF3DD6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-17 05:42:57.867625 :C0CF3DD7: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-08-17 05:42:57.867652 :C0CF3DDA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-17 05:42:57.869338 :C0CF3DE0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-17 05:42:57.869583 :C0CF3DE1: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-08-17 05:42:57.869616 :C0CF3DE4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-17 05:42:57.870535 :C0CF3DEA: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-08-17 05:42:57.870669 :C0CF3DF3: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-08-17 05:43:27.985316 :C0CF4796:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:34.969332 :C0CF49F1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:34.955 2025-08-17 05:43:34.969334 :C0CF49F2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20576 pso_num=22 pso_serial#=169 2025-08-17 05:43:34.969479 :C0CF49F3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20576 (legacy spawn) 2025-08-17 05:43:34.975938 :C0CF49F4: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-08-17 05:43:34.976110 :C0CF49FD: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-08-17 05:43:34.976908 :C0CF49FE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:34.985643 :C0CF4A01:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:34.971 2025-08-17 05:43:34.985645 :C0CF4A02:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21932 pso_num=22 pso_serial#=170 2025-08-17 05:43:34.985779 :C0CF4A03:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21932 (legacy spawn) 2025-08-17 05:43:34.992278 :C0CF4A04: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-08-17 05:43:34.992441 :C0CF4A0D: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-08-17 05:43:34.993256 :C0CF4A0E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:38.183051 :C0CF4B0D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:38.169 2025-08-17 05:43:38.183053 :C0CF4B0E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21448 pso_num=22 pso_serial#=171 2025-08-17 05:43:38.183209 :C0CF4B0F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21448 (legacy spawn) 2025-08-17 05:43:38.190231 :C0CF4B10: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-08-17 05:43:38.190429 :C0CF4B15: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-08-17 05:43:38.191387 :C0CF4B1A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:38.200623 :C0CF4B1D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:38.200 2025-08-17 05:43:38.200625 :C0CF4B1E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14024 pso_num=22 pso_serial#=172 2025-08-17 05:43:38.200775 :C0CF4B1F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14024 (legacy spawn) 2025-08-17 05:43:38.207486 :C0CF4B20: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-08-17 05:43:38.207666 :C0CF4B29: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-08-17 05:43:38.208600 :C0CF4B2A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:54.968114 :C0CF5016:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:54.955 2025-08-17 05:43:54.968116 :C0CF5017:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17932 pso_num=22 pso_serial#=173 2025-08-17 05:43:54.968258 :C0CF5018:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17932 (legacy spawn) 2025-08-17 05:43:54.974761 :C0CF5019: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-08-17 05:43:54.974932 :C0CF5022: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-08-17 05:43:54.975726 :C0CF5023:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:57.022160 :C0CF50BA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:57.018 2025-08-17 05:43:57.022162 :C0CF50BB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22132 pso_num=22 pso_serial#=174 2025-08-17 05:43:57.022305 :C0CF50BC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22132 (legacy spawn) 2025-08-17 05:43:57.028746 :C0CF50BD: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-08-17 05:43:57.028938 :C0CF50C2: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-08-17 05:43:57.029738 :C0CF50C7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:43:57.914494 :C0CF5123:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:43:57.909 2025-08-17 05:43:57.914496 :C0CF5124:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20680 pso_num=22 pso_serial#=175 2025-08-17 05:43:57.914630 :C0CF5125:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20680 osp_idx=0 osp_ver=526312 osp_pg=0 (spawn #496888) 2025-08-17 05:43:57.914631 :C0CF5126:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 05:43:57.914631 :C0CF5127:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=209 time=4090107220 2025-08-17 05:43:57.914632 :C0CF5128:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:43:57.914632 :C0CF5129:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:43:57.914633 :C0CF512A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:43:57.914633 :C0CF512B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:43:57.914633 :C0CF512C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:43:57.915176 :C0CF512D: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-08-17 05:43:57.915806 :C0CF5142: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-08-17 05:43:57.923261 :C0CF515E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-17 05:43:57.923693 :C0CF515F: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-08-17 05:43:57.923731 :C0CF5162:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-17 05:43:57.924597 :C0CF5168: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-08-17 05:43:57.924713 :C0CF5171: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-08-17 05:44:12.920477 :C0CF562B: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-08-17 05:44:43.041235 :C0CF600D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:44:43.327230 :C0CF6048:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:44:43.322 2025-08-17 05:44:43.327231 :C0CF6049:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19304 pso_num=22 pso_serial#=176 2025-08-17 05:44:43.327367 :C0CF604A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19304 osp_idx=0 osp_ver=526313 osp_pg=0 (spawn #496891) 2025-08-17 05:44:43.327368 :C0CF604B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 05:44:43.327368 :C0CF604C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=210 time=4090152626 2025-08-17 05:44:43.327368 :C0CF604D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:44:43.327369 :C0CF604E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:44:43.327369 :C0CF604F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:44:43.327369 :C0CF6050:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:44:43.327370 :C0CF6051:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:44:43.327853 :C0CF6052:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 05:44:43.327896 :C0CF605B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-17 05:54:44.166937 :C0D026B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-17 05:54:44.624769 :C0D026EE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-17 05:54:44.620 2025-08-17 05:54:44.624771 :C0D026EF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18016 pso_num=22 pso_serial#=177 2025-08-17 05:54:44.624908 :C0D026F0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18016 osp_idx=0 osp_ver=526314 osp_pg=0 (spawn #496913) 2025-08-17 05:54:44.624908 :C0D026F1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 05:54:44.624909 :C0D026F2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=211 time=4090753923 2025-08-17 05:54:44.624909 :C0D026F3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 05:54:44.624909 :C0D026F4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 05:54:44.624910 :C0D026F5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 05:54:44.624910 :C0D026F6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 05:54:44.624911 :C0D026F7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 05:54:44.625426 :C0D026F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-17 05:54:44.625475 :C0D026FF:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 18016, W000)