Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250916220006\orcl_vkrm_24716_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:35981M/63366M, Ph+PgF:40300M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 24716, image: ORACLE.EXE (VKRM) *** 2025-09-16 22:00:06.706 *** SESSION ID:(177.20159) 2025-09-16 22:00:06.706 *** 2025-09-16 22:00:06.706 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=24716, pid: 22, proc_ser: 84, sid: 177, sess_ser: 20159 ------------------------------------------------------------------------------- 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=6.528618 sec, exc=6.528618 sec, total=6.528618 sec wait times: max=infinite, heur=6.528618 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 20159 --------------------------------------------------- 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 --------------------------------------------------- [7 samples, 22:00:00 - 22:00:06] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-16 22:00:06.706 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 24716, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-16 21:56:16.808980 :CE5D3183:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:56:16.809007 :CE5D3184:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:56:16.809041 :CE5D3185:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:56:16.809320 :CE5D3186: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-09-16 21:56:16.809358 :CE5D3189:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:56:16.822018 :CE5D318F: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-09-16 21:56:16.822095 :CE5D3192:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-09-16 21:56:16.822130 :CE5D3193:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-09-16 21:56:16.823975 :CE5D3199:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:56:16.824020 :CE5D319A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:56:16.824329 :CE5D319B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:56:16.824567 :CE5D319C: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-09-16 21:56:16.824606 :CE5D319F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:56:16.829478 :CE5D31A5: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-09-16 21:56:16.829548 :CE5D31A8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-09-16 21:56:16.829590 :CE5D31A9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-09-16 21:56:16.829951 :CE5D31AF:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-16 21:56:16.830332 :CE5D31B0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-16 21:56:16.830461 :CE5D31B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:56:16.830731 :CE5D31B2: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-09-16 21:56:16.830761 :CE5D31B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:56:16.832470 :CE5D31BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:56:16.833314 :CE5D31BC: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-09-16 21:56:16.833345 :CE5D31BF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:56:16.834392 :CE5D31C5: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-09-16 21:56:16.834525 :CE5D31CD: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-09-16 21:56:46.922977 :CE5D3BD0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:00.575091 :CE5D3FC1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:00.566 2025-09-16 21:57:00.575093 :CE5D3FC2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24684 pso_num=22 pso_serial#=67 2025-09-16 21:57:00.575232 :CE5D3FC3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24684 (legacy spawn) 2025-09-16 21:57:00.581995 :CE5D3FC4: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-09-16 21:57:00.582165 :CE5D3FC9: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-09-16 21:57:00.582981 :CE5D3FCE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:06.129340 :CE5D41B4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:06.119 2025-09-16 21:57:06.129342 :CE5D41B5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24660 pso_num=22 pso_serial#=68 2025-09-16 21:57:06.129479 :CE5D41B6:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24660 (legacy spawn) 2025-09-16 21:57:06.136161 :CE5D41B7: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-09-16 21:57:06.136327 :CE5D41BC: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-09-16 21:57:06.137107 :CE5D41C1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:10.103868 :CE5D4300:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:10.089 2025-09-16 21:57:10.103870 :CE5D4301:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24596 pso_num=22 pso_serial#=69 2025-09-16 21:57:10.104006 :CE5D4302:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24596 (legacy spawn) 2025-09-16 21:57:10.110735 :CE5D4303: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-09-16 21:57:10.110901 :CE5D4308: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-09-16 21:57:10.111715 :CE5D430D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:10.120613 :CE5D4310:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:10.120 2025-09-16 21:57:10.120616 :CE5D4311:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22080 pso_num=22 pso_serial#=70 2025-09-16 21:57:10.120758 :CE5D4312:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22080 (legacy spawn) 2025-09-16 21:57:10.127410 :CE5D4313: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-09-16 21:57:10.127574 :CE5D431C: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-09-16 21:57:10.128379 :CE5D431D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:13.291219 :CE5D442F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:13.278 2025-09-16 21:57:13.291222 :CE5D4430:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25268 pso_num=22 pso_serial#=71 2025-09-16 21:57:13.291368 :CE5D4431:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25268 (legacy spawn) 2025-09-16 21:57:13.298017 :CE5D4432: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-09-16 21:57:13.298191 :CE5D443A: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-09-16 21:57:13.298995 :CE5D443C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:13.307924 :CE5D443F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:13.293 2025-09-16 21:57:13.307926 :CE5D4440:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23640 pso_num=22 pso_serial#=72 2025-09-16 21:57:13.308064 :CE5D4441:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23640 (legacy spawn) 2025-09-16 21:57:13.314786 :CE5D4452: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-09-16 21:57:13.314950 :CE5D445B: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-09-16 21:57:13.315744 :CE5D445C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:16.802361 :CE5D45AB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:16.796 2025-09-16 21:57:16.802363 :CE5D45AC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23868 pso_num=22 pso_serial#=73 2025-09-16 21:57:16.802507 :CE5D45AD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23868 osp_idx=0 osp_ver=616480 osp_pg=0 (spawn #599175) 2025-09-16 21:57:16.802508 :CE5D45AE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 21:57:16.802508 :CE5D45AF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=222 time=2445488112 2025-09-16 21:57:16.802509 :CE5D45B0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 21:57:16.802509 :CE5D45B1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 21:57:16.802509 :CE5D45B2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 21:57:16.802510 :CE5D45B3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 21:57:16.802510 :CE5D45B4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 21:57:16.803088 :CE5D45B5: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-09-16 21:57:16.803767 :CE5D45CA: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-09-16 21:57:16.811837 :CE5D45E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:57:16.812246 :CE5D45E8: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-09-16 21:57:16.812295 :CE5D45EB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:57:16.813259 :CE5D45F1: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-09-16 21:57:16.813380 :CE5D45FA: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-09-16 21:57:46.942850 :CE5D4FD4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:50.082595 :CE5D50BF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:50.070 2025-09-16 21:57:50.082597 :CE5D50C0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21304 pso_num=22 pso_serial#=74 2025-09-16 21:57:50.082740 :CE5D50C1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21304 (legacy spawn) 2025-09-16 21:57:50.089574 :CE5D50C2: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-09-16 21:57:50.089762 :CE5D50C7: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-09-16 21:57:50.090613 :CE5D50CC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:57:50.099986 :CE5D50CF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:57:50.086 2025-09-16 21:57:50.099988 :CE5D50D0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13400 pso_num=22 pso_serial#=75 2025-09-16 21:57:50.100201 :CE5D50D1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13400 (legacy spawn) 2025-09-16 21:57:50.107164 :CE5D50D2: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-09-16 21:57:50.107337 :CE5D50DB: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-09-16 21:57:50.108219 :CE5D50DC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:58:03.124042 :CE5D54D1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:58:03.116 2025-09-16 21:58:03.124044 :CE5D54D2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22932 pso_num=22 pso_serial#=76 2025-09-16 21:58:03.124179 :CE5D54D3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22932 (legacy spawn) 2025-09-16 21:58:03.130995 :CE5D54D4: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-09-16 21:58:03.131167 :CE5D54D9: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-09-16 21:58:03.132054 :CE5D54DE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:58:14.426503 :CE5D5882:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:58:14.416 2025-09-16 21:58:14.426505 :CE5D5883:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18680 pso_num=22 pso_serial#=77 2025-09-16 21:58:14.426654 :CE5D5884:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18680 (legacy spawn) 2025-09-16 21:58:14.433427 :CE5D5885: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-09-16 21:58:14.433603 :CE5D588E: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-09-16 21:58:14.434427 :CE5D588F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:58:16.907340 :CE5D5979:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:58:16.901 2025-09-16 21:58:16.907342 :CE5D597A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20532 pso_num=22 pso_serial#=78 2025-09-16 21:58:16.907480 :CE5D597B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20532 osp_idx=0 osp_ver=616485 osp_pg=0 (spawn #599177) 2025-09-16 21:58:16.907481 :CE5D597C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 21:58:16.907482 :CE5D597D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=223 time=2445548205 2025-09-16 21:58:16.907482 :CE5D597E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 21:58:16.907482 :CE5D597F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 21:58:16.907483 :CE5D5980:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 21:58:16.907483 :CE5D5981:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 21:58:16.907484 :CE5D5982:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 21:58:16.908094 :CE5D5983: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-09-16 21:58:16.908802 :CE5D5998: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-09-16 21:58:16.920329 :CE5D59BF:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-16 21:58:16.920804 :CE5D59C0:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-16 21:58:16.923400 :CE5D59C1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-16 21:58:16.923807 :CE5D59C2: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-09-16 21:58:16.923845 :CE5D59C5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-16 21:58:16.924803 :CE5D59CB: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-09-16 21:58:16.924939 :CE5D59D4: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-09-16 21:58:46.989373 :CE5D63A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:58:48.441581 :CE5D63E8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:58:48.427 2025-09-16 21:58:48.441583 :CE5D63E9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25240 pso_num=22 pso_serial#=79 2025-09-16 21:58:48.441724 :CE5D63EA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25240 (legacy spawn) 2025-09-16 21:58:48.448449 :CE5D63EB: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-09-16 21:58:48.448623 :CE5D63F0: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-09-16 21:58:48.449409 :CE5D63F5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:58:59.593114 :CE5D6782:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:58:59.578 2025-09-16 21:58:59.593116 :CE5D6783:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24268 pso_num=22 pso_serial#=80 2025-09-16 21:58:59.593263 :CE5D6784:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24268 (legacy spawn) 2025-09-16 21:58:59.599924 :CE5D6785: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-09-16 21:58:59.600102 :CE5D678E: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-09-16 21:58:59.600913 :CE5D678F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:58:59.609779 :CE5D6792:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:58:59.609 2025-09-16 21:58:59.609781 :CE5D6793:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24856 pso_num=22 pso_serial#=81 2025-09-16 21:58:59.609922 :CE5D6794:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24856 (legacy spawn) 2025-09-16 21:58:59.616608 :CE5D6795: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-09-16 21:58:59.616784 :CE5D679D: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-09-16 21:58:59.617575 :CE5D679F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:59:01.959985 :CE5D688B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:59:01.954 2025-09-16 21:59:01.959987 :CE5D688C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21428 pso_num=22 pso_serial#=82 2025-09-16 21:59:01.960132 :CE5D688D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21428 osp_idx=0 osp_ver=616489 osp_pg=0 (spawn #599179) 2025-09-16 21:59:01.960133 :CE5D688E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 21:59:01.960134 :CE5D688F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=224 time=2445593268 2025-09-16 21:59:01.960134 :CE5D6890:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 21:59:01.960135 :CE5D6891:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 21:59:01.960135 :CE5D6892:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 21:59:01.960135 :CE5D6893:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 21:59:01.960135 :CE5D6894:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 21:59:01.960704 :CE5D6895: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-09-16 21:59:01.961456 :CE5D68AA: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-09-16 21:59:01.966973 :CE5D68C7: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-09-16 21:59:01.968190 :CE5D68CF: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-09-16 21:59:01.968315 :CE5D68D8: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-09-16 21:59:16.970956 :CE5D6DD2: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-09-16 21:59:47.057077 :CE5D783C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 21:59:56.638401 :CE5D7B25:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 21:59:56.628 2025-09-16 21:59:56.638402 :CE5D7B26:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25480 pso_num=22 pso_serial#=83 2025-09-16 21:59:56.638551 :CE5D7B27:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25480 (legacy spawn) 2025-09-16 21:59:56.645416 :CE5D7B28: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-09-16 21:59:56.645585 :CE5D7B2D: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-09-16 21:59:56.646390 :CE5D7B32:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-16 22:00:00.075907 :CE5D7C62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-16 22:00:00.065 2025-09-16 22:00:00.075910 :CE5D7C63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24716 pso_num=22 pso_serial#=84 2025-09-16 22:00:00.076031 :CE5D7C64:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24716 osp_idx=0 osp_ver=616491 osp_pg=0 (spawn #599181) 2025-09-16 22:00:00.076032 :CE5D7C65:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-16 22:00:00.076032 :CE5D7C66:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=225 time=2445651377 2025-09-16 22:00:00.076032 :CE5D7C67:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-16 22:00:00.076032 :CE5D7C68:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-16 22:00:00.076033 :CE5D7C69:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-16 22:00:00.076033 :CE5D7C6A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-16 22:00:00.076033 :CE5D7C6B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-16 22:00:00.076120 :CE5D7C6C: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-09-16 22:00:00.076127 :CE5D7C6D: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: 24716, VKRM)