Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250722220008\orcl_vkrm_15748_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:36070M/63366M, Ph+PgF:41887M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 15748, image: ORACLE.EXE (VKRM) *** 2025-07-22 22:00:08.176 *** SESSION ID:(177.47919) 2025-07-22 22:00:08.176 *** 2025-07-22 22:00:08.176 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=15748, pid: 22, proc_ser: 13, sid: 177, sess_ser: 47919 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=8.066357 sec, exc=8.066357 sec, total=8.066357 sec wait times: max=infinite, heur=8.066357 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.001200 sec since current wait 0: waited for 'resmgr:internal state change' location=0x3, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.100708 sec, exc=0.100708 sec, total=0.100708 sec wait times: max=0.100000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 177 serial 47919 --------------------------------------------------- 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, 22:00:00 - 22:00:08] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-22 22:00:08.176 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 15748, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-22 21:57:15.048103 :B59E6835: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-22 21:57:15.048281 :B59E683E: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-22 21:57:15.049178 :B59E683F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:57:15.807749 :B59E68DA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:57:15.802 2025-07-22 21:57:15.807752 :B59E68DB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15532 pso_num=22 pso_serial#=250 2025-07-22 21:57:15.807886 :B59E68DC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15532 osp_idx=0 osp_ver=433567 osp_pg=0 (spawn #412473) 2025-07-22 21:57:15.807887 :B59E68DD: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-22 21:57:15.807887 :B59E68DE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=85 time=1902211704 2025-07-22 21:57:15.807887 :B59E68DF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:57:15.807888 :B59E68E0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:57:15.807888 :B59E68E1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:57:15.807889 :B59E68E2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:57:15.807889 :B59E68E3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:57:15.808362 :B59E68E4: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-22 21:57:15.809001 :B59E68F9: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-22 21:57:15.816539 :B59E6915:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-22 21:57:15.816904 :B59E6916: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-22 21:57:15.816941 :B59E6919:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-22 21:57:15.817878 :B59E691F: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-22 21:57:15.818005 :B59E6928: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-22 21:57:45.937896 :B59E7292:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:57:48.118914 :B59E72F4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:57:48.103 2025-07-22 21:57:48.118916 :B59E72F5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19208 pso_num=22 pso_serial#=251 2025-07-22 21:57:48.119050 :B59E72F6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19208 (legacy spawn) 2025-07-22 21:57:48.125584 :B59E7307: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-22 21:57:48.125751 :B59E730C: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-22 21:57:48.126557 :B59E7311:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:57:48.143962 :B59E7314:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:57:48.135 2025-07-22 21:57:48.143964 :B59E7315:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4152 pso_num=22 pso_serial#=252 2025-07-22 21:57:48.144100 :B59E7316:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4152 (legacy spawn) 2025-07-22 21:57:48.150620 :B59E7317: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-22 21:57:48.150809 :B59E7320: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-22 21:57:48.151661 :B59E7321:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:57:51.277254 :B59E7443:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:57:51.264 2025-07-22 21:57:51.277256 :B59E7444:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9700 pso_num=22 pso_serial#=253 2025-07-22 21:57:51.277441 :B59E7445:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9700 (legacy spawn) 2025-07-22 21:57:51.283983 :B59E747B: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-22 21:57:51.284160 :B59E7481: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-22 21:57:51.284976 :B59E7485:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:57:51.293619 :B59E7488:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:57:51.280 2025-07-22 21:57:51.293621 :B59E7489:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1484 pso_num=22 pso_serial#=254 2025-07-22 21:57:51.293764 :B59E748A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1484 (legacy spawn) 2025-07-22 21:57:51.300266 :B59E748B: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-22 21:57:51.300446 :B59E7494: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-22 21:57:51.301246 :B59E7495:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:57:55.730965 :B59E75B3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:57:55.722 2025-07-22 21:57:55.730967 :B59E75B4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9028 pso_num=22 pso_serial#=255 2025-07-22 21:57:55.731108 :B59E75B5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9028 (legacy spawn) 2025-07-22 21:57:55.737622 :B59E75B6: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-22 21:57:55.737802 :B59E75BB: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-22 21:57:55.738624 :B59E75C0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:58:03.304187 :B59E782E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:58:03.299 2025-07-22 21:58:03.304188 :B59E782F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18840 pso_num=22 pso_serial#=1 2025-07-22 21:58:03.304329 :B59E7830:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18840 osp_idx=0 osp_ver=433573 osp_pg=0 (spawn #412475) 2025-07-22 21:58:03.304330 :B59E7831:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 21:58:03.304331 :B59E7832:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=86 time=1902259204 2025-07-22 21:58:03.304331 :B59E7833:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:58:03.304331 :B59E7834:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:58:03.304332 :B59E7835:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:58:03.304332 :B59E7836:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:58:03.304332 :B59E7837:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:58:03.304820 :B59E7838:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 21:58:03.304862 :B59E783E:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-22 21:58:03.323898 :B59E7885:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:58:03.796717 :B59E78AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:58:03.783 2025-07-22 21:58:03.796719 :B59E78AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17332 pso_num=22 pso_serial#=2 2025-07-22 21:58:03.796854 :B59E78AC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17332 (legacy spawn) 2025-07-22 21:58:03.803295 :B59E78AD: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-22 21:58:03.803466 :B59E78B2: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-22 21:58:03.804250 :B59E78B7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:58:03.813009 :B59E78BA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:58:03.799 2025-07-22 21:58:03.813010 :B59E78BB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17976 pso_num=22 pso_serial#=3 2025-07-22 21:58:03.813150 :B59E78BC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17976 (legacy spawn) 2025-07-22 21:58:03.819697 :B59E78BD: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-22 21:58:03.819863 :B59E78C6: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-22 21:58:03.820679 :B59E78C7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:58:09.497949 :B59E7A8D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:58:09.490 2025-07-22 21:58:09.497951 :B59E7A8E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19056 pso_num=22 pso_serial#=4 2025-07-22 21:58:09.498095 :B59E7A8F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19056 (legacy spawn) 2025-07-22 21:58:09.523587 :B59E7A90: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-22 21:58:09.523768 :B59E7A95: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-22 21:58:09.524586 :B59E7A9A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:58:15.927121 :B59E7C9C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:58:15.921 2025-07-22 21:58:15.927123 :B59E7C9D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15184 pso_num=22 pso_serial#=5 2025-07-22 21:58:15.927257 :B59E7C9E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15184 osp_idx=0 osp_ver=433577 osp_pg=0 (spawn #412476) 2025-07-22 21:58:15.927257 :B59E7C9F: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-22 21:58:15.927258 :B59E7CA0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=87 time=1902271814 2025-07-22 21:58:15.927258 :B59E7CA1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:58:15.927259 :B59E7CA2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:58:15.927260 :B59E7CA3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:58:15.927260 :B59E7CA4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:58:15.927260 :B59E7CA5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:58:15.927748 :B59E7CA6: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-22 21:58:15.928375 :B59E7CBB: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-22 21:58:15.942711 :B59E7CD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-22 21:58:15.942797 :B59E7CD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-22 21:58:15.943582 :B59E7CD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-22 21:58:15.943912 :B59E7CDA: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-22 21:58:15.943952 :B59E7CDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-22 21:58:15.951946 :B59E7CE3: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-22 21:58:15.952022 :B59E7CE6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-07-22 21:58:15.952061 :B59E7CE7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-07-22 21:58:15.952418 :B59E7CED:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-22 21:58:15.952812 :B59E7CEE:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-22 21:58:15.953040 :B59E7CEF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-22 21:58:15.953277 :B59E7CF0: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-22 21:58:15.953304 :B59E7CF3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-22 21:58:15.954929 :B59E7CF9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-22 21:58:15.955163 :B59E7CFA: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-22 21:58:15.955195 :B59E7CFD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-22 21:58:15.956111 :B59E7D03: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-22 21:58:15.956243 :B59E7D0C: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-22 21:58:46.036714 :B59E86B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:00.889738 :B59E8B95:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:00.890 2025-07-22 21:59:00.889740 :B59E8B96:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18776 pso_num=22 pso_serial#=6 2025-07-22 21:59:00.889892 :B59E8B97:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18776 (legacy spawn) 2025-07-22 21:59:00.896637 :B59E8B98: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-22 21:59:00.896824 :B59E8B9D: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-22 21:59:00.897661 :B59E8BA2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:00.906681 :B59E8BA5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:00.905 2025-07-22 21:59:00.906683 :B59E8BA6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5284 pso_num=22 pso_serial#=7 2025-07-22 21:59:00.906819 :B59E8BA7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=5284 (legacy spawn) 2025-07-22 21:59:00.922744 :B59E8BA8: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-22 21:59:00.922914 :B59E8BB1: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-22 21:59:00.923830 :B59E8BB2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:03.820443 :B59E8CA3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:03.812 2025-07-22 21:59:03.820445 :B59E8CA4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13600 pso_num=22 pso_serial#=8 2025-07-22 21:59:03.820581 :B59E8CA5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13600 (legacy spawn) 2025-07-22 21:59:03.827002 :B59E8CA6: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-22 21:59:03.827192 :B59E8CAF: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-22 21:59:03.827984 :B59E8CBC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:03.944866 :B59E8CBF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:03.937 2025-07-22 21:59:03.944868 :B59E8CC0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12272 pso_num=22 pso_serial#=9 2025-07-22 21:59:03.945011 :B59E8CC1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12272 (legacy spawn) 2025-07-22 21:59:03.951419 :B59E8CC2: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-22 21:59:03.951592 :B59E8CCB: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-22 21:59:03.952415 :B59E8CCC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:03.961116 :B59E8CCF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:03.952 2025-07-22 21:59:03.961119 :B59E8CD0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19132 pso_num=22 pso_serial#=10 2025-07-22 21:59:03.961263 :B59E8CD1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19132 (legacy spawn) 2025-07-22 21:59:03.967733 :B59E8CD2: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-22 21:59:03.967922 :B59E8CDA: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-22 21:59:03.968744 :B59E8CDC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:11.683045 :B59E8EEC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:11.671 2025-07-22 21:59:11.683048 :B59E8EED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17604 pso_num=22 pso_serial#=11 2025-07-22 21:59:11.683188 :B59E8EEE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17604 (legacy spawn) 2025-07-22 21:59:11.689570 :B59E8EEF: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-22 21:59:11.689744 :B59E8EF4: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-22 21:59:11.690525 :B59E8EF9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 21:59:15.995727 :B59E90C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 21:59:15.990 2025-07-22 21:59:15.995730 :B59E90C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1852 pso_num=22 pso_serial#=12 2025-07-22 21:59:15.995864 :B59E90C4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1852 osp_idx=0 osp_ver=433584 osp_pg=0 (spawn #412478) 2025-07-22 21:59:15.995864 :B59E90C5: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-22 21:59:15.995865 :B59E90C6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=88 time=1902331892 2025-07-22 21:59:15.995865 :B59E90C7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 21:59:15.995865 :B59E90C8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 21:59:15.995866 :B59E90C9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 21:59:15.995866 :B59E90CA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 21:59:15.995866 :B59E90CB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 21:59:15.996358 :B59E90CC: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-22 21:59:15.996993 :B59E90E1: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-22 21:59:16.004557 :B59E90FD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-22 21:59:16.004904 :B59E90FE: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-22 21:59:16.004945 :B59E9101:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-22 21:59:16.005782 :B59E9107: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-22 21:59:16.005904 :B59E9110: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-22 21:59:46.121862 :B59E9AB8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-22 22:00:00.012181 :B59E9ED6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-22 22:00:00.004 2025-07-22 22:00:00.012183 :B59E9ED7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15748 pso_num=22 pso_serial#=13 2025-07-22 22:00:00.012291 :B59E9ED8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15748 osp_idx=0 osp_ver=433585 osp_pg=0 (spawn #412480) 2025-07-22 22:00:00.012292 :B59E9ED9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-22 22:00:00.012292 :B59E9EDA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=89 time=1902375908 2025-07-22 22:00:00.012293 :B59E9EDB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-22 22:00:00.012293 :B59E9EDC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-22 22:00:00.012293 :B59E9EDD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-22 22:00:00.012294 :B59E9EDE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-22 22:00:00.012294 :B59E9EDF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-22 22:00:00.012365 :B59E9EE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-22 22:00:00.012372 :B59E9EE1:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:22:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 15748, VKRM)