Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250616220008\orcl_vkrm_17132_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:36562M/63366M, Ph+PgF:42969M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 17132, image: ORACLE.EXE (VKRM) *** 2025-06-16 22:00:08.132 *** SESSION ID:(177.28757) 2025-06-16 22:00:08.132 *** 2025-06-16 22:00:08.132 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=17132, pid: 22, proc_ser: 62, sid: 177, sess_ser: 28757 ------------------------------------------------------------------------------- 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=8.110330 sec, exc=8.110330 sec, total=8.110330 sec wait times: max=infinite, heur=8.110330 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 28757 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:00:00 - 22:00:08] 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-06-16 22:00:08.132 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 17132, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-16 21:56:20.715575 :A5A12E53:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-16 21:56:20.716552 :A5A12E59: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-06-16 21:56:20.716698 :A5A12E62: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-06-16 21:56:50.800497 :A5A137EA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:06.020911 :A5A13CB5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:06.012 2025-06-16 21:57:06.020913 :A5A13CB6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17192 pso_num=22 pso_serial#=41 2025-06-16 21:57:06.021050 :A5A13CB7:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17192 (legacy spawn) 2025-06-16 21:57:06.027564 :A5A13CB8: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-06-16 21:57:06.027736 :A5A13CBD: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-06-16 21:57:06.028565 :A5A13CC2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:17.384011 :A5A14055:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:17.380 2025-06-16 21:57:17.384013 :A5A14056:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15712 pso_num=22 pso_serial#=42 2025-06-16 21:57:17.384158 :A5A14057:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15712 (legacy spawn) 2025-06-16 21:57:17.390976 :A5A14058: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-06-16 21:57:17.391173 :A5A1405D: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-06-16 21:57:17.392079 :A5A14062:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:17.761810 :A5A14065:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:17.755 2025-06-16 21:57:17.761812 :A5A14066:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16084 pso_num=22 pso_serial#=43 2025-06-16 21:57:17.761949 :A5A14067:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16084 (legacy spawn) 2025-06-16 21:57:17.768468 :A5A14068: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-06-16 21:57:17.768633 :A5A14071: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-06-16 21:57:17.769417 :A5A14072:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:17.771673 :A5A14075:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:17.771 2025-06-16 21:57:17.771676 :A5A14076:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15696 pso_num=22 pso_serial#=44 2025-06-16 21:57:17.771805 :A5A14077:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15696 (legacy spawn) 2025-06-16 21:57:17.778143 :A5A14078: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-06-16 21:57:17.778305 :A5A14081: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-06-16 21:57:17.779100 :A5A14082:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:20.781318 :A5A1417B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:20.776 2025-06-16 21:57:20.781320 :A5A1417C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12500 pso_num=22 pso_serial#=45 2025-06-16 21:57:20.781458 :A5A1417D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12500 osp_idx=0 osp_ver=314532 osp_pg=0 (spawn #292482) 2025-06-16 21:57:20.781459 :A5A1417E:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:57:20.781459 :A5A1417F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=255 time=3086783969 2025-06-16 21:57:20.781459 :A5A14180:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:57:20.781460 :A5A14181:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:57:20.781460 :A5A14182:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:57:20.781461 :A5A14183:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:57:20.781461 :A5A14184:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:57:20.781946 :A5A14185: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-06-16 21:57:20.782562 :A5A1419A: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-06-16 21:57:20.790139 :A5A141B7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-16 21:57:20.790520 :A5A141B8: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-06-16 21:57:20.790555 :A5A141BB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-16 21:57:20.791463 :A5A141C1: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-06-16 21:57:20.791597 :A5A141CA: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-06-16 21:57:50.918612 :A5A14BC3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:54.005647 :A5A14CA2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:53.991 2025-06-16 21:57:54.005649 :A5A14CA3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16424 pso_num=22 pso_serial#=46 2025-06-16 21:57:54.005820 :A5A14CA4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16424 (legacy spawn) 2025-06-16 21:57:54.012163 :A5A14CA5: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-06-16 21:57:54.012335 :A5A14CAA: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-06-16 21:57:54.013147 :A5A14CAF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:54.021768 :A5A14CB2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:54.007 2025-06-16 21:57:54.021770 :A5A14CB3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17140 pso_num=22 pso_serial#=47 2025-06-16 21:57:54.021909 :A5A14CB4:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17140 (legacy spawn) 2025-06-16 21:57:54.028663 :A5A14CB5: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-06-16 21:57:54.028859 :A5A14CBD: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-06-16 21:57:54.029752 :A5A14CBF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:57.180977 :A5A14DD6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:57.168 2025-06-16 21:57:57.180979 :A5A14DD7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16916 pso_num=22 pso_serial#=48 2025-06-16 21:57:57.181117 :A5A14DD8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16916 (legacy spawn) 2025-06-16 21:57:57.187655 :A5A14DD9: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-06-16 21:57:57.187844 :A5A14DDE: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-06-16 21:57:57.188637 :A5A14DE3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:57:57.197380 :A5A14DE6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:57:57.183 2025-06-16 21:57:57.197382 :A5A14DE7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15492 pso_num=22 pso_serial#=49 2025-06-16 21:57:57.197540 :A5A14DE8:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15492 (legacy spawn) 2025-06-16 21:57:57.203949 :A5A14DE9: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-06-16 21:57:57.204116 :A5A14DF2: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-06-16 21:57:57.204916 :A5A14DF3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:58:14.221312 :A5A152EB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:58:14.212 2025-06-16 21:58:14.221314 :A5A152EC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12152 pso_num=22 pso_serial#=50 2025-06-16 21:58:14.221461 :A5A152ED:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12152 (legacy spawn) 2025-06-16 21:58:14.227974 :A5A152EE: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-06-16 21:58:14.228152 :A5A152F3: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-06-16 21:58:14.228981 :A5A152F8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:58:19.978248 :A5A154DE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:58:19.966 2025-06-16 21:58:19.978250 :A5A154DF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14136 pso_num=22 pso_serial#=51 2025-06-16 21:58:19.978380 :A5A154E0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14136 (legacy spawn) 2025-06-16 21:58:19.984850 :A5A154E1: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-06-16 21:58:19.985028 :A5A154E6: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-06-16 21:58:19.985808 :A5A154EB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:58:20.862543 :A5A1555D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:58:20.856 2025-06-16 21:58:20.862545 :A5A1555E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16988 pso_num=22 pso_serial#=52 2025-06-16 21:58:20.862689 :A5A1555F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16988 osp_idx=0 osp_ver=314539 osp_pg=0 (spawn #292484) 2025-06-16 21:58:20.862690 :A5A15560:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:58:20.862690 :A5A15561:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=0 time=3086844047 2025-06-16 21:58:20.862690 :A5A15562:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:58:20.862691 :A5A15563:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:58:20.862691 :A5A15564:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:58:20.862692 :A5A15565:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:58:20.862692 :A5A15566:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:58:20.863177 :A5A15567: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-06-16 21:58:20.863814 :A5A1557C: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-06-16 21:58:20.874807 :A5A15598:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-16 21:58:20.875265 :A5A15599:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-16 21:58:20.877567 :A5A1559A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-16 21:58:20.878016 :A5A1559B: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-06-16 21:58:20.878053 :A5A1559E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-16 21:58:20.878931 :A5A155A4: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-06-16 21:58:20.879059 :A5A155AD: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-06-16 21:58:50.996875 :A5A15F0F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:58:51.083177 :A5A15F13:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:58:51.075 2025-06-16 21:58:51.083179 :A5A15F14:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16044 pso_num=22 pso_serial#=53 2025-06-16 21:58:51.083315 :A5A15F15:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16044 (legacy spawn) 2025-06-16 21:58:51.089817 :A5A15F16: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-06-16 21:58:51.089991 :A5A15F1B: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-06-16 21:58:51.090806 :A5A15F20:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:06.727205 :A5A163F7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:06.713 2025-06-16 21:59:06.727208 :A5A163F8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12664 pso_num=22 pso_serial#=54 2025-06-16 21:59:06.727340 :A5A163F9:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12664 (legacy spawn) 2025-06-16 21:59:06.733728 :A5A163FA: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-06-16 21:59:06.733899 :A5A16403: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-06-16 21:59:06.734682 :A5A16404:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:06.743384 :A5A16407:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:06.729 2025-06-16 21:59:06.743386 :A5A16408:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14804 pso_num=22 pso_serial#=55 2025-06-16 21:59:06.743532 :A5A16409:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14804 (legacy spawn) 2025-06-16 21:59:06.750038 :A5A1640A: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-06-16 21:59:06.750200 :A5A16413: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-06-16 21:59:06.750986 :A5A16414:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:09.799204 :A5A164FA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:09.786 2025-06-16 21:59:09.799206 :A5A164FB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13440 pso_num=22 pso_serial#=56 2025-06-16 21:59:09.799341 :A5A164FC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13440 (legacy spawn) 2025-06-16 21:59:09.805807 :A5A164FD: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-06-16 21:59:09.805973 :A5A16505: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-06-16 21:59:09.806787 :A5A16507:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:09.815414 :A5A1650A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:09.801 2025-06-16 21:59:09.815416 :A5A1650B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17156 pso_num=22 pso_serial#=57 2025-06-16 21:59:09.815554 :A5A1650C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17156 (legacy spawn) 2025-06-16 21:59:09.822034 :A5A1650D: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-06-16 21:59:09.822205 :A5A16516: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-06-16 21:59:09.823022 :A5A16517:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:20.374414 :A5A1683E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:20.374 2025-06-16 21:59:20.374416 :A5A1683F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15876 pso_num=22 pso_serial#=58 2025-06-16 21:59:20.374559 :A5A16840:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15876 (legacy spawn) 2025-06-16 21:59:20.381017 :A5A16841: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-06-16 21:59:20.381184 :A5A16846: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-06-16 21:59:20.381981 :A5A1684B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:20.950568 :A5A168C1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:20.945 2025-06-16 21:59:20.950570 :A5A168C2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13760 pso_num=22 pso_serial#=59 2025-06-16 21:59:20.950708 :A5A168C3:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13760 osp_idx=0 osp_ver=314546 osp_pg=0 (spawn #292486) 2025-06-16 21:59:20.950709 :A5A168C4:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 21:59:20.950709 :A5A168C5:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=1 time=3086904141 2025-06-16 21:59:20.950710 :A5A168C6:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 21:59:20.950710 :A5A168C7:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 21:59:20.950710 :A5A168C8:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 21:59:20.950711 :A5A168C9:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 21:59:20.950711 :A5A168CA:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 21:59:20.951201 :A5A168CB: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-06-16 21:59:20.951840 :A5A168E0: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-06-16 21:59:20.959397 :A5A168FC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-16 21:59:20.959772 :A5A168FD: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-06-16 21:59:20.959811 :A5A16900:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-16 21:59:20.960653 :A5A16906: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-06-16 21:59:20.960766 :A5A1690F: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-06-16 21:59:51.043504 :A5A172DF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:53.249042 :A5A17372:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:53.241 2025-06-16 21:59:53.249045 :A5A17373:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17276 pso_num=22 pso_serial#=60 2025-06-16 21:59:53.249183 :A5A17374:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17276 (legacy spawn) 2025-06-16 21:59:53.255698 :A5A17375: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-06-16 21:59:53.255876 :A5A1737A: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-06-16 21:59:53.256697 :A5A1737F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 21:59:56.551526 :A5A17492:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 21:59:56.542 2025-06-16 21:59:56.551528 :A5A17493:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15404 pso_num=22 pso_serial#=61 2025-06-16 21:59:56.551668 :A5A17494:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=15404 (legacy spawn) 2025-06-16 21:59:56.558166 :A5A17495: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-06-16 21:59:56.558335 :A5A1749A: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-06-16 21:59:56.559171 :A5A1749F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-16 22:00:00.027371 :A5A175CD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-16 22:00:00.020 2025-06-16 22:00:00.027373 :A5A175CE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17132 pso_num=22 pso_serial#=62 2025-06-16 22:00:00.027485 :A5A175CF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17132 osp_idx=0 osp_ver=314549 osp_pg=0 (spawn #292489) 2025-06-16 22:00:00.027485 :A5A175D0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-16 22:00:00.027486 :A5A175D1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=2 time=3086943219 2025-06-16 22:00:00.027486 :A5A175D2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-16 22:00:00.027486 :A5A175D3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-16 22:00:00.027487 :A5A175D4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-16 22:00:00.027487 :A5A175D5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-16 22:00:00.027487 :A5A175D6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-16 22:00:00.027555 :A5A175D7: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-06-16 22:00:00.027561 :A5A175D8: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: 17132, VKRM)