Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250702220006\orcl_vkrm_14064_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:36860M/63366M, Ph+PgF:42867M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 14064, image: ORACLE.EXE (VKRM) *** 2025-07-02 22:00:06.253 *** SESSION ID:(177.26581) 2025-07-02 22:00:06.253 *** 2025-07-02 22:00:06.253 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=14064, pid: 22, proc_ser: 41, sid: 177, sess_ser: 26581 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=6.231651 sec, exc=6.231651 sec, total=6.231651 sec wait times: max=infinite, heur=6.231651 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 26581 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:00:00 - 22:00:06] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-02 22:00:06.253 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 14064, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-02 21:57:19.975641 :ACB259D8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:57:19.967 2025-07-02 21:57:19.975643 :ACB259D9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4124 pso_num=22 pso_serial#=25 2025-07-02 21:57:19.975785 :ACB259DA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4124 (legacy spawn) 2025-07-02 21:57:19.982253 :ACB259DB: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-07-02 21:57:19.982422 :ACB259E4: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-07-02 21:57:19.983226 :ACB259E5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:57:20.089760 :ACB259E8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:57:20.077 2025-07-02 21:57:20.089762 :ACB259E9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14672 pso_num=22 pso_serial#=26 2025-07-02 21:57:20.089906 :ACB259EA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14672 (legacy spawn) 2025-07-02 21:57:20.096427 :ACB259EB: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-07-02 21:57:20.096591 :ACB259F4: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-07-02 21:57:20.097402 :ACB259F5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:57:20.132272 :ACB25A27:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:57:20.124 2025-07-02 21:57:20.132274 :ACB25A28:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3740 pso_num=22 pso_serial#=27 2025-07-02 21:57:20.132412 :ACB25A29:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=3740 (legacy spawn) 2025-07-02 21:57:20.138807 :ACB25A2A: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-07-02 21:57:20.138838 :ACB25A2D: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-07-02 21:57:20.138982 :ACB25A34: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-07-02 21:57:20.139799 :ACB25A41:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:57:23.646022 :ACB25B4C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:57:23.640 2025-07-02 21:57:23.646025 :ACB25B4D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17004 pso_num=22 pso_serial#=28 2025-07-02 21:57:23.646175 :ACB25B4E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17004 osp_idx=0 osp_ver=369085 osp_pg=0 (spawn #345819) 2025-07-02 21:57:23.646176 :ACB25B4F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:57:23.646177 :ACB25B50:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=56 time=174219533 2025-07-02 21:57:23.646177 :ACB25B51:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:57:23.646177 :ACB25B52:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:57:23.646178 :ACB25B53:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:57:23.646178 :ACB25B54:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:57:23.646178 :ACB25B55:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:57:23.646715 :ACB25B56: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-07-02 21:57:23.647440 :ACB25B6B: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-07-02 21:57:23.655167 :ACB25B87:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-02 21:57:23.655605 :ACB25B88: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-07-02 21:57:23.655648 :ACB25B8B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-02 21:57:23.656609 :ACB25B94: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-07-02 21:57:23.656739 :ACB25B9D: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-07-02 21:57:53.774979 :ACB26554:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:57:56.405833 :ACB26646:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:57:56.392 2025-07-02 21:57:56.405834 :ACB26647:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=280 pso_num=22 pso_serial#=29 2025-07-02 21:57:56.405969 :ACB26648:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=280 (legacy spawn) 2025-07-02 21:57:56.412455 :ACB26649: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-07-02 21:57:56.412628 :ACB26651: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-07-02 21:57:56.413393 :ACB26653:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:57:56.432142 :ACB26656:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:57:56.424 2025-07-02 21:57:56.432144 :ACB26657:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13424 pso_num=22 pso_serial#=30 2025-07-02 21:57:56.432286 :ACB26658:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13424 (legacy spawn) 2025-07-02 21:57:56.438775 :ACB26659: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-07-02 21:57:56.438964 :ACB26662: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-07-02 21:57:56.439808 :ACB26663:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:58:22.131974 :ACB26E42:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:58:22.124 2025-07-02 21:58:22.131977 :ACB26E43:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14828 pso_num=22 pso_serial#=31 2025-07-02 21:58:22.132120 :ACB26E44:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14828 (legacy spawn) 2025-07-02 21:58:22.138596 :ACB26E45: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-07-02 21:58:22.138781 :ACB26E4A: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-07-02 21:58:22.139679 :ACB26E4F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:58:23.733756 :ACB26EEB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:58:23.728 2025-07-02 21:58:23.733758 :ACB26EEC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15640 pso_num=22 pso_serial#=32 2025-07-02 21:58:23.733899 :ACB26EED:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15640 osp_idx=0 osp_ver=369089 osp_pg=0 (spawn #345822) 2025-07-02 21:58:23.733900 :ACB26EEE:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:58:23.733900 :ACB26EEF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=57 time=174279626 2025-07-02 21:58:23.733901 :ACB26EF0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:58:23.733901 :ACB26EF1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:58:23.733902 :ACB26EF2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:58:23.733902 :ACB26EF3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:58:23.733902 :ACB26EF4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:58:23.734393 :ACB26EF5: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-07-02 21:58:23.735043 :ACB26F0A: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-07-02 21:58:23.749163 :ACB26F26:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-02 21:58:23.749246 :ACB26F27:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-02 21:58:23.750107 :ACB26F28:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-02 21:58:23.750464 :ACB26F29: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-07-02 21:58:23.750504 :ACB26F2C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-02 21:58:23.758641 :ACB26F32: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-07-02 21:58:23.758720 :ACB26F35:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-07-02 21:58:23.758771 :ACB26F36:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-07-02 21:58:23.759171 :ACB26F3C:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-02 21:58:23.759553 :ACB26F3D:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-02 21:58:23.759754 :ACB26F3E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-02 21:58:23.760009 :ACB26F3F: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-07-02 21:58:23.760037 :ACB26F42:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-02 21:58:23.761603 :ACB26F48:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-02 21:58:23.761820 :ACB26F49: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-07-02 21:58:23.761847 :ACB26F4C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-02 21:58:23.762843 :ACB26F52: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-07-02 21:58:23.762962 :ACB26F5B: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-07-02 21:58:53.851513 :ACB2792D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:58:59.534439 :ACB27B05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:58:59.526 2025-07-02 21:58:59.534442 :ACB27B06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15800 pso_num=22 pso_serial#=33 2025-07-02 21:58:59.534579 :ACB27B07:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15800 (legacy spawn) 2025-07-02 21:58:59.540977 :ACB27B08: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-07-02 21:58:59.541151 :ACB27B10: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-07-02 21:58:59.541980 :ACB27B12:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:06.190100 :ACB27CF5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:06.176 2025-07-02 21:59:06.190102 :ACB27CF6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10124 pso_num=22 pso_serial#=34 2025-07-02 21:59:06.190245 :ACB27CF7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10124 (legacy spawn) 2025-07-02 21:59:06.196611 :ACB27CF8: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-07-02 21:59:06.196791 :ACB27CFF: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-07-02 21:59:06.197625 :ACB27D02:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:06.206270 :ACB27D05:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:06.191 2025-07-02 21:59:06.206272 :ACB27D06:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4644 pso_num=22 pso_serial#=35 2025-07-02 21:59:06.206407 :ACB27D07:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=4644 (legacy spawn) 2025-07-02 21:59:06.212780 :ACB27D08: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-07-02 21:59:06.212958 :ACB27D10: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-07-02 21:59:06.213786 :ACB27D12:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:09.404506 :ACB27E52:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:09.397 2025-07-02 21:59:09.404508 :ACB27E53:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11076 pso_num=22 pso_serial#=36 2025-07-02 21:59:09.404642 :ACB27E54:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11076 (legacy spawn) 2025-07-02 21:59:09.411134 :ACB27E55: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-07-02 21:59:09.411314 :ACB27E5A: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-07-02 21:59:09.412238 :ACB27E5F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:09.420942 :ACB27E62:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:09.412 2025-07-02 21:59:09.420944 :ACB27E63:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2512 pso_num=22 pso_serial#=37 2025-07-02 21:59:09.421079 :ACB27E64:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2512 (legacy spawn) 2025-07-02 21:59:09.427515 :ACB27E65: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-07-02 21:59:09.427674 :ACB27E6E: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-07-02 21:59:09.428456 :ACB27E6F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:23.828091 :ACB282BF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:23.822 2025-07-02 21:59:23.828093 :ACB282C0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11012 pso_num=22 pso_serial#=38 2025-07-02 21:59:23.828237 :ACB282C1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11012 osp_idx=0 osp_ver=369095 osp_pg=0 (spawn #345824) 2025-07-02 21:59:23.828237 :ACB282C2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:59:23.828238 :ACB282C3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=58 time=174339720 2025-07-02 21:59:23.828238 :ACB282C4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:59:23.828238 :ACB282C5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:59:23.828239 :ACB282C6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:59:23.828239 :ACB282C7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:59:23.828240 :ACB282C8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:59:23.828736 :ACB282C9: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-07-02 21:59:23.829383 :ACB282DE: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-07-02 21:59:23.836943 :ACB282FA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-02 21:59:23.837306 :ACB282FB: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-07-02 21:59:23.837344 :ACB282FE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-02 21:59:23.838199 :ACB2830F: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-07-02 21:59:23.838315 :ACB28318: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-07-02 21:59:53.938225 :ACB28C9D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:55.403688 :ACB28D15:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:55.390 2025-07-02 21:59:55.403690 :ACB28D16:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10400 pso_num=22 pso_serial#=39 2025-07-02 21:59:55.403834 :ACB28D17:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10400 (legacy spawn) 2025-07-02 21:59:55.410265 :ACB28D18: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-07-02 21:59:55.410450 :ACB28D20: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-07-02 21:59:55.411231 :ACB28D22:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 21:59:56.427654 :ACB28D88:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 21:59:56.422 2025-07-02 21:59:56.427657 :ACB28D89:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4280 pso_num=22 pso_serial#=40 2025-07-02 21:59:56.427802 :ACB28D8A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4280 osp_idx=0 osp_ver=369097 osp_pg=0 (spawn #345826) 2025-07-02 21:59:56.427803 :ACB28D8B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 21:59:56.427803 :ACB28D8C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=59 time=174372314 2025-07-02 21:59:56.427803 :ACB28D8D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 21:59:56.427804 :ACB28D8E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 21:59:56.427804 :ACB28D8F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 21:59:56.427804 :ACB28D90:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 21:59:56.427805 :ACB28D91:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 21:59:56.428298 :ACB28D92: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-07-02 21:59:56.428343 :ACB28D98: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-07-02 21:59:56.432354 :ACB28DC1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-02 21:59:56.432395 :ACB28DC2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-02 21:59:56.432653 :ACB28DC3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-02 21:59:56.432669 :ACB28DC4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-02 21:59:56.432881 :ACB28DC5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-02 21:59:56.432895 :ACB28DC6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-02 21:59:56.433102 :ACB28DC7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-02 21:59:56.433115 :ACB28DC8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-02 21:59:56.433326 :ACB28DC9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-02 21:59:56.433339 :ACB28DCA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-02 21:59:56.433540 :ACB28DCB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:kelt.c:517 2025-07-02 21:59:56.433553 :ACB28DCC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:kelt.c:517 2025-07-02 21:59:56.435929 :ACB28DCD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-02 22:00:00.027309 :ACB28EEE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-02 22:00:00.020 2025-07-02 22:00:00.027311 :ACB28EEF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14064 pso_num=22 pso_serial#=41 2025-07-02 22:00:00.027419 :ACB28EF0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14064 osp_idx=0 osp_ver=369098 osp_pg=0 (spawn #345827) 2025-07-02 22:00:00.027420 :ACB28EF1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-02 22:00:00.027420 :ACB28EF2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=60 time=174375923 2025-07-02 22:00:00.027421 :ACB28EF3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-02 22:00:00.027421 :ACB28EF4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-02 22:00:00.027421 :ACB28EF5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-02 22:00:00.027421 :ACB28EF6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-02 22:00:00.027422 :ACB28EF7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-02 22:00:00.027492 :ACB28EF8: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-07-02 22:00:00.027499 :ACB28EF9: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: 14064, VKRM)