Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250811220221\orcl_vkrm_20372_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:35576M/63366M, Ph+PgF:41005M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 20372, image: ORACLE.EXE (VKRM) *** 2025-08-11 22:02:22.013 *** SESSION ID:(193.17891) 2025-08-11 22:02:22.013 *** 2025-08-11 22:02:22.013 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=20372, pid: 24, proc_ser: 113, sid: 193, sess_ser: 17891 ------------------------------------------------------------------------------- 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=2 min 22 sec, exc=2 min 22 sec, total=2 min 22 sec wait times: max=infinite, heur=2 min 22 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 193 serial 17891 --------------------------------------------------- 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, 22:00:22 - 22:02:22] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.016000 sec (max dump time=30.000000 sec) *** 2025-08-11 22:02:22.029 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 20372, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-11 21:56:20.968337 :BE76B00B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 21:56:20.968831 :BE76B00C: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-08-11 21:56:20.969532 :BE76B021: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-08-11 21:56:20.980605 :BE76B03D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-11 21:56:20.981147 :BE76B03E:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-11 21:56:20.983481 :BE76B03F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-11 21:56:20.983820 :BE76B040: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-08-11 21:56:20.983857 :BE76B043:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-11 21:56:20.984719 :BE76B049: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-08-11 21:56:20.984840 :BE76B052: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-08-11 21:56:51.099148 :BE76B9F4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:14.223179 :BE76C0CE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:14.211 2025-08-11 21:57:14.223181 :BE76C0CF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20772 pso_num=24 pso_serial#=93 2025-08-11 21:57:14.223314 :BE76C0D0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20772 (legacy spawn) 2025-08-11 21:57:14.229766 :BE76C0D1: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-08-11 21:57:14.229945 :BE76C0D6: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-08-11 21:57:14.230732 :BE76C0DB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:15.276541 :BE76C12B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:15.262 2025-08-11 21:57:15.276543 :BE76C12C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16832 pso_num=24 pso_serial#=94 2025-08-11 21:57:15.276720 :BE76C12D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16832 (legacy spawn) 2025-08-11 21:57:15.283139 :BE76C12E: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-08-11 21:57:15.283320 :BE76C133: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-08-11 21:57:15.284129 :BE76C138:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:15.292773 :BE76C13B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:15.278 2025-08-11 21:57:15.292775 :BE76C13C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19608 pso_num=24 pso_serial#=95 2025-08-11 21:57:15.292913 :BE76C13D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19608 (legacy spawn) 2025-08-11 21:57:15.299384 :BE76C13E: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-08-11 21:57:15.299547 :BE76C147: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-08-11 21:57:15.300344 :BE76C148:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:18.253289 :BE76C235:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:18.243 2025-08-11 21:57:18.253291 :BE76C236:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7692 pso_num=24 pso_serial#=96 2025-08-11 21:57:18.253434 :BE76C237:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7692 (legacy spawn) 2025-08-11 21:57:18.260013 :BE76C238: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-08-11 21:57:18.260180 :BE76C241: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-08-11 21:57:18.260980 :BE76C242:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:18.395995 :BE76C245:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:18.384 2025-08-11 21:57:18.395997 :BE76C246:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16692 pso_num=24 pso_serial#=97 2025-08-11 21:57:18.396142 :BE76C247:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=16692 (legacy spawn) 2025-08-11 21:57:18.402653 :BE76C248: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-08-11 21:57:18.402826 :BE76C251: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-08-11 21:57:18.403641 :BE76C252:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:18.412362 :BE76C255:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:18.399 2025-08-11 21:57:18.412365 :BE76C256:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17836 pso_num=24 pso_serial#=98 2025-08-11 21:57:18.412514 :BE76C257:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17836 (legacy spawn) 2025-08-11 21:57:18.418960 :BE76C258: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-08-11 21:57:18.419137 :BE76C261: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-08-11 21:57:18.419934 :BE76C262:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:21.053306 :BE76C34B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:21.048 2025-08-11 21:57:21.053308 :BE76C34C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18492 pso_num=24 pso_serial#=99 2025-08-11 21:57:21.053448 :BE76C34D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18492 osp_idx=22 osp_ver=716149 osp_pg=0 (spawn #479143) 2025-08-11 21:57:21.053449 :BE76C34E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 21:57:21.053449 :BE76C34F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=126 time=3630110361 2025-08-11 21:57:21.053449 :BE76C350:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 21:57:21.053450 :BE76C351:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 21:57:21.053450 :BE76C352:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 21:57:21.053450 :BE76C353:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 21:57:21.053451 :BE76C354:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 21:57:21.053940 :BE76C355: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-08-11 21:57:21.054581 :BE76C36A: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-08-11 21:57:21.062147 :BE76C386:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-11 21:57:21.062534 :BE76C387: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-08-11 21:57:21.062569 :BE76C38A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-11 21:57:21.063435 :BE76C390: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-08-11 21:57:21.063559 :BE76C399: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-08-11 21:57:51.147903 :BE76CD68:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:51.677449 :BE76CD82:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:51.663 2025-08-11 21:57:51.677451 :BE76CD83:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20948 pso_num=24 pso_serial#=100 2025-08-11 21:57:51.677576 :BE76CD84:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20948 (legacy spawn) 2025-08-11 21:57:51.683993 :BE76CD85: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-08-11 21:57:51.684161 :BE76CD8E: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-08-11 21:57:51.684936 :BE76CD8F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:51.693645 :BE76CD92:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:51.679 2025-08-11 21:57:51.693647 :BE76CD93:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21272 pso_num=24 pso_serial#=101 2025-08-11 21:57:51.693791 :BE76CD94:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21272 (legacy spawn) 2025-08-11 21:57:51.700271 :BE76CD95: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-08-11 21:57:51.700433 :BE76CD9E: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-08-11 21:57:51.701243 :BE76CD9F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:52.438418 :BE76CDF7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:52.429 2025-08-11 21:57:52.438421 :BE76CDF8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20704 pso_num=24 pso_serial#=102 2025-08-11 21:57:52.438554 :BE76CDF9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20704 (legacy spawn) 2025-08-11 21:57:52.461867 :BE76CDFA: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-08-11 21:57:52.462050 :BE76CDFF: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-08-11 21:57:52.462992 :BE76CE04:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:54.766648 :BE76CE92:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:54.753 2025-08-11 21:57:54.766650 :BE76CE93:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14400 pso_num=24 pso_serial#=103 2025-08-11 21:57:54.766786 :BE76CE94:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14400 (legacy spawn) 2025-08-11 21:57:54.773336 :BE76CE95: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-08-11 21:57:54.773514 :BE76CE9A: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-08-11 21:57:54.774312 :BE76CE9F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:57:54.795378 :BE76CEA2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:57:54.788 2025-08-11 21:57:54.795380 :BE76CEA3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21252 pso_num=24 pso_serial#=104 2025-08-11 21:57:54.795515 :BE76CEA4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21252 (legacy spawn) 2025-08-11 21:57:54.801969 :BE76CEA5: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-08-11 21:57:54.802136 :BE76CEAE: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-08-11 21:57:54.802949 :BE76CEAF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:58:16.390032 :BE76D5A5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:58:16.377 2025-08-11 21:58:16.390034 :BE76D5A6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12604 pso_num=24 pso_serial#=105 2025-08-11 21:58:16.390179 :BE76D5A7:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12604 (legacy spawn) 2025-08-11 21:58:16.396688 :BE76D5A8: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-08-11 21:58:16.396854 :BE76D5AD: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-08-11 21:58:16.397655 :BE76D5B2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:58:21.137620 :BE76D6F5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:58:21.132 2025-08-11 21:58:21.137622 :BE76D6F6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21096 pso_num=24 pso_serial#=106 2025-08-11 21:58:21.137751 :BE76D6F7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21096 osp_idx=22 osp_ver=716156 osp_pg=0 (spawn #479147) 2025-08-11 21:58:21.137752 :BE76D6F8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 21:58:21.137752 :BE76D6F9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=127 time=3630170439 2025-08-11 21:58:21.137753 :BE76D6FA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 21:58:21.137753 :BE76D6FB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 21:58:21.137754 :BE76D6FC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 21:58:21.137754 :BE76D6FD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 21:58:21.137754 :BE76D6FE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 21:58:21.138223 :BE76D6FF: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-08-11 21:58:21.138809 :BE76D70B: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-08-11 21:58:21.150639 :BE76D753:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-11 21:58:21.151102 :BE76D754:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-11 21:58:21.153496 :BE76D755:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-11 21:58:21.153822 :BE76D756: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-08-11 21:58:21.153860 :BE76D759:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-11 21:58:21.154698 :BE76D75F: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-08-11 21:58:21.154819 :BE76D768: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-08-11 21:58:51.231083 :BE76E0F9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:59:00.743220 :BE76E3AF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:59:00.735 2025-08-11 21:59:00.743222 :BE76E3B0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21120 pso_num=24 pso_serial#=107 2025-08-11 21:59:00.743362 :BE76E3B1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21120 (legacy spawn) 2025-08-11 21:59:00.750263 :BE76E3B2: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-08-11 21:59:00.750465 :BE76E3BB: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-08-11 21:59:00.751521 :BE76E3BC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:59:04.287883 :BE76E4D0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:59:04.274 2025-08-11 21:59:04.287885 :BE76E4D1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21312 pso_num=24 pso_serial#=108 2025-08-11 21:59:04.288024 :BE76E4D2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21312 (legacy spawn) 2025-08-11 21:59:04.294520 :BE76E4D3: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-08-11 21:59:04.294689 :BE76E4D8: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-08-11 21:59:04.295470 :BE76E4DD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:59:04.304162 :BE76E4E0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:59:04.289 2025-08-11 21:59:04.304164 :BE76E4E1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15644 pso_num=24 pso_serial#=109 2025-08-11 21:59:04.304307 :BE76E4E2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15644 (legacy spawn) 2025-08-11 21:59:04.310748 :BE76E4E3: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-08-11 21:59:04.310921 :BE76E4EC: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-08-11 21:59:04.311725 :BE76E4ED:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:59:07.757279 :BE76E60F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:59:07.743 2025-08-11 21:59:07.757281 :BE76E610:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=5964 pso_num=24 pso_serial#=110 2025-08-11 21:59:07.757417 :BE76E611:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=5964 (legacy spawn) 2025-08-11 21:59:07.764060 :BE76E612: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-08-11 21:59:07.764241 :BE76E617: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-08-11 21:59:07.765047 :BE76E61C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:59:07.774078 :BE76E61F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:59:07.758 2025-08-11 21:59:07.774080 :BE76E620:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7652 pso_num=24 pso_serial#=111 2025-08-11 21:59:07.774229 :BE76E621:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=7652 (legacy spawn) 2025-08-11 21:59:07.780738 :BE76E622: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-08-11 21:59:07.780906 :BE76E62B: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-08-11 21:59:07.781702 :BE76E62C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 21:59:21.230148 :BE76EA26:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 21:59:21.225 2025-08-11 21:59:21.230150 :BE76EA27:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10656 pso_num=24 pso_serial#=112 2025-08-11 21:59:21.230291 :BE76EA28:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10656 osp_idx=22 osp_ver=716162 osp_pg=0 (spawn #479149) 2025-08-11 21:59:21.230292 :BE76EA29:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 21:59:21.230292 :BE76EA2A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=128 time=3630230533 2025-08-11 21:59:21.230293 :BE76EA2B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 21:59:21.230293 :BE76EA2C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 21:59:21.230294 :BE76EA2D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 21:59:21.230294 :BE76EA2E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 21:59:21.230294 :BE76EA2F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 21:59:21.230774 :BE76EA30: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-08-11 21:59:21.231414 :BE76EA45: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-08-11 21:59:21.238960 :BE76EA61:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-11 21:59:21.239334 :BE76EA62: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-08-11 21:59:21.239372 :BE76EA65:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-11 21:59:21.240232 :BE76EA6B: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-08-11 21:59:21.240361 :BE76EA74: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-08-11 21:59:51.313009 :BE76F3FD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-11 22:00:00.018631 :BE76F6D2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-11 22:00:00.012 2025-08-11 22:00:00.018633 :BE76F6D3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20372 pso_num=24 pso_serial#=113 2025-08-11 22:00:00.018740 :BE76F6D4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=20372 osp_idx=22 osp_ver=716163 osp_pg=0 (spawn #479151) 2025-08-11 22:00:00.018741 :BE76F6D5:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-11 22:00:00.018741 :BE76F6D6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=129 time=3630269329 2025-08-11 22:00:00.018742 :BE76F6D7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-11 22:00:00.018743 :BE76F6D8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-11 22:00:00.018743 :BE76F6D9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-11 22:00:00.018743 :BE76F6DA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-11 22:00:00.018744 :BE76F6DB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-11 22:00:00.018811 :BE76F6DC: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-08-11 22:00:00.018818 :BE76F6DD: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: 20372, VKRM)