Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250601220818\orcl_vkrm_11776_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:37165M/63366M, Ph+PgF:44050M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 11776, image: ORACLE.EXE (VKRM) *** 2025-06-01 22:08:18.722 *** SESSION ID:(193.8261) 2025-06-01 22:08:18.722 *** 2025-06-01 22:08:18.722 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=11776, pid: 24, proc_ser: 16, sid: 193, sess_ser: 8261 ------------------------------------------------------------------------------- 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=968 min 18 sec, exc=968 min 18 sec, total=968 min 18 sec wait times: max=infinite, heur=968 min 18 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 8261 --------------------------------------------------- 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:06:18 - 22:08:18] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-01 22:08:18.722 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 11776, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-01 05:56:27.791050 :9EC05BC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-01 05:56:27.791997 :9EC05BC8: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-01 05:56:27.792118 :9EC05BD1: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-01 05:56:57.862957 :9EC06508:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:56:58.936325 :9EC0653E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:56:58.928 2025-06-01 05:56:58.936327 :9EC0653F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10836 pso_num=24 pso_serial#=250 2025-06-01 05:56:58.936460 :9EC06540:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10836 (legacy spawn) 2025-06-01 05:56:58.942919 :9EC06541: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-01 05:56:58.943090 :9EC06546: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-01 05:56:58.943926 :9EC0654B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:02.347204 :9EC06654:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:02.339 2025-06-01 05:57:02.347206 :9EC06655:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14340 pso_num=24 pso_serial#=251 2025-06-01 05:57:02.347346 :9EC06656:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14340 (legacy spawn) 2025-06-01 05:57:02.353713 :9EC06657: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-01 05:57:02.353892 :9EC0665C: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-01 05:57:02.354731 :9EC06661:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:02.363376 :9EC06664:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:02.355 2025-06-01 05:57:02.363379 :9EC06665:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15128 pso_num=24 pso_serial#=252 2025-06-01 05:57:02.363513 :9EC06666:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15128 (legacy spawn) 2025-06-01 05:57:02.369978 :9EC06667: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-01 05:57:02.370160 :9EC0666E: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-01 05:57:02.370974 :9EC06671:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:05.549801 :9EC0676F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:05.537 2025-06-01 05:57:05.549803 :9EC06770:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14552 pso_num=24 pso_serial#=253 2025-06-01 05:57:05.549933 :9EC06771:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14552 (legacy spawn) 2025-06-01 05:57:05.556380 :9EC06772: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-01 05:57:05.556546 :9EC0677B: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-01 05:57:05.557342 :9EC0677C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:05.565943 :9EC0677F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:05.552 2025-06-01 05:57:05.565946 :9EC06780:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13548 pso_num=24 pso_serial#=254 2025-06-01 05:57:05.566085 :9EC06781:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13548 (legacy spawn) 2025-06-01 05:57:05.572552 :9EC067AB: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-01 05:57:05.572714 :9EC067B4: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-01 05:57:05.573516 :9EC067B5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:24.466585 :9EC06D70:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:24.458 2025-06-01 05:57:24.466586 :9EC06D71:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13452 pso_num=24 pso_serial#=255 2025-06-01 05:57:24.466725 :9EC06D72:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13452 (legacy spawn) 2025-06-01 05:57:24.473167 :9EC06D73: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-01 05:57:24.473339 :9EC06D79: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-01 05:57:24.474156 :9EC06D7D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:27.790874 :9EC06E74:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:27.785 2025-06-01 05:57:27.790876 :9EC06E75:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14320 pso_num=24 pso_serial#=1 2025-06-01 05:57:27.791019 :9EC06E76:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14320 osp_idx=22 osp_ver=348095 osp_pg=0 (spawn #240256) 2025-06-01 05:57:27.791020 :9EC06E77: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-01 05:57:27.791020 :9EC06E78:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=0 time=1733206438 2025-06-01 05:57:27.791020 :9EC06E79:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 05:57:27.791021 :9EC06E7A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 05:57:27.791021 :9EC06E7B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 05:57:27.791021 :9EC06E7C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 05:57:27.791022 :9EC06E7D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 05:57:27.791522 :9EC06E7E: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-01 05:57:27.792163 :9EC06E93: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-01 05:57:27.799710 :9EC06EAF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-01 05:57:27.800076 :9EC06EB0: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-01 05:57:27.800112 :9EC06EB3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-01 05:57:27.800954 :9EC06EB9: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-01 05:57:27.801066 :9EC06EC2: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-01 05:57:57.878724 :9EC07803:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:58.487836 :9EC07812:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:58.474 2025-06-01 05:57:58.487838 :9EC07813:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13720 pso_num=24 pso_serial#=2 2025-06-01 05:57:58.487982 :9EC07814:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13720 (legacy spawn) 2025-06-01 05:57:58.507314 :9EC07815: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-01 05:57:58.507501 :9EC0781A: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-01 05:57:58.508444 :9EC0781F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:59.939079 :9EC078CB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:59.925 2025-06-01 05:57:59.939081 :9EC078CC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10960 pso_num=24 pso_serial#=3 2025-06-01 05:57:59.939240 :9EC078CD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10960 (legacy spawn) 2025-06-01 05:57:59.945627 :9EC078CE: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-01 05:57:59.945801 :9EC078D7: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-01 05:57:59.946609 :9EC078D8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:57:59.965912 :9EC078DB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:57:59.957 2025-06-01 05:57:59.965914 :9EC078DC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12876 pso_num=24 pso_serial#=4 2025-06-01 05:57:59.966053 :9EC078DD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12876 (legacy spawn) 2025-06-01 05:57:59.972428 :9EC078DE: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-01 05:57:59.972596 :9EC078E7: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-01 05:57:59.973501 :9EC078E8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:58:01.137487 :9EC07915:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:58:01.128 2025-06-01 05:58:01.137489 :9EC07916:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14172 pso_num=24 pso_serial#=5 2025-06-01 05:58:01.137635 :9EC07917:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14172 (legacy spawn) 2025-06-01 05:58:01.144322 :9EC07918: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-01 05:58:01.144517 :9EC0791F: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-01 05:58:01.145515 :9EC07922:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:58:14.937666 :9EC07D79:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:58:14.923 2025-06-01 05:58:14.937669 :9EC07D7A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14452 pso_num=24 pso_serial#=6 2025-06-01 05:58:14.937807 :9EC07D7B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14452 (legacy spawn) 2025-06-01 05:58:14.944214 :9EC07D7C: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-01 05:58:14.944383 :9EC07D81: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-01 05:58:14.945173 :9EC07D86:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:58:14.963256 :9EC07D89:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:58:14.954 2025-06-01 05:58:14.963258 :9EC07D8A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11368 pso_num=24 pso_serial#=7 2025-06-01 05:58:14.963395 :9EC07D8B:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11368 (legacy spawn) 2025-06-01 05:58:14.969818 :9EC07D8C: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-01 05:58:14.969980 :9EC07D95: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-01 05:58:14.970802 :9EC07D96:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:58:18.138145 :9EC07E98:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:58:18.125 2025-06-01 05:58:18.138147 :9EC07E99:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15100 pso_num=24 pso_serial#=8 2025-06-01 05:58:18.138291 :9EC07E9A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15100 (legacy spawn) 2025-06-01 05:58:18.144713 :9EC07E9B: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-01 05:58:18.144916 :9EC07EA1: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-01 05:58:18.145734 :9EC07EA5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:58:18.154406 :9EC07EA8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:58:18.141 2025-06-01 05:58:18.154408 :9EC07EA9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10512 pso_num=24 pso_serial#=9 2025-06-01 05:58:18.154546 :9EC07EAA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10512 (legacy spawn) 2025-06-01 05:58:18.161104 :9EC07EAB: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-01 05:58:18.161265 :9EC07EB4: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-01 05:58:18.162049 :9EC07EB5:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:58:27.874555 :9EC081A2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:58:27.869 2025-06-01 05:58:27.874557 :9EC081A3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13572 pso_num=24 pso_serial#=10 2025-06-01 05:58:27.874696 :9EC081A4:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13572 osp_idx=22 osp_ver=348104 osp_pg=0 (spawn #240258) 2025-06-01 05:58:27.874697 :9EC081A5: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-01 05:58:27.874697 :9EC081A6:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=1 time=1733266516 2025-06-01 05:58:27.874698 :9EC081A7:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 05:58:27.874698 :9EC081A8:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 05:58:27.874699 :9EC081A9:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 05:58:27.874699 :9EC081AA:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 05:58:27.874699 :9EC081AB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 05:58:27.875204 :9EC081AC: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-01 05:58:27.875854 :9EC081C1: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-01 05:58:27.886783 :9EC081DD:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-01 05:58:27.887240 :9EC081DE:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-01 05:58:27.889580 :9EC081DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-01 05:58:27.889929 :9EC081E0: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-01 05:58:27.889964 :9EC081E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-01 05:58:27.890921 :9EC081E9: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-01 05:58:27.891055 :9EC081F2: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-01 05:58:57.964248 :9EC08B78:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:59:03.319821 :9EC08D2A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:59:03.312 2025-06-01 05:59:03.319823 :9EC08D2B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14744 pso_num=24 pso_serial#=11 2025-06-01 05:59:03.319958 :9EC08D2C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14744 (legacy spawn) 2025-06-01 05:59:03.326425 :9EC08D2D: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-01 05:59:03.326602 :9EC08D32: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-01 05:59:03.327439 :9EC08D37:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:59:07.230472 :9EC08E3A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:59:07.219 2025-06-01 05:59:07.230474 :9EC08E3B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14760 pso_num=24 pso_serial#=12 2025-06-01 05:59:07.230659 :9EC08E3C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14760 (legacy spawn) 2025-06-01 05:59:07.237079 :9EC08E3D: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-01 05:59:07.237252 :9EC08E46: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-01 05:59:07.238094 :9EC08E47:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:59:27.485292 :9EC09478:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:59:27.477 2025-06-01 05:59:27.485294 :9EC09479:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14052 pso_num=24 pso_serial#=13 2025-06-01 05:59:27.485436 :9EC0947A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14052 (legacy spawn) 2025-06-01 05:59:27.491895 :9EC0947B: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-01 05:59:27.492064 :9EC09484: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-01 05:59:27.492903 :9EC09485:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:59:27.501622 :9EC09488:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:59:27.493 2025-06-01 05:59:27.501624 :9EC09489:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14588 pso_num=24 pso_serial#=14 2025-06-01 05:59:27.501758 :9EC0948A:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14588 (legacy spawn) 2025-06-01 05:59:27.508225 :9EC0948B: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-01 05:59:27.508394 :9EC09494: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-01 05:59:27.509196 :9EC09495:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 05:59:27.966751 :9EC094BC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 05:59:27.961 2025-06-01 05:59:27.966753 :9EC094BD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13688 pso_num=24 pso_serial#=15 2025-06-01 05:59:27.966887 :9EC094BE:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13688 osp_idx=22 osp_ver=348109 osp_pg=0 (spawn #240260) 2025-06-01 05:59:27.966888 :9EC094BF: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-01 05:59:27.966888 :9EC094C0:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=2 time=1733326610 2025-06-01 05:59:27.966888 :9EC094C1:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 05:59:27.966889 :9EC094C2:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 05:59:27.966889 :9EC094C3:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 05:59:27.966889 :9EC094C4:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 05:59:27.966890 :9EC094C5:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 05:59:27.967378 :9EC094C6: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-01 05:59:27.968011 :9EC094DB: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-01 05:59:27.975609 :9EC094F7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-01 05:59:27.975971 :9EC094F8: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-01 05:59:27.976006 :9EC094FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-01 05:59:27.976857 :9EC09501: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-01 05:59:27.976975 :9EC0950A: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-01 05:59:58.079249 :9EC09EB9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-01 06:00:00.034145 :9EC09F92:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-01 06:00:00.027 2025-06-01 06:00:00.034147 :9EC09F93:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11776 pso_num=24 pso_serial#=16 2025-06-01 06:00:00.034256 :9EC09F94:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=11776 osp_idx=22 osp_ver=348110 osp_pg=0 (spawn #240262) 2025-06-01 06:00:00.034256 :9EC09F95: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-01 06:00:00.034257 :9EC09F96:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=3 time=1733358672 2025-06-01 06:00:00.034257 :9EC09F97:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-01 06:00:00.034258 :9EC09F98:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-01 06:00:00.034258 :9EC09F99:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-01 06:00:00.034259 :9EC09F9A:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-01 06:00:00.034259 :9EC09F9B:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-01 06:00:00.034335 :9EC09F9C: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-01 06:00:00.034342 :9EC09F9D: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: 11776, VKRM)