Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250712141017\orcl_vkrm_8228_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:36415M/63366M, Ph+PgF:42493M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 8228, image: ORACLE.EXE (VKRM) *** 2025-07-12 14:10:18.012 *** SESSION ID:(177.16597) 2025-07-12 14:10:18.012 *** 2025-07-12 14:10:18.012 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=8228, pid: 22, proc_ser: 154, sid: 177, sess_ser: 16597 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=490 min 17 sec, exc=490 min 17 sec, total=490 min 17 sec wait times: max=infinite, heur=490 min 17 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.000000 sec since current wait Sampled Session History of session 177 serial 16597 --------------------------------------------------- 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, 14:08:18 - 14:10:18] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-12 14:10:18.012 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 8228, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-12 05:56:02.154834 :B0DD1AEC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16944 pso_num=22 pso_serial#=137 2025-07-12 05:56:02.154971 :B0DD1AED:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16944 osp_idx=0 osp_ver=392654 osp_pg=0 (spawn #376919) 2025-07-12 05:56:02.154971 :B0DD1AEE: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-12 05:56:02.154972 :B0DD1AEF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=191 time=980538048 2025-07-12 05:56:02.154972 :B0DD1AF0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 05:56:02.154972 :B0DD1AF1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 05:56:02.154973 :B0DD1AF2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 05:56:02.154973 :B0DD1AF3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 05:56:02.154973 :B0DD1AF4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 05:56:02.155467 :B0DD1AF5: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-12 05:56:02.156184 :B0DD1B0A: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-12 05:56:02.167261 :B0DD1B26:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-12 05:56:02.167722 :B0DD1B27:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-12 05:56:02.170053 :B0DD1B28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-12 05:56:02.170393 :B0DD1B29: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-12 05:56:02.170432 :B0DD1B2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-12 05:56:02.171293 :B0DD1B32: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-12 05:56:02.171430 :B0DD1B3B: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-12 05:56:32.289884 :B0DD2502:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:56:38.677950 :B0DD2734:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:56:38.663 2025-07-12 05:56:38.677952 :B0DD2735:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15164 pso_num=22 pso_serial#=138 2025-07-12 05:56:38.678089 :B0DD2736:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15164 (legacy spawn) 2025-07-12 05:56:38.684611 :B0DD2737: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-12 05:56:38.684786 :B0DD273C: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-12 05:56:38.685617 :B0DD2741:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:56:39.942161 :B0DD2763:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:56:39.928 2025-07-12 05:56:39.942163 :B0DD2764:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4508 pso_num=22 pso_serial#=139 2025-07-12 05:56:39.942301 :B0DD2765:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4508 (legacy spawn) 2025-07-12 05:56:39.948761 :B0DD2766: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-12 05:56:39.948925 :B0DD276B: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-12 05:56:39.949703 :B0DD276F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:56:39.958366 :B0DD2772:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:56:39.943 2025-07-12 05:56:39.958368 :B0DD2773:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1216 pso_num=22 pso_serial#=140 2025-07-12 05:56:39.958503 :B0DD2774:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=1216 (legacy spawn) 2025-07-12 05:56:39.964983 :B0DD2775: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-12 05:56:39.965144 :B0DD277E: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-12 05:56:39.965973 :B0DD277F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:56:42.988270 :B0DD2871:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:56:42.975 2025-07-12 05:56:42.988272 :B0DD2872:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17888 pso_num=22 pso_serial#=141 2025-07-12 05:56:42.988473 :B0DD2873:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17888 (legacy spawn) 2025-07-12 05:56:42.994895 :B0DD2874: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-12 05:56:42.994929 :B0DD2877: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-12 05:56:42.995106 :B0DD287F: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-12 05:56:42.995923 :B0DD288B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:56:43.013708 :B0DD288E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:56:43.006 2025-07-12 05:56:43.013711 :B0DD288F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9092 pso_num=22 pso_serial#=142 2025-07-12 05:56:43.013842 :B0DD2890:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9092 (legacy spawn) 2025-07-12 05:56:43.020301 :B0DD2891: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-12 05:56:43.020480 :B0DD2899: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-12 05:56:43.021257 :B0DD289A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:56:49.001301 :B0DD2A7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:56:48.988 2025-07-12 05:56:49.001303 :B0DD2A80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14344 pso_num=22 pso_serial#=143 2025-07-12 05:56:49.001444 :B0DD2A81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14344 (legacy spawn) 2025-07-12 05:56:49.008242 :B0DD2A82: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-12 05:56:49.008447 :B0DD2A87: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-12 05:56:49.009323 :B0DD2A8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:57:02.254274 :B0DD2F38:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:57:02.249 2025-07-12 05:57:02.254276 :B0DD2F39:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9284 pso_num=22 pso_serial#=144 2025-07-12 05:57:02.254416 :B0DD2F3A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9284 osp_idx=0 osp_ver=392661 osp_pg=0 (spawn #376921) 2025-07-12 05:57:02.254417 :B0DD2F3B: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-12 05:57:02.254417 :B0DD2F3C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=192 time=980598142 2025-07-12 05:57:02.254418 :B0DD2F3D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 05:57:02.254418 :B0DD2F3E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 05:57:02.254418 :B0DD2F3F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 05:57:02.254419 :B0DD2F40:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 05:57:02.254419 :B0DD2F41:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 05:57:02.254915 :B0DD2F42: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-12 05:57:02.255551 :B0DD2F57: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-12 05:57:02.263056 :B0DD2F73:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-12 05:57:02.263438 :B0DD2F74: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-12 05:57:02.263474 :B0DD2F77:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-12 05:57:02.264378 :B0DD2F7D: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-12 05:57:02.264499 :B0DD2F86: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-12 05:57:27.290498 :B0DD3877:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-12 05:57:27.290522*:B0DD387B:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1752271046 eid=16952968 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-07-12 05:57:27.290522*:B0DD387C:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-07-12 05:57:27.290522*:B0DD387D:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-07-12 05:57:27.290522*:B0DD387E:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-07-12 05:57:27.290522*:B0DD387F:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-07-12 05:57:27.290522*:B0DD3880:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-07-12 05:57:27.291071 :B0DD3881: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-12 05:57:27.296364 :B0DD3889: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-12 05:57:27.297236 :B0DD3891: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-12 05:57:27.297370 :B0DD3899: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-12 05:57:57.360434 :B0DD4299:db_trace:ksl2.c@2324:kslwtbctx(): [10005:22:0] KSL WAIT BEG [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-12 05:57:57.360447 :B0DD429A:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:0] KSL WAIT END [latch: call allocation] 136410064/0x82173d0 10/0xa 0/0x0 wait_id=0 seq_num=0 snap_id=0 2025-07-12 05:57:57.360449 :B0DD429B:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:0] KSL WAIT END wait times (usecs) - snap=14, exc=14, tot=14 2025-07-12 05:57:57.360605 :B0DD429F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:58:02.341744 :B0DD43EA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:58:02.336 2025-07-12 05:58:02.341747 :B0DD43EB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12780 pso_num=22 pso_serial#=145 2025-07-12 05:58:02.341883 :B0DD43EC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12780 osp_idx=0 osp_ver=392662 osp_pg=0 (spawn #376923) 2025-07-12 05:58:02.341884 :B0DD43ED: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-12 05:58:02.341884 :B0DD43EE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=193 time=980658236 2025-07-12 05:58:02.341885 :B0DD43EF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 05:58:02.341885 :B0DD43F0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 05:58:02.341885 :B0DD43F1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 05:58:02.341886 :B0DD43F2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 05:58:02.341886 :B0DD43F3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 05:58:02.342378 :B0DD43F4: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-12 05:58:02.343071 :B0DD4409: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-12 05:58:02.354105 :B0DD4425:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-12 05:58:02.354557 :B0DD4426:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-12 05:58:02.357006 :B0DD4427:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-12 05:58:02.357352 :B0DD4428: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-12 05:58:02.357387 :B0DD442B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-12 05:58:02.358249 :B0DD4431: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-12 05:58:02.358382 :B0DD443A: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-12 05:58:32.460265 :B0DD4DF5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:58:53.365645 :B0DD5473:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:58:53.352 2025-07-12 05:58:53.365648 :B0DD5474:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15428 pso_num=22 pso_serial#=146 2025-07-12 05:58:53.365783 :B0DD5475:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15428 (legacy spawn) 2025-07-12 05:58:53.372228 :B0DD5476: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-12 05:58:53.372404 :B0DD547B: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-12 05:58:53.373189 :B0DD5480:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:58:55.064822 :B0DD5531:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:58:55.055 2025-07-12 05:58:55.064824 :B0DD5532:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5196 pso_num=22 pso_serial#=147 2025-07-12 05:58:55.064960 :B0DD5533:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=5196 (legacy spawn) 2025-07-12 05:58:55.071420 :B0DD5534: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-12 05:58:55.071608 :B0DD5539: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-12 05:58:55.072584 :B0DD553E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:59:02.433383 :B0DD576C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:59:02.427 2025-07-12 05:59:02.433386 :B0DD576D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9364 pso_num=22 pso_serial#=148 2025-07-12 05:59:02.433523 :B0DD576E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9364 osp_idx=0 osp_ver=392665 osp_pg=0 (spawn #376925) 2025-07-12 05:59:02.433524 :B0DD576F: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-12 05:59:02.433525 :B0DD5770:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=194 time=980718329 2025-07-12 05:59:02.433525 :B0DD5771:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 05:59:02.433526 :B0DD5772:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 05:59:02.433526 :B0DD5773:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 05:59:02.433526 :B0DD5774:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 05:59:02.433527 :B0DD5775:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 05:59:02.434014 :B0DD5776: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-12 05:59:02.434662 :B0DD578B: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-12 05:59:02.442189 :B0DD57A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-12 05:59:02.442551 :B0DD57A8: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-12 05:59:02.442590 :B0DD57AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-12 05:59:02.443415 :B0DD57B1: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-12 05:59:02.443527 :B0DD57BA: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-12 05:59:32.557665 :B0DD61AD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:59:41.491437 :B0DD6473:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:59:41.481 2025-07-12 05:59:41.491440 :B0DD6474:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15304 pso_num=22 pso_serial#=149 2025-07-12 05:59:41.491576 :B0DD6475:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15304 (legacy spawn) 2025-07-12 05:59:41.498141 :B0DD6476: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-12 05:59:41.498313 :B0DD647B: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-12 05:59:41.499116 :B0DD6480:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:59:41.507774 :B0DD6483:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:59:41.497 2025-07-12 05:59:41.507776 :B0DD6484:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15704 pso_num=22 pso_serial#=150 2025-07-12 05:59:41.507910 :B0DD6485:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15704 (legacy spawn) 2025-07-12 05:59:41.514351 :B0DD6486: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-12 05:59:41.514518 :B0DD648F: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-12 05:59:41.515357 :B0DD6490:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:59:44.588909 :B0DD658A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:59:44.575 2025-07-12 05:59:44.588911 :B0DD658B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7536 pso_num=22 pso_serial#=151 2025-07-12 05:59:44.589052 :B0DD658C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=7536 (legacy spawn) 2025-07-12 05:59:44.595933 :B0DD658D: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-12 05:59:44.596120 :B0DD6592: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-12 05:59:44.596970 :B0DD6597:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:59:44.606235 :B0DD659A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:59:44.606 2025-07-12 05:59:44.606237 :B0DD659B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8552 pso_num=22 pso_serial#=152 2025-07-12 05:59:44.606384 :B0DD659C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8552 (legacy spawn) 2025-07-12 05:59:44.613271 :B0DD659D: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-12 05:59:44.613455 :B0DD65A6: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-12 05:59:44.614340 :B0DD65A7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 05:59:55.539050 :B0DD6924:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 05:59:55.526 2025-07-12 05:59:55.539052 :B0DD6925:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4884 pso_num=22 pso_serial#=153 2025-07-12 05:59:55.539190 :B0DD6926:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4884 (legacy spawn) 2025-07-12 05:59:55.545841 :B0DD6927: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-12 05:59:55.546018 :B0DD692C: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-12 05:59:55.546850 :B0DD6931:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-12 06:00:00.031988 :B0DD6B13:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-12 06:00:00.025 2025-07-12 06:00:00.031990 :B0DD6B14:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8228 pso_num=22 pso_serial#=154 2025-07-12 06:00:00.032100 :B0DD6B15:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8228 osp_idx=0 osp_ver=392671 osp_pg=0 (spawn #376928) 2025-07-12 06:00:00.032101 :B0DD6B16: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-12 06:00:00.032102 :B0DD6B17:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=195 time=980775923 2025-07-12 06:00:00.032102 :B0DD6B18:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-12 06:00:00.032103 :B0DD6B19:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-12 06:00:00.032103 :B0DD6B1A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-12 06:00:00.032104 :B0DD6B1B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-12 06:00:00.032104 :B0DD6B1C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-12 06:00:00.032179 :B0DD6B1D: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-12 06:00:00.032185 :B0DD6B1E: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: 8228, VKRM)