Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823100031\orcl_vkrm_21416_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:35293M/63366M, Ph+PgF:40464M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 21416, image: ORACLE.EXE (VKRM) *** 2025-08-23 10:00:32.055 *** SESSION ID:(177.45095) 2025-08-23 10:00:32.055 *** 2025-08-23 10:00:32.055 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=21416, pid: 22, proc_ser: 76, sid: 177, sess_ser: 45095 ------------------------------------------------------------------------------- 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=240 min 32 sec, exc=240 min 32 sec, total=240 min 32 sec wait times: max=infinite, heur=240 min 32 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 45095 --------------------------------------------------- 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, 09:58:32 - 10:00:32] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 10:00:32.055 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 21416, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 05:57:14.323620 :C3727B66:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=40 time=314336330 2025-08-23 05:57:14.323620 :C3727B67:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 05:57:14.323621 :C3727B68:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 05:57:14.323621 :C3727B69:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 05:57:14.323621 :C3727B6A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 05:57:14.323622 :C3727B6B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 05:57:14.324096 :C3727B6C: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-08-23 05:57:14.324804 :C3727B81: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-08-23 05:57:14.332225 :C3727B9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-23 05:57:14.332656 :C3727B9E: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-08-23 05:57:14.332693 :C3727BA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-23 05:57:14.333531 :C3727BA7: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-08-23 05:57:14.333668 :C3727BAF: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-08-23 05:57:29.334195 :C3728120: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-08-23 05:57:29.334217*:C3728123:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1755899849 eid=17019606 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-23 05:57:29.334217*:C3728124:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-08-23 05:57:29.334217*:C3728125:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-08-23 05:57:29.334217*:C3728126:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-08-23 05:57:29.334217*:C3728127:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-08-23 05:57:29.334217*:C3728128:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-08-23 05:57:29.334766 :C3728129: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-08-23 05:57:29.340163 :C3728131: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-08-23 05:57:29.341049 :C3728139: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-08-23 05:57:29.341179 :C3728142: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-08-23 05:57:59.445149 :C3728AC3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:01.291310 :C3728B84:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:01.283 2025-08-23 05:58:01.291312 :C3728B85:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21968 pso_num=22 pso_serial#=60 2025-08-23 05:58:01.291446 :C3728B86:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21968 (legacy spawn) 2025-08-23 05:58:01.297923 :C3728B87: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-08-23 05:58:01.298088 :C3728B8C: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-08-23 05:58:01.298901 :C3728B91:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:01.307560 :C3728B94:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:01.299 2025-08-23 05:58:01.307562 :C3728B95:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14932 pso_num=22 pso_serial#=61 2025-08-23 05:58:01.307704 :C3728B96:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14932 (legacy spawn) 2025-08-23 05:58:01.314088 :C3728B97: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-08-23 05:58:01.314257 :C3728BA0: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-08-23 05:58:01.315070 :C3728BA1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:04.379071 :C3728C88:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:04.366 2025-08-23 05:58:04.379073 :C3728C89:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22428 pso_num=22 pso_serial#=62 2025-08-23 05:58:04.379224 :C3728C8A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22428 (legacy spawn) 2025-08-23 05:58:04.385732 :C3728C8B: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-08-23 05:58:04.385897 :C3728C90: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-08-23 05:58:04.386750 :C3728C95:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:04.395463 :C3728C98:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:04.382 2025-08-23 05:58:04.395465 :C3728C99:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22404 pso_num=22 pso_serial#=63 2025-08-23 05:58:04.395603 :C3728C9A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22404 (legacy spawn) 2025-08-23 05:58:04.401997 :C3728C9B: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-08-23 05:58:04.402183 :C3728CA4: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-08-23 05:58:04.402989 :C3728CA5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:14.430689 :C3728F9C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:14.425 2025-08-23 05:58:14.430692 :C3728F9D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20768 pso_num=22 pso_serial#=64 2025-08-23 05:58:14.430825 :C3728F9E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20768 osp_idx=0 osp_ver=543541 osp_pg=0 (spawn #516915) 2025-08-23 05:58:14.430826 :C3728F9F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 05:58:14.430826 :C3728FA0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=314396440 2025-08-23 05:58:14.430827 :C3728FA1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 05:58:14.430827 :C3728FA2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 05:58:14.430827 :C3728FA3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 05:58:14.430827 :C3728FA4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 05:58:14.430828 :C3728FA5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 05:58:14.431315 :C3728FA6: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-08-23 05:58:14.432028 :C3728FBB: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-08-23 05:58:14.443025 :C3728FD7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-23 05:58:14.443524 :C3728FD8:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-23 05:58:14.445813 :C3728FD9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-23 05:58:14.446155 :C3728FDA: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-08-23 05:58:14.446191 :C3728FDD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-23 05:58:14.447047 :C3728FE3: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-08-23 05:58:14.447183 :C3728FEC: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-08-23 05:58:44.534334 :C372999D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:45.573012 :C3729A13:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:45.559 2025-08-23 05:58:45.573014 :C3729A14:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22408 pso_num=22 pso_serial#=65 2025-08-23 05:58:45.573152 :C3729A15:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22408 (legacy spawn) 2025-08-23 05:58:45.579666 :C3729A16: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-08-23 05:58:45.579835 :C3729A1F: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-08-23 05:58:45.580634 :C3729A20:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:58:53.022045 :C3729C72:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:58:53.013 2025-08-23 05:58:53.022047 :C3729C73:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15576 pso_num=22 pso_serial#=66 2025-08-23 05:58:53.022188 :C3729C74:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15576 (legacy spawn) 2025-08-23 05:58:53.028676 :C3729C75: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-08-23 05:58:53.028853 :C3729C7A: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-08-23 05:58:53.029635 :C3729C7F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:13.904877 :C372A32D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:13.890 2025-08-23 05:59:13.904879 :C372A32E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22524 pso_num=22 pso_serial#=67 2025-08-23 05:59:13.905010 :C372A32F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22524 (legacy spawn) 2025-08-23 05:59:13.911497 :C372A330: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-08-23 05:59:13.911668 :C372A335: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-08-23 05:59:13.912450 :C372A33A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:13.921135 :C372A33D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:13.906 2025-08-23 05:59:13.921137 :C372A33E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21156 pso_num=22 pso_serial#=68 2025-08-23 05:59:13.921274 :C372A33F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21156 (legacy spawn) 2025-08-23 05:59:13.928045 :C372A340: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-08-23 05:59:13.928208 :C372A349: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-08-23 05:59:13.929040 :C372A34A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:14.489984 :C372A37C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:14.484 2025-08-23 05:59:14.489986 :C372A37D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22128 pso_num=22 pso_serial#=69 2025-08-23 05:59:14.490117 :C372A37E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22128 osp_idx=0 osp_ver=543546 osp_pg=0 (spawn #516917) 2025-08-23 05:59:14.490118 :C372A37F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 05:59:14.490118 :C372A380:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=314456502 2025-08-23 05:59:14.490119 :C372A381:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 05:59:14.490119 :C372A382:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 05:59:14.490120 :C372A383:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 05:59:14.490120 :C372A384:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 05:59:14.490120 :C372A385:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 05:59:14.490683 :C372A386: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-08-23 05:59:14.491346 :C372A39B: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-08-23 05:59:14.498870 :C372A3B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-23 05:59:14.499232 :C372A3B8: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-08-23 05:59:14.499270 :C372A3BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-23 05:59:14.500173 :C372A3C1: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-08-23 05:59:14.500300 :C372A3CA: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-08-23 05:59:44.586784 :C372AD53:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:47.704168 :C372AE4F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:47.690 2025-08-23 05:59:47.704170 :C372AE50:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21480 pso_num=22 pso_serial#=70 2025-08-23 05:59:47.704316 :C372AE51:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21480 (legacy spawn) 2025-08-23 05:59:47.711209 :C372AE52: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-08-23 05:59:47.711401 :C372AE5B: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-08-23 05:59:47.712270 :C372AE5C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:50.285369 :C372AF33:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:50.279 2025-08-23 05:59:50.285371 :C372AF34:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19628 pso_num=22 pso_serial#=71 2025-08-23 05:59:50.285504 :C372AF35:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19628 (legacy spawn) 2025-08-23 05:59:50.291902 :C372AF36: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-08-23 05:59:50.292073 :C372AF3B: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-08-23 05:59:50.292873 :C372AF40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:50.301736 :C372AF43:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:50.294 2025-08-23 05:59:50.301738 :C372AF44:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19336 pso_num=22 pso_serial#=72 2025-08-23 05:59:50.301875 :C372AF45:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19336 (legacy spawn) 2025-08-23 05:59:50.308386 :C372AF46: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-08-23 05:59:50.308558 :C372AF4F: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-08-23 05:59:50.309358 :C372AF50:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:53.483839 :C372B03E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:53.471 2025-08-23 05:59:53.483841 :C372B03F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18716 pso_num=22 pso_serial#=73 2025-08-23 05:59:53.483981 :C372B040:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18716 (legacy spawn) 2025-08-23 05:59:53.490744 :C372B041: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-08-23 05:59:53.490924 :C372B046: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-08-23 05:59:53.491758 :C372B04B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:53.500720 :C372B04E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:53.486 2025-08-23 05:59:53.500722 :C372B04F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20612 pso_num=22 pso_serial#=74 2025-08-23 05:59:53.500865 :C372B050:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20612 (legacy spawn) 2025-08-23 05:59:53.507515 :C372B051: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-08-23 05:59:53.507682 :C372B056: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-08-23 05:59:53.508516 :C372B05A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 05:59:53.804650 :C372B0AA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 05:59:53.799 2025-08-23 05:59:53.804652 :C372B0AB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22492 pso_num=22 pso_serial#=75 2025-08-23 05:59:53.804792 :C372B0AC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22492 osp_idx=0 osp_ver=543552 osp_pg=0 (spawn #516919) 2025-08-23 05:59:53.804793 :C372B0AD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 05:59:53.804794 :C372B0AE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=43 time=314495815 2025-08-23 05:59:53.804794 :C372B0AF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 05:59:53.804794 :C372B0B0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 05:59:53.804795 :C372B0B1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 05:59:53.804795 :C372B0B2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 05:59:53.804796 :C372B0B3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 05:59:53.805321 :C372B0B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 05:59:53.805370 :C372B0BA:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 05:59:53.809542 :C372B0D3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-23 05:59:53.809584 :C372B0D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-23 05:59:53.809857 :C372B0D5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-23 05:59:53.809875 :C372B0D6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-23 05:59:53.810093 :C372B0D7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-23 05:59:53.810109 :C372B0D8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-23 05:59:53.810319 :C372B0D9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-23 05:59:53.810333 :C372B0DA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-23 05:59:53.810544 :C372B0DB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-23 05:59:53.810557 :C372B0DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-23 05:59:53.810761 :C372B0DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-08-23 05:59:53.810773 :C372B0DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-08-23 05:59:53.813095 :C372B0DF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-23 06:00:00.021660 :C372B2DF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-23 06:00:00.014 2025-08-23 06:00:00.021662 :C372B2E0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21416 pso_num=22 pso_serial#=76 2025-08-23 06:00:00.021773 :C372B2E1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21416 osp_idx=0 osp_ver=543553 osp_pg=0 (spawn #516920) 2025-08-23 06:00:00.021774 :C372B2E2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 06:00:00.021774 :C372B2E3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=44 time=314502033 2025-08-23 06:00:00.021775 :C372B2E4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 06:00:00.021775 :C372B2E5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 06:00:00.021775 :C372B2E6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 06:00:00.021775 :C372B2E7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 06:00:00.021776 :C372B2E8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 06:00:00.021851 :C372B2E9: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-08-23 06:00:00.021859 :C372B2EA: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: 21416, VKRM)