Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250915220005\orcl_vkrm_24424_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:34717M/63366M, Ph+PgF:39306M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 24424, image: ORACLE.EXE (VKRM) *** 2025-09-15 22:00:05.208 *** SESSION ID:(177.12945) 2025-09-15 22:00:05.208 *** 2025-09-15 22:00:05.208 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=24424, pid: 22, proc_ser: 77, sid: 177, sess_ser: 12945 ------------------------------------------------------------------------------- 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=5.189613 sec, exc=5.189613 sec, total=5.189613 sec wait times: max=infinite, heur=5.189613 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 12945 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 22:00:00 - 22:00:05] 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-15 22:00:05.208 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 24424, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-15 21:56:50.180153 :CDEAE380:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21588 pso_num=22 pso_serial#=57 2025-09-15 21:56:50.180296 :CDEAE381:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21588 osp_idx=0 osp_ver=613404 osp_pg=0 (spawn #595836) 2025-09-15 21:56:50.180298 :CDEAE382: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-15 21:56:50.180299 :CDEAE383:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=120 time=2359061487 2025-09-15 21:56:50.180299 :CDEAE384:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:56:50.180300 :CDEAE385:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:56:50.180300 :CDEAE386:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:56:50.180300 :CDEAE387:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:56:50.180301 :CDEAE388:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:56:50.180793 :CDEAE389: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-15 21:56:50.181458 :CDEAE39E: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-15 21:56:50.186836 :CDEAE3BA: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-15 21:56:50.187803 :CDEAE3C2: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-15 21:56:50.187929 :CDEAE3CB: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-15 21:57:05.194172 :CDEAE8D3: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-15 21:57:05.194193*:CDEAE8D6:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1757944625 eid=17057156 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-15 21:57:05.194193*:CDEAE8D7:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-09-15 21:57:05.194193*:CDEAE8D8:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-09-15 21:57:05.194193*:CDEAE8D9:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-09-15 21:57:05.194193*:CDEAE8DA:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-09-15 21:57:05.194193*:CDEAE8DB:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-09-15 21:57:05.194729 :CDEAE8DC: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-15 21:57:05.202116 :CDEAE8E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-15 21:57:05.202499 :CDEAE8E5: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-15 21:57:05.202536 :CDEAE8E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-15 21:57:05.203355 :CDEAE8EE: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-15 21:57:05.203474 :CDEAE8F7: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-15 21:57:35.294989 :CDEAF30E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:57:35.787902 :CDEAF34D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:57:35.774 2025-09-15 21:57:35.787903 :CDEAF34E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20600 pso_num=22 pso_serial#=58 2025-09-15 21:57:35.788042 :CDEAF34F:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20600 (legacy spawn) 2025-09-15 21:57:35.794519 :CDEAF350: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-15 21:57:35.794695 :CDEAF355: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-15 21:57:35.795486 :CDEAF35A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:57:35.813672 :CDEAF390:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:57:35.805 2025-09-15 21:57:35.813674 :CDEAF391:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21580 pso_num=22 pso_serial#=59 2025-09-15 21:57:35.813809 :CDEAF392:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21580 (legacy spawn) 2025-09-15 21:57:35.820358 :CDEAF393: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-15 21:57:35.820517 :CDEAF39C: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-15 21:57:35.821419 :CDEAF39D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:57:38.984595 :CDEAF489:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:57:38.971 2025-09-15 21:57:38.984596 :CDEAF48A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21328 pso_num=22 pso_serial#=60 2025-09-15 21:57:38.984727 :CDEAF48B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21328 (legacy spawn) 2025-09-15 21:57:38.991285 :CDEAF48C: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-15 21:57:38.991458 :CDEAF491: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-15 21:57:38.992251 :CDEAF496:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:57:39.000925 :CDEAF499:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:57:38.987 2025-09-15 21:57:39.000927 :CDEAF49A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23280 pso_num=22 pso_serial#=61 2025-09-15 21:57:39.001064 :CDEAF49B:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23280 (legacy spawn) 2025-09-15 21:57:39.007688 :CDEAF49C: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-15 21:57:39.007857 :CDEAF4A5: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-15 21:57:39.008671 :CDEAF4A6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:57:53.318919 :CDEAF8ED:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:57:53.309 2025-09-15 21:57:53.318922 :CDEAF8EE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24048 pso_num=22 pso_serial#=62 2025-09-15 21:57:53.319072 :CDEAF8EF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24048 (legacy spawn) 2025-09-15 21:57:53.325569 :CDEAF8F0: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-15 21:57:53.325748 :CDEAF8F5: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-15 21:57:53.326591 :CDEAF8FA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:02.963158 :CDEAFC33:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:02.950 2025-09-15 21:58:02.963160 :CDEAFC34:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24100 pso_num=22 pso_serial#=63 2025-09-15 21:58:02.963298 :CDEAFC35:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24100 (legacy spawn) 2025-09-15 21:58:02.969879 :CDEAFC36: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-15 21:58:02.970055 :CDEAFC3B: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-15 21:58:02.970906 :CDEAFC40:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:04.880655 :CDEAFCCB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:04.872 2025-09-15 21:58:04.880657 :CDEAFCCC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24780 pso_num=22 pso_serial#=64 2025-09-15 21:58:04.880792 :CDEAFCCD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24780 (legacy spawn) 2025-09-15 21:58:04.887339 :CDEAFCCE: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-15 21:58:04.887505 :CDEAFCD3: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-15 21:58:04.888378 :CDEAFCD8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:05.299851 :CDEAFCFE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:05.294 2025-09-15 21:58:05.299853 :CDEAFCFF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22828 pso_num=22 pso_serial#=65 2025-09-15 21:58:05.299988 :CDEAFD00:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22828 osp_idx=0 osp_ver=613412 osp_pg=0 (spawn #595838) 2025-09-15 21:58:05.299989 :CDEAFD01: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-15 21:58:05.299990 :CDEAFD02:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=121 time=2359136596 2025-09-15 21:58:05.299990 :CDEAFD03:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:58:05.299990 :CDEAFD04:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:58:05.299990 :CDEAFD05:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:58:05.299991 :CDEAFD06:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:58:05.299991 :CDEAFD07:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:58:05.300525 :CDEAFD08: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-15 21:58:05.301181 :CDEAFD1D: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-15 21:58:05.312240 :CDEAFD39:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-15 21:58:05.312786 :CDEAFD3A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-15 21:58:05.315175 :CDEAFD3B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-15 21:58:05.315525 :CDEAFD3C: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-15 21:58:05.315561 :CDEAFD3F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-15 21:58:05.316491 :CDEAFD45: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-15 21:58:05.316618 :CDEAFD4E: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-15 21:58:35.383611 :CDEB06FD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:35.971925 :CDEB076A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:35.963 2025-09-15 21:58:35.971927 :CDEB076B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6364 pso_num=22 pso_serial#=66 2025-09-15 21:58:35.972070 :CDEB076C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6364 (legacy spawn) 2025-09-15 21:58:35.978608 :CDEB076D: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-15 21:58:35.978779 :CDEB0772: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-15 21:58:35.979638 :CDEB0777:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:48.445667 :CDEB0B58:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:48.431 2025-09-15 21:58:48.445669 :CDEB0B59:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23100 pso_num=22 pso_serial#=67 2025-09-15 21:58:48.445811 :CDEB0B5A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23100 (legacy spawn) 2025-09-15 21:58:48.452217 :CDEB0B5B: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-15 21:58:48.452392 :CDEB0B60: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-15 21:58:48.453235 :CDEB0B65:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:48.470802 :CDEB0B68:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:48.462 2025-09-15 21:58:48.470804 :CDEB0B69:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25128 pso_num=22 pso_serial#=68 2025-09-15 21:58:48.470940 :CDEB0B6A:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25128 (legacy spawn) 2025-09-15 21:58:48.477412 :CDEB0B6B: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-15 21:58:48.477571 :CDEB0B74: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-15 21:58:48.478412 :CDEB0B75:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:51.792950 :CDEB0C7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:51.779 2025-09-15 21:58:51.792952 :CDEB0C80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21444 pso_num=22 pso_serial#=69 2025-09-15 21:58:51.793091 :CDEB0C81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21444 (legacy spawn) 2025-09-15 21:58:51.799605 :CDEB0C82: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-15 21:58:51.799776 :CDEB0C8B: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-15 21:58:51.800615 :CDEB0C8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:58:51.809300 :CDEB0C8F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:58:51.795 2025-09-15 21:58:51.809302 :CDEB0C90:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25448 pso_num=22 pso_serial#=70 2025-09-15 21:58:51.809445 :CDEB0C91:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25448 (legacy spawn) 2025-09-15 21:58:51.816018 :CDEB0C92: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-15 21:58:51.816190 :CDEB0C9B: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-15 21:58:51.817034 :CDEB0C9C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:59:01.358477 :CDEB0FAF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:59:01.344 2025-09-15 21:59:01.358479 :CDEB0FB0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23700 pso_num=22 pso_serial#=71 2025-09-15 21:59:01.358615 :CDEB0FB1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23700 (legacy spawn) 2025-09-15 21:59:01.365181 :CDEB0FB2: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-15 21:59:01.365360 :CDEB0FBB: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-15 21:59:01.366162 :CDEB0FBC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:59:01.717023 :CDEB0FBF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:59:01.703 2025-09-15 21:59:01.717026 :CDEB0FC0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20404 pso_num=22 pso_serial#=72 2025-09-15 21:59:01.717167 :CDEB0FC1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20404 (legacy spawn) 2025-09-15 21:59:01.723577 :CDEB0FC2: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-15 21:59:01.723771 :CDEB0FC7: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-15 21:59:01.724555 :CDEB0FCC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:59:01.733305 :CDEB0FCF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:59:01.719 2025-09-15 21:59:01.733307 :CDEB0FD0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25324 pso_num=22 pso_serial#=73 2025-09-15 21:59:01.733443 :CDEB0FD1:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25324 (legacy spawn) 2025-09-15 21:59:01.740027 :CDEB0FD2: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-15 21:59:01.740194 :CDEB0FDB: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-15 21:59:01.741006 :CDEB0FDC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:59:05.406413 :CDEB1104:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:59:05.401 2025-09-15 21:59:05.406414 :CDEB1105:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19552 pso_num=22 pso_serial#=74 2025-09-15 21:59:05.406552 :CDEB1106:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19552 osp_idx=0 osp_ver=613421 osp_pg=0 (spawn #595840) 2025-09-15 21:59:05.406553 :CDEB1107: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-15 21:59:05.406554 :CDEB1108:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=122 time=2359196705 2025-09-15 21:59:05.406554 :CDEB1109:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 21:59:05.406555 :CDEB110A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 21:59:05.406555 :CDEB110B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 21:59:05.406555 :CDEB110C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 21:59:05.406556 :CDEB110D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 21:59:05.407046 :CDEB110E: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-15 21:59:05.407693 :CDEB1123: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-15 21:59:05.415316 :CDEB113F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-15 21:59:05.415691 :CDEB1140: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-15 21:59:05.415726 :CDEB1143:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-15 21:59:05.416648 :CDEB1149: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-15 21:59:05.416768 :CDEB1152: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-15 21:59:35.521811 :CDEB1B08:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:59:35.596936 :CDEB1B0C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:59:35.584 2025-09-15 21:59:35.596938 :CDEB1B0D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24816 pso_num=22 pso_serial#=75 2025-09-15 21:59:35.597078 :CDEB1B0E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24816 (legacy spawn) 2025-09-15 21:59:35.603584 :CDEB1B0F: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-15 21:59:35.603755 :CDEB1B14: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-15 21:59:35.604570 :CDEB1B19:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 21:59:38.186987 :CDEB1BFE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 21:59:38.178 2025-09-15 21:59:38.186990 :CDEB1BFF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23652 pso_num=22 pso_serial#=76 2025-09-15 21:59:38.187173 :CDEB1C00:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23652 (legacy spawn) 2025-09-15 21:59:38.194426 :CDEB1C01: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-15 21:59:38.194615 :CDEB1C06: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-15 21:59:38.195550 :CDEB1C0B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-15 22:00:00.023632 :CDEB22E0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-15 22:00:00.016 2025-09-15 22:00:00.023634 :CDEB22E1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24424 pso_num=22 pso_serial#=77 2025-09-15 22:00:00.023741 :CDEB22E2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24424 osp_idx=0 osp_ver=613424 osp_pg=0 (spawn #595842) 2025-09-15 22:00:00.023741 :CDEB22E3: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-15 22:00:00.023742 :CDEB22E4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=123 time=2359251330 2025-09-15 22:00:00.023742 :CDEB22E5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-15 22:00:00.023742 :CDEB22E6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-15 22:00:00.023743 :CDEB22E7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-15 22:00:00.023743 :CDEB22E8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-15 22:00:00.023743 :CDEB22E9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-15 22:00:00.023812 :CDEB22EA: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-15 22:00:00.023819 :CDEB22EB: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: 24424, VKRM)