Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250528220008\orcl_vkrm_12788_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:37283M/63366M, Ph+PgF:44240M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 12788, image: ORACLE.EXE (VKRM) *** 2025-05-28 22:00:08.578 *** SESSION ID:(209.42331) 2025-05-28 22:00:08.578 *** 2025-05-28 22:00:08.578 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=12788, pid: 26, proc_ser: 4, sid: 209, sess_ser: 42331 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=8.460657 sec, exc=8.460657 sec, total=8.460657 sec wait times: max=infinite, heur=8.460657 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.000965 sec since current wait 0: waited for 'resmgr:internal state change' location=0x3, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.100945 sec, exc=0.100945 sec, total=0.100945 sec wait times: max=0.100000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 209 serial 42331 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:00:01 - 22:00:08] idle wait at each sample [1 sample, 22:00:00] waited for 'resmgr:internal state change', seq_num: 1 p1: 'location'=0x3 p2: ' '=0x0 p3: ' '=0x0 time_waited: 0.100945 sec (sample interval: 0 sec) [1 sample, 21:59:59] idle wait at each sample [session created at: 22:00:00] --------------------------------------------------- Sampled Session History Summary: longest_non_idle_wait: 'resmgr:internal state change' [1 sample, 22:00:00 ] time_waited: 0.100945 sec (sample interval: 0 sec) --------------------------------------------------- ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-28 22:00:08.578 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 12788, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-28 21:55:42.176180 :9D498CD4:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:55:42.176993 :9D498CD6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:55:45.987279 :9D498DF9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:55:45.978 2025-05-28 21:55:45.987281 :9D498DFA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13080 pso_num=26 pso_serial#=232 2025-05-28 21:55:45.987417 :9D498DFB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13080 (legacy spawn) 2025-05-28 21:55:45.993828 :9D498DFC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:55:45.994032 :9D498E01:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:55:45.994836 :9D498E06:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:55:55.503853 :9D49914B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:55:55.496 2025-05-28 21:55:55.503855 :9D49914C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13544 pso_num=26 pso_serial#=233 2025-05-28 21:55:55.503989 :9D49914D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13544 (legacy spawn) 2025-05-28 21:55:55.510481 :9D49914E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:55:55.510653 :9D499153:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:55:55.511486 :9D499158:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:56:51.958840 :9D49A2FB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:56:51.950 2025-05-28 21:56:51.958842 :9D49A2FC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10512 pso_num=26 pso_serial#=234 2025-05-28 21:56:51.958995 :9D49A2FD:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10512 (legacy spawn) 2025-05-28 21:56:51.965528 :9D49A2FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:56:51.965699 :9D49A303:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:56:51.966521 :9D49A308:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:56:51.975289 :9D49A30B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:56:51.966 2025-05-28 21:56:51.975291 :9D49A30C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14756 pso_num=26 pso_serial#=235 2025-05-28 21:56:51.975423 :9D49A30D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14756 (legacy spawn) 2025-05-28 21:56:51.981905 :9D49A30E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:56:51.982067 :9D49A316:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:56:51.982867 :9D49A318:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:56:54.172253 :9D49A3C5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:56:54.163 2025-05-28 21:56:54.172255 :9D49A3C6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13356 pso_num=26 pso_serial#=236 2025-05-28 21:56:54.172411 :9D49A3C7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13356 (legacy spawn) 2025-05-28 21:56:54.178842 :9D49A3C8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:56:54.179080 :9D49A3CD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:56:54.179932 :9D49A3D2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:56:55.036020 :9D49A40D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:56:55.022 2025-05-28 21:56:55.036022 :9D49A40E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15220 pso_num=26 pso_serial#=237 2025-05-28 21:56:55.036164 :9D49A40F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=15220 (legacy spawn) 2025-05-28 21:56:55.042692 :9D49A410:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:56:55.042890 :9D49A415:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:56:55.043681 :9D49A41A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:56:55.052252 :9D49A41D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:56:55.038 2025-05-28 21:56:55.052254 :9D49A41E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14468 pso_num=26 pso_serial#=238 2025-05-28 21:56:55.052385 :9D49A41F:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14468 (legacy spawn) 2025-05-28 21:56:55.058796 :9D49A420:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:56:55.058959 :9D49A429:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:56:55.059738 :9D49A42A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:56:57.999455 :9D49A519:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:56:57.991 2025-05-28 21:56:57.999457 :9D49A51A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14432 pso_num=26 pso_serial#=239 2025-05-28 21:56:57.999591 :9D49A51B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14432 (legacy spawn) 2025-05-28 21:56:58.006131 :9D49A51C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:56:58.006306 :9D49A521:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:56:58.007169 :9D49A526:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:57:50.537825 :9D49B5BE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:57:50.529 2025-05-28 21:57:50.537826 :9D49B5BF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12620 pso_num=26 pso_serial#=240 2025-05-28 21:57:50.537960 :9D49B5C0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12620 (legacy spawn) 2025-05-28 21:57:50.544401 :9D49B5C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:57:50.544574 :9D49B5C6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:57:50.545260 :9D49B5EE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:00.197846 :9D49B8A8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:00.189 2025-05-28 21:58:00.197848 :9D49B8A9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14472 pso_num=26 pso_serial#=241 2025-05-28 21:58:00.197988 :9D49B8AA:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14472 (legacy spawn) 2025-05-28 21:58:00.204516 :9D49B8AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:00.204699 :9D49B8B2:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:00.205565 :9D49B8B5:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:02.579777 :9D49B97B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:02.571 2025-05-28 21:58:02.579780 :9D49B97C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14456 pso_num=26 pso_serial#=242 2025-05-28 21:58:02.579919 :9D49B97D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14456 (legacy spawn) 2025-05-28 21:58:02.586340 :9D49B97E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:02.586530 :9D49B983:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:02.603096 :9D49B988:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:04.635042 :9D49B9E6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:04.635 2025-05-28 21:58:04.635043 :9D49B9E7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13808 pso_num=26 pso_serial#=243 2025-05-28 21:58:04.635133 :9D49B9E8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13808 (legacy spawn) 2025-05-28 21:58:04.641375 :9D49B9E9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:04.641547 :9D49B9EE:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:04.642500 :9D49B9F3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:04.650989 :9D49B9F6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:04.651 2025-05-28 21:58:04.650990 :9D49B9F7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14204 pso_num=26 pso_serial#=244 2025-05-28 21:58:04.651125 :9D49B9F8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14204 (legacy spawn) 2025-05-28 21:58:04.657524 :9D49B9F9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:04.657688 :9D49BA02:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:04.658536 :9D49BA03:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:07.835553 :9D49BB3F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:07.822 2025-05-28 21:58:07.835555 :9D49BB40:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7792 pso_num=26 pso_serial#=245 2025-05-28 21:58:07.835687 :9D49BB41:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=7792 (legacy spawn) 2025-05-28 21:58:07.842129 :9D49BB42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:07.842302 :9D49BB47:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:07.843105 :9D49BB4C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:07.851619 :9D49BB4F:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:07.837 2025-05-28 21:58:07.851621 :9D49BB50:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13744 pso_num=26 pso_serial#=246 2025-05-28 21:58:07.851753 :9D49BB51:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13744 (legacy spawn) 2025-05-28 21:58:07.858204 :9D49BB5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:07.858375 :9D49BB67:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:07.859179 :9D49BB68:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:40.938022 :9D49C5D3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:40.931 2025-05-28 21:58:40.938024 :9D49C5D4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12244 pso_num=26 pso_serial#=247 2025-05-28 21:58:40.938204 :9D49C5D5:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12244 (legacy spawn) 2025-05-28 21:58:40.944718 :9D49C5D6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:40.944902 :9D49C5DF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:40.945738 :9D49C5E0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:40.954764 :9D49C5E3:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:40.946 2025-05-28 21:58:40.954767 :9D49C5E4:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14180 pso_num=26 pso_serial#=248 2025-05-28 21:58:40.954907 :9D49C5E5:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14180 (legacy spawn) 2025-05-28 21:58:40.961527 :9D49C5E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:40.961686 :9D49C5EF:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:40.962543 :9D49C5F0:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:44.053906 :9D49C6CA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:44.040 2025-05-28 21:58:44.053908 :9D49C6CB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11860 pso_num=26 pso_serial#=249 2025-05-28 21:58:44.054041 :9D49C6CC:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11860 (legacy spawn) 2025-05-28 21:58:44.060511 :9D49C6CD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:44.060687 :9D49C6D2:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:44.061547 :9D49C6D7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:44.070160 :9D49C6DA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:44.056 2025-05-28 21:58:44.070162 :9D49C6DB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14012 pso_num=26 pso_serial#=250 2025-05-28 21:58:44.070298 :9D49C6DC:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14012 (legacy spawn) 2025-05-28 21:58:44.076848 :9D49C6DD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:44.077017 :9D49C6E6:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:44.077902 :9D49C6E7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:46.836120 :9D49C7A5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:46.823 2025-05-28 21:58:46.836123 :9D49C7A6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13472 pso_num=26 pso_serial#=251 2025-05-28 21:58:46.836268 :9D49C7A7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13472 (legacy spawn) 2025-05-28 21:58:46.842915 :9D49C7A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:46.843080 :9D49C7AD:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:46.843871 :9D49C7B2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:58:46.852519 :9D49C7B5:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:58:46.838 2025-05-28 21:58:46.852521 :9D49C7B6:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13400 pso_num=26 pso_serial#=252 2025-05-28 21:58:46.852656 :9D49C7B7:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=13400 (legacy spawn) 2025-05-28 21:58:46.859252 :9D49C7E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:58:46.859420 :9D49C7E9:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:58:46.860222 :9D49C7EA:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:59:02.352313 :9D49CC69:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:59:02.344 2025-05-28 21:59:02.352315 :9D49CC6A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14224 pso_num=26 pso_serial#=253 2025-05-28 21:59:02.352457 :9D49CC6B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14224 (legacy spawn) 2025-05-28 21:59:02.358972 :9D49CC6C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:02.359144 :9D49CC71:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:59:02.359988 :9D49CC76:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:59:44.867398 :9D49DA42:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:59:44.858 2025-05-28 21:59:44.867400 :9D49DA43:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14984 pso_num=26 pso_serial#=254 2025-05-28 21:59:44.867544 :9D49DA44:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14984 (legacy spawn) 2025-05-28 21:59:44.873998 :9D49DA45:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:44.874038 :9D49DA48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:44.874229 :9D49DA4F:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:59:44.875095 :9D49DA5C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:59:53.945350 :9D49DD0E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:59:53.937 2025-05-28 21:59:53.945352 :9D49DD0F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7708 pso_num=26 pso_serial#=255 2025-05-28 21:59:53.945487 :9D49DD10:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=7708 (legacy spawn) 2025-05-28 21:59:53.951975 :9D49DD11:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:53.952146 :9D49DD1A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:59:53.953019 :9D49DD1B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:59:53.961608 :9D49DD1E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:59:53.952 2025-05-28 21:59:53.961610 :9D49DD1F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10720 pso_num=26 pso_serial#=1 2025-05-28 21:59:53.961742 :9D49DD20:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10720 (legacy spawn) 2025-05-28 21:59:53.968091 :9D49DD21:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:53.968300 :9D49DD2A:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:59:53.969095 :9D49DD2B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:59:57.139844 :9D49DE1C:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:59:57.126 2025-05-28 21:59:57.139846 :9D49DE1D:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14976 pso_num=26 pso_serial#=2 2025-05-28 21:59:57.139988 :9D49DE1E:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14976 (legacy spawn) 2025-05-28 21:59:57.146499 :9D49DE1F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:57.146530 :9D49DE22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:57.146689 :9D49DE29:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:59:57.147490 :9D49DE36:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 21:59:57.156154 :9D49DE39:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 21:59:57.142 2025-05-28 21:59:57.156156 :9D49DE3A:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14776 pso_num=26 pso_serial#=3 2025-05-28 21:59:57.156292 :9D49DE3B:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=14776 (legacy spawn) 2025-05-28 21:59:57.162682 :9D49DE3C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-28 21:59:57.162849 :9D49DE45:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-28 21:59:57.163693 :9D49DE46:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-28 22:00:00.029172 :9D49DF37:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-28 22:00:00.022 2025-05-28 22:00:00.029174 :9D49DF38:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12788 pso_num=26 pso_serial#=4 2025-05-28 22:00:00.029283 :9D49DF39:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12788 osp_idx=24 osp_ver=347569 osp_pg=0 (spawn #229136) 2025-05-28 22:00:00.029283 :9D49DF3A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-28 22:00:00.029284 :9D49DF3B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=229 time=1445372282 2025-05-28 22:00:00.029284 :9D49DF3C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-28 22:00:00.029285 :9D49DF3D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-28 22:00:00.029285 :9D49DF3E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-28 22:00:00.029285 :9D49DF3F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-28 22:00:00.029286 :9D49DF40:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-28 22:00:00.029355 :9D49DF41:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-28 22:00:00.029361 :9D49DF42:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:26:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 26 (osid: 12788, VKRM)