Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250906220445\orcl_vkrm_6024_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:34991M/63366M, Ph+PgF:39779M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 6024, image: ORACLE.EXE (VKRM) *** 2025-09-06 22:04:45.913 *** SESSION ID:(177.3075) 2025-09-06 22:04:45.913 *** 2025-09-06 22:04:45.913 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=6024, pid: 22, proc_ser: 180, sid: 177, sess_ser: 3075 ------------------------------------------------------------------------------- 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=964 min 45 sec, exc=964 min 45 sec, total=964 min 45 sec wait times: max=infinite, heur=964 min 45 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 3075 --------------------------------------------------- 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:02:45 - 22:04:45] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-06 22:04:45.913 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 6024, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-06 05:56:08.349698 :C9A404DA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:56:10.285596 :C9A40554:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:56:10.279 2025-09-06 05:56:10.285598 :C9A40555:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19952 pso_num=22 pso_serial#=163 2025-09-06 05:56:10.285787 :C9A40556:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19952 osp_idx=0 osp_ver=581380 osp_pg=0 (spawn #563589) 2025-09-06 05:56:10.285787 :C9A40557: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-06 05:56:10.285788 :C9A40558:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=183 time=1523821596 2025-09-06 05:56:10.285789 :C9A40559:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:56:10.285789 :C9A4055A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:56:10.285790 :C9A4055B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:56:10.285790 :C9A4055C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:56:10.285790 :C9A4055D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:56:10.286344 :C9A4055E: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-06 05:56:10.287067 :C9A40573: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-06 05:56:10.298440 :C9A4058F:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 05:56:10.298951 :C9A40590:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 05:56:10.301342 :C9A40591:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-06 05:56:10.301796 :C9A40592: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-06 05:56:10.301849 :C9A40595:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-06 05:56:10.302830 :C9A4059B: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-06 05:56:10.302974 :C9A405A4: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-06 05:56:40.356121 :C9A40EFE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:56:42.233763 :C9A40F96:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:56:42.224 2025-09-06 05:56:42.233765 :C9A40F97:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21576 pso_num=22 pso_serial#=164 2025-09-06 05:56:42.233908 :C9A40F98:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21576 (legacy spawn) 2025-09-06 05:56:42.240668 :C9A40F99: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-06 05:56:42.240837 :C9A40F9E: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-06 05:56:42.241684 :C9A40FA3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:56:50.256696 :C9A41256:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:56:50.248 2025-09-06 05:56:50.256698 :C9A41257:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18312 pso_num=22 pso_serial#=165 2025-09-06 05:56:50.256840 :C9A41258:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18312 (legacy spawn) 2025-09-06 05:56:50.263330 :C9A41259: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-06 05:56:50.263513 :C9A4125E: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-06 05:56:50.264352 :C9A41263:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:56:50.779219 :C9A41273:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:56:50.766 2025-09-06 05:56:50.779221 :C9A41274:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24148 pso_num=22 pso_serial#=166 2025-09-06 05:56:50.779355 :C9A41275:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24148 (legacy spawn) 2025-09-06 05:56:50.785825 :C9A41276: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-06 05:56:50.786002 :C9A4127F: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-06 05:56:50.797919 :C9A41280:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:56:50.806563 :C9A41283:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:56:50.797 2025-09-06 05:56:50.806565 :C9A41284:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22920 pso_num=22 pso_serial#=167 2025-09-06 05:56:50.806701 :C9A41285:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22920 (legacy spawn) 2025-09-06 05:56:50.813174 :C9A41286: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-06 05:56:50.813352 :C9A4128F: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-06 05:56:50.814193 :C9A41290:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:56:55.339325 :C9A413E8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:56:55.334 2025-09-06 05:56:55.339327 :C9A413E9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23560 pso_num=22 pso_serial#=168 2025-09-06 05:56:55.339457 :C9A413EA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23560 osp_idx=0 osp_ver=581385 osp_pg=0 (spawn #563591) 2025-09-06 05:56:55.339457 :C9A413EB: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-06 05:56:55.339458 :C9A413EC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=184 time=1523866643 2025-09-06 05:56:55.339458 :C9A413ED:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:56:55.339459 :C9A413EE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:56:55.339459 :C9A413EF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:56:55.339459 :C9A413F0:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:56:55.339460 :C9A413F1:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:56:55.340005 :C9A413F2: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-06 05:56:55.340648 :C9A41407: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-06 05:56:55.346052 :C9A41423: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-06 05:56:55.346993 :C9A4142B: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-06 05:56:55.347124 :C9A41434: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-06 05:57:10.373675 :C9A418FD: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-06 05:57:10.373699*:C9A41902:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x000000000FCCBB58 estart=1757109429 eid=17041819 ctx=0x000000077BD98A08 cwh=0x000000000FC802A0 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-09-06 05:57:10.373699*:C9A41903:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 pubTabIdxCnt=0 2025-09-06 05:57:10.373699*:C9A41904:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x000000000FCCBB58 2025-09-06 05:57:10.373699*:C9A41905:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x000000000FCCBB58 2025-09-06 05:57:10.373699*:C9A41906:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x000000000FCCBB58 curCtx=0x000000000FC8F300 xsc->flg4=65792 flags=2 2025-09-06 05:57:10.373699*:C9A41907:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x000000000FCCBB58 2025-09-06 05:57:10.374250 :C9A41908: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-06 05:57:10.381704 :C9A41910:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-06 05:57:10.382056 :C9A41911: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-06 05:57:10.382097 :C9A41914:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-06 05:57:10.382917 :C9A4191A: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-06 05:57:10.383045 :C9A41923: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-06 05:57:40.444926 :C9A4231C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:57:50.430359 :C9A42682:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:57:50.421 2025-09-06 05:57:50.430361 :C9A42683:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24284 pso_num=22 pso_serial#=169 2025-09-06 05:57:50.430541 :C9A42684:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24284 (legacy spawn) 2025-09-06 05:57:50.437024 :C9A42685: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-06 05:57:50.437219 :C9A4268A: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-06 05:57:50.438015 :C9A4268F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:57:52.445329 :C9A426EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:57:52.436 2025-09-06 05:57:52.445331 :C9A426ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14644 pso_num=22 pso_serial#=170 2025-09-06 05:57:52.445464 :C9A426EE:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14644 (legacy spawn) 2025-09-06 05:57:52.451983 :C9A426EF: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-06 05:57:52.452014 :C9A426F2: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-06 05:57:52.452164 :C9A426F5: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-06 05:57:52.453011 :C9A42706:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:10.451519 :C9A42C85:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:10.445 2025-09-06 05:58:10.451521 :C9A42C86:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18800 pso_num=22 pso_serial#=171 2025-09-06 05:58:10.451659 :C9A42C87:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18800 osp_idx=0 osp_ver=581388 osp_pg=0 (spawn #563593) 2025-09-06 05:58:10.451660 :C9A42C88: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-06 05:58:10.451660 :C9A42C89:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=185 time=1523941752 2025-09-06 05:58:10.451661 :C9A42C8A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:58:10.451661 :C9A42C8B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:58:10.451662 :C9A42C8C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:58:10.451662 :C9A42C8D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:58:10.451662 :C9A42C8E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:58:10.452148 :C9A42C8F: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-06 05:58:10.452875 :C9A42CA4: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-06 05:58:10.463925 :C9A42CC1:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 05:58:10.464374 :C9A42CC2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-06 05:58:10.466780 :C9A42CC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-06 05:58:10.467106 :C9A42CC4: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-06 05:58:10.467141 :C9A42CC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-06 05:58:10.468009 :C9A42CCD: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-06 05:58:10.468133 :C9A42CD6: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-06 05:58:40.544411 :C9A43676:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:48.514117 :C9A438F6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:48.499 2025-09-06 05:58:48.514119 :C9A438F7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24408 pso_num=22 pso_serial#=172 2025-09-06 05:58:48.514265 :C9A438F8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24408 (legacy spawn) 2025-09-06 05:58:48.520932 :C9A438F9: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-06 05:58:48.521126 :C9A43901: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-06 05:58:48.522036 :C9A43903:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:48.531086 :C9A43906:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:48.531 2025-09-06 05:58:48.531088 :C9A43907:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24260 pso_num=22 pso_serial#=173 2025-09-06 05:58:48.531236 :C9A43908:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24260 (legacy spawn) 2025-09-06 05:58:48.538136 :C9A43909: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-06 05:58:48.538320 :C9A43911: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-06 05:58:48.539300 :C9A43913:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:51.777871 :C9A43A3E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:51.764 2025-09-06 05:58:51.777873 :C9A43A3F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24388 pso_num=22 pso_serial#=174 2025-09-06 05:58:51.778007 :C9A43A40:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24388 (legacy spawn) 2025-09-06 05:58:51.797717 :C9A43A41: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-06 05:58:51.797900 :C9A43A4A: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-06 05:58:51.798781 :C9A43A4B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:51.807377 :C9A43A4E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:51.795 2025-09-06 05:58:51.807379 :C9A43A4F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23228 pso_num=22 pso_serial#=175 2025-09-06 05:58:51.807514 :C9A43A50:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23228 (legacy spawn) 2025-09-06 05:58:51.814036 :C9A43A51: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-06 05:58:51.814065 :C9A43A54: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-06 05:58:51.814219 :C9A43A5B: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-06 05:58:51.814998 :C9A43A68:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:54.611078 :C9A43B01:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:54.602 2025-09-06 05:58:54.611080 :C9A43B02:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13240 pso_num=22 pso_serial#=176 2025-09-06 05:58:54.611217 :C9A43B03:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13240 (legacy spawn) 2025-09-06 05:58:54.617843 :C9A43B04: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-06 05:58:54.618016 :C9A43B09: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-06 05:58:54.618873 :C9A43B0E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:58:59.734581 :C9A43CE3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:58:59.734 2025-09-06 05:58:59.734584 :C9A43CE4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22312 pso_num=22 pso_serial#=177 2025-09-06 05:58:59.734721 :C9A43CE5:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22312 (legacy spawn) 2025-09-06 05:58:59.741189 :C9A43CE6: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-06 05:58:59.741361 :C9A43CEB: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-06 05:58:59.742137 :C9A43CF0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:59:10.517731 :C9A44038:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:59:10.512 2025-09-06 05:59:10.517733 :C9A44039:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20600 pso_num=22 pso_serial#=178 2025-09-06 05:59:10.517868 :C9A4403A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=20600 osp_idx=0 osp_ver=581395 osp_pg=0 (spawn #563595) 2025-09-06 05:59:10.517869 :C9A4403B: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-06 05:59:10.517870 :C9A4403C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=186 time=1524001815 2025-09-06 05:59:10.517870 :C9A4403D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 05:59:10.517870 :C9A4403E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 05:59:10.517871 :C9A4403F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 05:59:10.517871 :C9A44040:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 05:59:10.517871 :C9A44041:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 05:59:10.518359 :C9A44042: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-06 05:59:10.519000 :C9A44057: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-06 05:59:10.526546 :C9A44073:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-06 05:59:10.526946 :C9A44074: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-06 05:59:10.526979 :C9A44077:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-06 05:59:10.527868 :C9A4407D: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-06 05:59:10.528003 :C9A44086: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-06 05:59:40.612130 :C9A449FD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 05:59:57.142954 :C9A44F35:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 05:59:57.139 2025-09-06 05:59:57.142956 :C9A44F36:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24424 pso_num=22 pso_serial#=179 2025-09-06 05:59:57.143099 :C9A44F37:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24424 (legacy spawn) 2025-09-06 05:59:57.150181 :C9A44F38: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-06 05:59:57.150358 :C9A44F3D: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-06 05:59:57.151306 :C9A44F42:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-06 06:00:00.021908 :C9A4503C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-06 06:00:00.015 2025-09-06 06:00:00.021910 :C9A4503D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6024 pso_num=22 pso_serial#=180 2025-09-06 06:00:00.022030 :C9A4503E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=6024 osp_idx=0 osp_ver=581397 osp_pg=0 (spawn #563597) 2025-09-06 06:00:00.022031 :C9A4503F: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-06 06:00:00.022032 :C9A45040:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=187 time=1524051330 2025-09-06 06:00:00.022032 :C9A45041:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-06 06:00:00.022033 :C9A45042:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-06 06:00:00.022033 :C9A45043:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-06 06:00:00.022034 :C9A45044:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-06 06:00:00.022034 :C9A45045:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-06 06:00:00.022129 :C9A45046: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-06 06:00:00.022138 :C9A45047: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: 6024, VKRM)