Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607180537\orcl_vkrm_15968_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:36827M/63366M, Ph+PgF:43479M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 15968, image: ORACLE.EXE (VKRM) *** 2025-06-07 18:05:37.396 *** SESSION ID:(193.3107) 2025-06-07 18:05:37.396 *** 2025-06-07 18:05:37.396 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=15968, pid: 24, proc_ser: 175, sid: 193, sess_ser: 3107 ------------------------------------------------------------------------------- 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=725 min 37 sec, exc=725 min 37 sec, total=725 min 37 sec wait times: max=infinite, heur=725 min 37 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000000 sec since current wait Sampled Session History of session 193 serial 3107 --------------------------------------------------- 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, 18:03:37 - 18:05:37] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 18:05:37.396 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 15968, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 05:56:17.767183 :A16344BB:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 05:56:17.767678 :A16344BC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 05:56:17.768313 :A16344D1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:56:17.779298 :A1634513:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 05:56:17.779761 :A1634514:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 05:56:17.782115 :A1634515:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-07 05:56:17.782444 :A1634516:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:56:17.782480 :A1634519:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-07 05:56:17.783346 :A163451F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:56:17.783472 :A1634528:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:56:47.843270 :A1634F05:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:56:59.842773 :A163529C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:56:59.831 2025-06-07 05:56:59.842775 :A163529D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15776 pso_num=24 pso_serial#=155 2025-06-07 05:56:59.842907 :A163529E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15776 (legacy spawn) 2025-06-07 05:56:59.849317 :A16352C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:56:59.849488 :A16352CD:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:56:59.850295 :A16352D2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:00.027413 :A16352F8:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:00.019 2025-06-07 05:57:00.027415 :A16352F9:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11644 pso_num=24 pso_serial#=156 2025-06-07 05:57:00.027560 :A16352FA:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11644 (legacy spawn) 2025-06-07 05:57:00.034072 :A16352FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:00.034244 :A1635304:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:00.035101 :A1635305:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:17.043600 :A1635811:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:17.029 2025-06-07 05:57:17.043602 :A1635812:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10824 pso_num=24 pso_serial#=157 2025-06-07 05:57:17.043742 :A1635813:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10824 (legacy spawn) 2025-06-07 05:57:17.050235 :A1635814:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:17.050413 :A163581D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:17.051216 :A163581E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:17.059911 :A1635821:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:17.045 2025-06-07 05:57:17.059913 :A1635822:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15972 pso_num=24 pso_serial#=158 2025-06-07 05:57:17.060054 :A1635823:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15972 (legacy spawn) 2025-06-07 05:57:17.066539 :A1635824:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:17.066709 :A163582C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:17.067530 :A163582E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:17.831776 :A163587D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:17.826 2025-06-07 05:57:17.831778 :A163587E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12148 pso_num=24 pso_serial#=159 2025-06-07 05:57:17.831911 :A163587F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=12148 osp_idx=22 osp_ver=374008 osp_pg=0 (spawn #260236) 2025-06-07 05:57:17.831912 :A1635880:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 05:57:17.831913 :A1635881:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=169 time=2251596485 2025-06-07 05:57:17.831913 :A1635882:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 05:57:17.831913 :A1635883:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 05:57:17.831913 :A1635884:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 05:57:17.831914 :A1635885:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 05:57:17.831914 :A1635886:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 05:57:17.832397 :A1635887:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 05:57:17.833037 :A163589C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:17.840565 :A16358B8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-07 05:57:17.840942 :A16358B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:17.840977 :A16358BC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-07 05:57:17.841910 :A16358C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:17.842037 :A16358CB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:47.917681 :A163632E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:53.247601 :A16364AE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:53.244 2025-06-07 05:57:53.247603 :A16364AF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13864 pso_num=24 pso_serial#=160 2025-06-07 05:57:53.247745 :A16364B0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13864 (legacy spawn) 2025-06-07 05:57:53.254198 :A16364B1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:53.254376 :A16364B6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:53.255184 :A16364BB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:53.263799 :A16364BE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:53.260 2025-06-07 05:57:53.263801 :A16364BF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10888 pso_num=24 pso_serial#=161 2025-06-07 05:57:53.263939 :A16364C0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10888 (legacy spawn) 2025-06-07 05:57:53.270344 :A16364C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:53.270502 :A16364CA:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:53.271266 :A16364CB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:56.340729 :A16365C0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:56.327 2025-06-07 05:57:56.340731 :A16365C1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13060 pso_num=24 pso_serial#=162 2025-06-07 05:57:56.340870 :A16365C2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13060 (legacy spawn) 2025-06-07 05:57:56.347329 :A16365C3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:56.347506 :A16365C8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:56.348302 :A16365CD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:57:56.356822 :A16365D0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:57:56.343 2025-06-07 05:57:56.356824 :A16365D1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15624 pso_num=24 pso_serial#=163 2025-06-07 05:57:56.356960 :A16365D2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15624 (legacy spawn) 2025-06-07 05:57:56.363449 :A16365D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:57:56.363618 :A16365DB:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:57:56.364438 :A16365DC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:58:02.136941 :A16367BB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:58:02.129 2025-06-07 05:58:02.136944 :A16367BC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15708 pso_num=24 pso_serial#=164 2025-06-07 05:58:02.137086 :A16367BD:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15708 (legacy spawn) 2025-06-07 05:58:02.144038 :A16367BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:58:02.144225 :A16367C3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:58:02.145138 :A16367C8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:58:07.683291 :A16369B3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:58:07.675 2025-06-07 05:58:07.683293 :A16369B4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15928 pso_num=24 pso_serial#=165 2025-06-07 05:58:07.683435 :A16369B5:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15928 (legacy spawn) 2025-06-07 05:58:07.689873 :A16369B6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:58:07.690080 :A16369BE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:58:07.690882 :A16369C0:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:58:17.920863 :A1637CD5:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:58:17.915 2025-06-07 05:58:17.920865 :A1637CD6:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16120 pso_num=24 pso_serial#=166 2025-06-07 05:58:17.921002 :A1637CD7:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16120 osp_idx=22 osp_ver=374015 osp_pg=0 (spawn #260238) 2025-06-07 05:58:17.921002 :A1637CD8:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 05:58:17.921003 :A1637CD9:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=170 time=2251656563 2025-06-07 05:58:17.921003 :A1637CDA:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 05:58:17.921004 :A1637CDB:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 05:58:17.921004 :A1637CDC:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 05:58:17.921004 :A1637CDD:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 05:58:17.921005 :A1637CDE:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 05:58:17.921492 :A1637CDF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 05:58:17.922138 :A1637CF4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:58:17.933230 :A1637D10:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 05:58:17.933693 :A1637D11:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-07 05:58:17.936082 :A1637D12:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-07 05:58:17.936428 :A1637D13:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:58:17.936466 :A1637D16:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-07 05:58:17.937329 :A1637D1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:58:17.937455 :A1637D25:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:58:48.023148 :A163870F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:04.312687 :A1638C6D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:04.300 2025-06-07 05:59:04.312689 :A1638C6E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4696 pso_num=24 pso_serial#=167 2025-06-07 05:59:04.312830 :A1638C6F:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=4696 (legacy spawn) 2025-06-07 05:59:04.319295 :A1638C70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:04.319460 :A1638C79:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:04.320273 :A1638C7A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:05.846235 :A1638CA0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:05.832 2025-06-07 05:59:05.846237 :A1638CA1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15104 pso_num=24 pso_serial#=168 2025-06-07 05:59:05.846386 :A1638CA2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15104 (legacy spawn) 2025-06-07 05:59:05.853188 :A1638CA3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:05.853381 :A1638CA8:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:05.854277 :A1638CAD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:05.863512 :A1638CB0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:05.863 2025-06-07 05:59:05.863515 :A1638CB1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14440 pso_num=24 pso_serial#=169 2025-06-07 05:59:05.863671 :A1638CB2:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14440 (legacy spawn) 2025-06-07 05:59:05.870552 :A1638CB3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:05.870739 :A1638CBC:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:05.871633 :A1638CBD:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:08.949220 :A1638DEF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:08.936 2025-06-07 05:59:08.949222 :A1638DF0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15588 pso_num=24 pso_serial#=170 2025-06-07 05:59:08.949357 :A1638DF1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15588 (legacy spawn) 2025-06-07 05:59:08.955765 :A1638DF2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:08.955937 :A1638DF7:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:08.956774 :A1638DFC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:08.965382 :A1638DFF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:08.951 2025-06-07 05:59:08.965384 :A1638E00:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11860 pso_num=24 pso_serial#=171 2025-06-07 05:59:08.965518 :A1638E01:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11860 (legacy spawn) 2025-06-07 05:59:08.972035 :A1638E02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:08.972204 :A1638E0B:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:08.973008 :A1638E0C:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:15.774531 :A163903B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:15.766 2025-06-07 05:59:15.774533 :A163903C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15512 pso_num=24 pso_serial#=172 2025-06-07 05:59:15.774716 :A163903D:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15512 (legacy spawn) 2025-06-07 05:59:15.781159 :A163903E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:15.781342 :A1639043:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:15.782132 :A1639048:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:17.991958 :A16390EF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:17.986 2025-06-07 05:59:17.991960 :A16390F0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9872 pso_num=24 pso_serial#=173 2025-06-07 05:59:17.992124 :A16390F1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9872 osp_idx=22 osp_ver=374022 osp_pg=0 (spawn #260240) 2025-06-07 05:59:17.992125 :A16390F2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 05:59:17.992127 :A16390F3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=171 time=2251716641 2025-06-07 05:59:17.992128 :A16390F4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 05:59:17.992128 :A16390F5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 05:59:17.992128 :A16390F6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 05:59:17.992129 :A16390F7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 05:59:17.992131 :A16390F8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 05:59:17.992673 :A16390F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 05:59:17.993314 :A163910E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:18.000841 :A163912B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-06-07 05:59:18.001211 :A163912C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:18.001245 :A163912F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-06-07 05:59:18.002081 :A1639135:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:18.002199 :A163913E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:48.082596 :A1639AFE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 05:59:49.985190 :A1639BB2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 05:59:49.976 2025-06-07 05:59:49.985192 :A1639BB3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15876 pso_num=24 pso_serial#=174 2025-06-07 05:59:49.985336 :A1639BB4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=15876 (legacy spawn) 2025-06-07 05:59:49.991856 :A1639BB5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 05:59:49.992027 :A1639BBE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 05:59:49.992838 :A1639BBF:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-06-07 06:00:00.020013 :A1639EE3:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 06-07 06:00:00.013 2025-06-07 06:00:00.020015 :A1639EE4:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15968 pso_num=24 pso_serial#=175 2025-06-07 06:00:00.020171 :A1639EE5:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15968 osp_idx=22 osp_ver=374024 osp_pg=0 (spawn #260242) 2025-06-07 06:00:00.020171 :A1639EE6:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 06:00:00.020172 :A1639EE7:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=172 time=2251758672 2025-06-07 06:00:00.020172 :A1639EE8:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 06:00:00.020173 :A1639EE9:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 06:00:00.020173 :A1639EEA:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 06:00:00.020174 :A1639EEB:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 06:00:00.020174 :A1639EEC:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 06:00:00.020272 :A1639EED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 06:00:00.020278 :A1639EEE:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:24:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 15968, VKRM)