Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250618220008\orcl_vkrm_15256_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:36500M/63366M, Ph+PgF:42880M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15256, image: ORACLE.EXE (VKRM) *** 2025-06-18 22:00:09.042 *** SESSION ID:(193.6445) 2025-06-18 22:00:09.042 *** 2025-06-18 22:00:09.042 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=15256, pid: 24, proc_ser: 76, sid: 193, sess_ser: 6445 ------------------------------------------------------------------------------- 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.914981 sec, exc=8.914981 sec, total=8.914981 sec wait times: max=infinite, heur=8.914981 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.000991 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.100957 sec, exc=0.100957 sec, total=0.100957 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 193 serial 6445 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:01 - 22:00:09] idle wait at each sample [1 sample, 22:00:00] waited for 'resmgr:internal state change', seq_num: 1 p1: 'location'=0x3 p2: ' '=0x0 p3: ' '=0x0 time_waited: 0.100957 sec (sample interval: 0 sec) [1 sample, 21:59:59] idle wait at each sample [session created at: 22:00:00] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'resmgr:internal state change' [1 sample, 22:00:00 ] time_waited: 0.100957 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-18 22:00:09.042 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15256, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-18 21:56:27.711422 :A681186A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:56:27.720056 :A681186D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:56:27.706 2025-06-18 21:56:27.720058 :A681186E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15136 pso_num=24 pso_serial#=60 2025-06-18 21:56:27.720193 :A681186F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15136 (legacy spawn) 2025-06-18 21:56:27.726587 :A6811870: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-06-18 21:56:27.726755 :A6811879: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-06-18 21:56:27.727553 :A681187A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:56:27.930959 :A68118E2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:56:27.925 2025-06-18 21:56:27.930961 :A68118E3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17008 pso_num=24 pso_serial#=61 2025-06-18 21:56:27.931096 :A68118E4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17008 osp_idx=22 osp_ver=431540 osp_pg=0 (spawn #299139) 2025-06-18 21:56:27.931098 :A68118E5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 21:56:27.931098 :A68118E6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=83 time=3259531125 2025-06-18 21:56:27.931099 :A68118E7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 21:56:27.931099 :A68118E8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 21:56:27.931099 :A68118E9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 21:56:27.931100 :A68118EA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 21:56:27.931100 :A68118EB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 21:56:27.931587 :A68118EC: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-06-18 21:56:27.932241 :A6811901: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-06-18 21:56:27.943264 :A681191D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-18 21:56:27.943725 :A681191E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-18 21:56:27.946067 :A681191F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:56:27.946425 :A6811920: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-06-18 21:56:27.946462 :A6811923:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:56:27.947335 :A6811929: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-06-18 21:56:27.947459 :A6811932: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-06-18 21:56:52.952344 :A68120F0: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-06-18 21:57:23.025530 :A6812ABF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:57:28.015277 :A6812C54:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:57:28.009 2025-06-18 21:57:28.015279 :A6812C55:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17164 pso_num=24 pso_serial#=62 2025-06-18 21:57:28.015413 :A6812C56:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17164 osp_idx=22 osp_ver=431541 osp_pg=0 (spawn #299141) 2025-06-18 21:57:28.015414 :A6812C57:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 21:57:28.015414 :A6812C58:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=84 time=3259591204 2025-06-18 21:57:28.015415 :A6812C59:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 21:57:28.015415 :A6812C5A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 21:57:28.015415 :A6812C5B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 21:57:28.015416 :A6812C5C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 21:57:28.015416 :A6812C5D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 21:57:28.015891 :A6812C5E: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-06-18 21:57:28.016609 :A6812C73: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-06-18 21:57:28.024083 :A6812C8F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:57:28.024475 :A6812C90: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-06-18 21:57:28.024512 :A6812C93:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:57:28.025408 :A6812C99: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-06-18 21:57:28.025525 :A6812CA2: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-06-18 21:57:58.098080 :A6813628:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:57:58.714947 :A681362B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:57:58.712 2025-06-18 21:57:58.714949 :A681362C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16948 pso_num=24 pso_serial#=63 2025-06-18 21:57:58.715089 :A681362D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16948 (legacy spawn) 2025-06-18 21:57:58.721593 :A681362E: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-06-18 21:57:58.721761 :A6813633: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-06-18 21:57:58.722553 :A6813638:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:57:58.731279 :A6813647:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:57:58.727 2025-06-18 21:57:58.731282 :A6813648:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15036 pso_num=24 pso_serial#=64 2025-06-18 21:57:58.731415 :A6813649:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15036 (legacy spawn) 2025-06-18 21:57:58.737886 :A681364A: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-06-18 21:57:58.738047 :A6813653: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-06-18 21:57:58.738857 :A6813654:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:58:03.766389 :A68137CD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:58:03.753 2025-06-18 21:58:03.766391 :A68137CE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15496 pso_num=24 pso_serial#=65 2025-06-18 21:58:03.766531 :A68137CF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15496 (legacy spawn) 2025-06-18 21:58:03.773009 :A68137D0: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-06-18 21:58:03.773180 :A68137D6: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-06-18 21:58:03.773991 :A68137DA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:58:04.102015 :A6813829:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:58:04.097 2025-06-18 21:58:04.102017 :A681382A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16164 pso_num=24 pso_serial#=66 2025-06-18 21:58:04.102157 :A681382B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16164 osp_idx=22 osp_ver=431545 osp_pg=0 (spawn #299143) 2025-06-18 21:58:04.102158 :A681382C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 21:58:04.102158 :A681382D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=85 time=3259627297 2025-06-18 21:58:04.102159 :A681382E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 21:58:04.102159 :A681382F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 21:58:04.102160 :A6813830:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 21:58:04.102160 :A6813831:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 21:58:04.102160 :A6813832:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 21:58:04.102652 :A6813833:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 21:58:04.102702 :A6813839:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-06-18 21:58:04.122700 :A68138A6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:58:24.407170 :A6813E83:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:58:24.399 2025-06-18 21:58:24.407171 :A6813E84:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12700 pso_num=24 pso_serial#=67 2025-06-18 21:58:24.407310 :A6813E85:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12700 (legacy spawn) 2025-06-18 21:58:24.431712 :A6813E86: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-06-18 21:58:24.431893 :A6813E8B: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-06-18 21:58:24.432743 :A6813E90:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:58:28.103202 :A6813FDA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:58:28.097 2025-06-18 21:58:28.103204 :A6813FDB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17160 pso_num=24 pso_serial#=68 2025-06-18 21:58:28.103341 :A6813FDC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17160 osp_idx=22 osp_ver=431547 osp_pg=0 (spawn #299145) 2025-06-18 21:58:28.103342 :A6813FDD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 21:58:28.103342 :A6813FDE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=86 time=3259651297 2025-06-18 21:58:28.103343 :A6813FDF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 21:58:28.103343 :A6813FE0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 21:58:28.103344 :A6813FE1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 21:58:28.103344 :A6813FE2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 21:58:28.103344 :A6813FE3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 21:58:28.103841 :A6813FE4: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-06-18 21:58:28.104528 :A6813FF9: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-06-18 21:58:28.118711 :A6814016:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:58:28.118828 :A6814017:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:58:28.119664 :A6814018:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:58:28.120017 :A6814019: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-06-18 21:58:28.120058 :A681401C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:58:28.127939 :A6814022: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-06-18 21:58:28.128008 :A6814025:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-06-18 21:58:28.128043 :A6814026:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-06-18 21:58:28.128429 :A681402C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-18 21:58:28.128787 :A681402D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-18 21:58:28.128975 :A681402E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:58:28.129207 :A681402F: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-06-18 21:58:28.129234 :A6814032:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:58:28.130785 :A6814038:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:58:28.131012 :A6814039: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-06-18 21:58:28.131043 :A681403C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:58:28.132033 :A6814042: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-06-18 21:58:28.132159 :A681404B: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-06-18 21:58:58.236812 :A68149BD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:08.097629 :A6814CA2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:08.094 2025-06-18 21:59:08.097631 :A6814CA3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16196 pso_num=24 pso_serial#=69 2025-06-18 21:59:08.097770 :A6814CA4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16196 (legacy spawn) 2025-06-18 21:59:08.104232 :A6814CA5: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-06-18 21:59:08.104405 :A6814CAA: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-06-18 21:59:08.105200 :A6814CAF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:08.133529 :A6814CB2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:08.125 2025-06-18 21:59:08.133531 :A6814CB3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16928 pso_num=24 pso_serial#=70 2025-06-18 21:59:08.133678 :A6814CB4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16928 (legacy spawn) 2025-06-18 21:59:08.140134 :A6814CB5: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-06-18 21:59:08.140299 :A6814CBE: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-06-18 21:59:08.141136 :A6814CC7:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:11.186320 :A6814DB2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:11.173 2025-06-18 21:59:11.186322 :A6814DB3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14508 pso_num=24 pso_serial#=71 2025-06-18 21:59:11.186462 :A6814DB4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14508 (legacy spawn) 2025-06-18 21:59:11.193170 :A6814DB5: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-06-18 21:59:11.193353 :A6814DBA: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-06-18 21:59:11.194179 :A6814DBF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:11.203021 :A6814DC2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:11.188 2025-06-18 21:59:11.203023 :A6814DC3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13508 pso_num=24 pso_serial#=72 2025-06-18 21:59:11.203162 :A6814DC4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13508 (legacy spawn) 2025-06-18 21:59:11.209634 :A6814DC5: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-06-18 21:59:11.209793 :A6814DCE: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-06-18 21:59:11.210612 :A6814DCF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:11.853056 :A6814DF7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:11.844 2025-06-18 21:59:11.853058 :A6814DF8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14796 pso_num=24 pso_serial#=73 2025-06-18 21:59:11.853200 :A6814DF9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14796 (legacy spawn) 2025-06-18 21:59:11.859639 :A6814DFA: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-06-18 21:59:11.859804 :A6814E03: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-06-18 21:59:11.860593 :A6814E04:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:26.563479 :A681527C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:26.555 2025-06-18 21:59:26.563481 :A681527D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15868 pso_num=24 pso_serial#=74 2025-06-18 21:59:26.563625 :A681527E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15868 (legacy spawn) 2025-06-18 21:59:26.589933 :A681527F: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-06-18 21:59:26.590101 :A6815284: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-06-18 21:59:26.590896 :A6815289:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 21:59:28.154622 :A68152DA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 21:59:28.148 2025-06-18 21:59:28.154624 :A68152DB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15520 pso_num=24 pso_serial#=75 2025-06-18 21:59:28.154767 :A68152DC:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15520 osp_idx=22 osp_ver=431554 osp_pg=0 (spawn #299147) 2025-06-18 21:59:28.154767 :A68152DD:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 21:59:28.154768 :A68152DE:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=87 time=3259711344 2025-06-18 21:59:28.154768 :A68152DF:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 21:59:28.154769 :A68152E0:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 21:59:28.154769 :A68152E1:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 21:59:28.154769 :A68152E2:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 21:59:28.154769 :A68152E3:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 21:59:28.155269 :A68152E4: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-06-18 21:59:28.155916 :A68152F9: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-06-18 21:59:28.163411 :A6815315:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-18 21:59:28.163822 :A6815316: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-06-18 21:59:28.163855 :A6815319:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-18 21:59:28.164745 :A681534F: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-06-18 21:59:28.164864 :A6815358: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-06-18 21:59:58.293172 :A6815CD6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-18 22:00:00.023691 :A6815D74:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-18 22:00:00.017 2025-06-18 22:00:00.023693 :A6815D75:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15256 pso_num=24 pso_serial#=76 2025-06-18 22:00:00.023840 :A6815D76:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15256 osp_idx=22 osp_ver=431555 osp_pg=0 (spawn #299149) 2025-06-18 22:00:00.023840 :A6815D77:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-18 22:00:00.023841 :A6815D78:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=88 time=3259743219 2025-06-18 22:00:00.023842 :A6815D79:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-18 22:00:00.023842 :A6815D7A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-18 22:00:00.023842 :A6815D7B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-18 22:00:00.023843 :A6815D7C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-18 22:00:00.023843 :A6815D7D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-18 22:00:00.023926 :A6815D7E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-18 22:00:00.023932 :A6815D7F:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:24:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 15256, VKRM)