Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250503180843\orcl_vkrm_1156_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:37603M/63366M, Ph+PgF:45154M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 1156, image: ORACLE.EXE (VKRM) *** 2025-05-03 18:08:43.111 *** SESSION ID:(209.23177) 2025-05-03 18:08:43.111 *** 2025-05-03 18:08:43.111 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=1156, pid: 26, proc_ser: 172, sid: 209, sess_ser: 23177 ------------------------------------------------------------------------------- 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=728 min 43 sec, exc=728 min 43 sec, total=728 min 43 sec wait times: max=infinite, heur=728 min 43 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 209 serial 23177 --------------------------------------------------- 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:06:43 - 18:08:43] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-03 18:08:43.111 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 1156, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-03 05:55:39.958488 :91FE7EAA: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-03 05:55:39.959280 :91FE7EAC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:55:59.393529 :91FE84F9:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:55:59.381 2025-05-03 05:55:59.393530 :91FE84FA:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11452 pso_num=26 pso_serial#=146 2025-05-03 05:55:59.393668 :91FE84FB:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11452 (legacy spawn) 2025-05-03 05:55:59.400052 :91FE84FC: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-03 05:55:59.400232 :91FE8502: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-03 05:55:59.401032 :91FE8506:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:05.098237 :91FE86E2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:05.083 2025-05-03 05:56:05.098238 :91FE86E3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=2956 pso_num=26 pso_serial#=147 2025-05-03 05:56:05.098376 :91FE86E4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=2956 (legacy spawn) 2025-05-03 05:56:05.104862 :91FE86E5: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-03 05:56:05.105041 :91FE86EE: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-03 05:56:05.105816 :91FE86EF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:05.114468 :91FE86F2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:05.099 2025-05-03 05:56:05.114471 :91FE86F3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8072 pso_num=26 pso_serial#=148 2025-05-03 05:56:05.114611 :91FE86F4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=8072 (legacy spawn) 2025-05-03 05:56:05.134795 :91FE86F5: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-03 05:56:05.134963 :91FE86FE: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-03 05:56:05.135768 :91FE86FF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:08.293961 :91FE87F1:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:08.281 2025-05-03 05:56:08.293963 :91FE87F2:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12140 pso_num=26 pso_serial#=149 2025-05-03 05:56:08.294102 :91FE87F3:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12140 (legacy spawn) 2025-05-03 05:56:08.300546 :91FE87F4: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-03 05:56:08.300577 :91FE87F7: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-03 05:56:08.300719 :91FE87FB: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-03 05:56:08.301490 :91FE880B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:08.310152 :91FE880E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:08.297 2025-05-03 05:56:08.310155 :91FE880F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11868 pso_num=26 pso_serial#=150 2025-05-03 05:56:08.310292 :91FE8810:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11868 (legacy spawn) 2025-05-03 05:56:08.316802 :91FE8811: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-03 05:56:08.316975 :91FE881A: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-03 05:56:08.317801 :91FE881B:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:41.608437 :91FE9305:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:41.594 2025-05-03 05:56:41.608439 :91FE9306:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10096 pso_num=26 pso_serial#=151 2025-05-03 05:56:41.608579 :91FE9307:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10096 (legacy spawn) 2025-05-03 05:56:41.615017 :91FE9308: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-03 05:56:41.615195 :91FE930D: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-03 05:56:41.615990 :91FE9312:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:41.624618 :91FE9315:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:41.609 2025-05-03 05:56:41.624621 :91FE9316:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12152 pso_num=26 pso_serial#=152 2025-05-03 05:56:41.624753 :91FE9317:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12152 (legacy spawn) 2025-05-03 05:56:41.631133 :91FE9318: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-03 05:56:41.631290 :91FE9321: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-03 05:56:41.632141 :91FE9322:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:44.720904 :91FE9417:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:44.708 2025-05-03 05:56:44.720906 :91FE9418:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12168 pso_num=26 pso_serial#=153 2025-05-03 05:56:44.721039 :91FE9419:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=12168 (legacy spawn) 2025-05-03 05:56:44.727519 :91FE941A: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-03 05:56:44.727682 :91FE941F: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-03 05:56:44.728454 :91FE9424:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:44.747742 :91FE9427:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:44.739 2025-05-03 05:56:44.747744 :91FE9428:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11952 pso_num=26 pso_serial#=154 2025-05-03 05:56:44.747878 :91FE9429:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11952 (legacy spawn) 2025-05-03 05:56:44.754371 :91FE942A: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-03 05:56:44.754560 :91FE9433: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-03 05:56:44.755384 :91FE9434:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:56:48.441883 :91FE954B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:56:48.433 2025-05-03 05:56:48.441885 :91FE954C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11676 pso_num=26 pso_serial#=155 2025-05-03 05:56:48.442021 :91FE954D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11676 (legacy spawn) 2025-05-03 05:56:48.448490 :91FE954E: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-03 05:56:48.448670 :91FE9553: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-03 05:56:48.449465 :91FE9558:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:01.566026 :91FE9995:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:01.553 2025-05-03 05:57:01.566028 :91FE9996:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10164 pso_num=26 pso_serial#=156 2025-05-03 05:57:01.566169 :91FE9997:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10164 (legacy spawn) 2025-05-03 05:57:01.572563 :91FE9998: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-03 05:57:01.572740 :91FE99A1: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-03 05:57:01.573532 :91FE99A2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:02.855155 :91FE9A12:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:02.850 2025-05-03 05:57:02.855157 :91FE9A13:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=7912 pso_num=26 pso_serial#=157 2025-05-03 05:57:02.855293 :91FE9A14:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=7912 osp_idx=24 osp_ver=207216 osp_pg=0 (spawn #143056) 2025-05-03 05:57:02.855293 :91FE9A15:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-03 05:57:02.855294 :91FE9A16:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=97 time=3522612343 2025-05-03 05:57:02.855294 :91FE9A17:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 05:57:02.855294 :91FE9A18:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 05:57:02.855295 :91FE9A19:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 05:57:02.855295 :91FE9A1A:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 05:57:02.855295 :91FE9A1B:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 05:57:02.855802 :91FE9A1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-05-03 05:57:02.855848 :91FE9A22:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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-05-03 05:57:02.875683 :91FE9A7F:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:54.420674 :91FEABC6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:54.406 2025-05-03 05:57:54.420675 :91FEABC7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=316 pso_num=26 pso_serial#=158 2025-05-03 05:57:54.420809 :91FEABC8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=316 (legacy spawn) 2025-05-03 05:57:54.427256 :91FEABC9: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-03 05:57:54.427430 :91FEABCE: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-03 05:57:54.428220 :91FEABD3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:54.444283 :91FEABD6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:54.437 2025-05-03 05:57:54.444286 :91FEABD7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9460 pso_num=26 pso_serial#=159 2025-05-03 05:57:54.444441 :91FEABD8:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9460 (legacy spawn) 2025-05-03 05:57:54.450835 :91FEABD9: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-03 05:57:54.451016 :91FEABE2: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-03 05:57:54.451826 :91FEABE3:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:56.550551 :91FEAC9E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:56.537 2025-05-03 05:57:56.550553 :91FEAC9F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11472 pso_num=26 pso_serial#=160 2025-05-03 05:57:56.550683 :91FEACA0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11472 (legacy spawn) 2025-05-03 05:57:56.557162 :91FEACA1: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-03 05:57:56.557331 :91FEACA6: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-03 05:57:56.558098 :91FEACAB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:57.519045 :91FEACDE:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:57.506 2025-05-03 05:57:57.519047 :91FEACDF:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=3760 pso_num=26 pso_serial#=161 2025-05-03 05:57:57.519189 :91FEACE0:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=3760 (legacy spawn) 2025-05-03 05:57:57.525887 :91FEACE1: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-03 05:57:57.525917 :91FEACE4: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-03 05:57:57.526064 :91FEACE7: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-03 05:57:57.526850 :91FEACF8:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:57:57.535531 :91FEACFB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:57:57.522 2025-05-03 05:57:57.535533 :91FEACFC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11996 pso_num=26 pso_serial#=162 2025-05-03 05:57:57.535664 :91FEACFD:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11996 (legacy spawn) 2025-05-03 05:57:57.542112 :91FEACFE: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-03 05:57:57.542269 :91FEAD07: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-03 05:57:57.543050 :91FEAD08:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:58:03.711880 :91FEAEF2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:58:03.703 2025-05-03 05:58:03.711882 :91FEAEF3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11548 pso_num=26 pso_serial#=163 2025-05-03 05:58:03.712011 :91FEAEF4:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11548 (legacy spawn) 2025-05-03 05:58:03.718426 :91FEAEF5: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-03 05:58:03.718594 :91FEAEFA: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-03 05:58:03.719441 :91FEAEFF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:04.545043 :91FEC326:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:04.531 2025-05-03 05:59:04.545045 :91FEC327:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11748 pso_num=26 pso_serial#=164 2025-05-03 05:59:04.545183 :91FEC328:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11748 (legacy spawn) 2025-05-03 05:59:04.551651 :91FEC329: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-03 05:59:04.551814 :91FEC332: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-03 05:59:04.552631 :91FEC333:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:05.915363 :91FEC37B:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:05.906 2025-05-03 05:59:05.915365 :91FEC37C:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9484 pso_num=26 pso_serial#=165 2025-05-03 05:59:05.915502 :91FEC37D:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9484 (legacy spawn) 2025-05-03 05:59:05.921958 :91FEC37E: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-03 05:59:05.922128 :91FEC383: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-03 05:59:05.922916 :91FEC388:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:07.125162 :91FEC3BA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:07.111 2025-05-03 05:59:07.125164 :91FEC3BB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=10376 pso_num=26 pso_serial#=166 2025-05-03 05:59:07.125298 :91FEC3BC:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=10376 (legacy spawn) 2025-05-03 05:59:07.131729 :91FEC3BD: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-03 05:59:07.131907 :91FEC3C2: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-03 05:59:07.132716 :91FEC3C7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:07.141358 :91FEC3CA:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:07.126 2025-05-03 05:59:07.141360 :91FEC3CB:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11668 pso_num=26 pso_serial#=167 2025-05-03 05:59:07.141500 :91FEC3CC:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11668 (legacy spawn) 2025-05-03 05:59:07.147919 :91FEC3CD: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-03 05:59:07.148088 :91FEC3D6: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-03 05:59:07.148872 :91FEC3D7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:43.408224 :91FED056:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:43.394 2025-05-03 05:59:43.408226 :91FED057:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=9384 pso_num=26 pso_serial#=168 2025-05-03 05:59:43.408358 :91FED058:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=9384 (legacy spawn) 2025-05-03 05:59:43.414750 :91FED059: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-03 05:59:43.414918 :91FED062: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-03 05:59:43.415711 :91FED063:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:43.424269 :91FED066:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:43.409 2025-05-03 05:59:43.424271 :91FED067:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11752 pso_num=26 pso_serial#=169 2025-05-03 05:59:43.424403 :91FED068:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11752 (legacy spawn) 2025-05-03 05:59:43.430907 :91FED069: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-03 05:59:43.431073 :91FED072: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-03 05:59:43.431879 :91FED073:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:46.509542 :91FED167:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:46.496 2025-05-03 05:59:46.509544 :91FED168:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11540 pso_num=26 pso_serial#=170 2025-05-03 05:59:46.509680 :91FED169:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11540 (legacy spawn) 2025-05-03 05:59:46.530208 :91FED16A: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-03 05:59:46.530382 :91FED173: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-03 05:59:46.531226 :91FED174:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 05:59:46.539815 :91FED177:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 05:59:46.527 2025-05-03 05:59:46.539817 :91FED178:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=11988 pso_num=26 pso_serial#=171 2025-05-03 05:59:46.539947 :91FED179:db_trace:kso.c@4070:ksonfy(): [10420:26:0] kso: new process: pid=11988 (legacy spawn) 2025-05-03 05:59:46.546393 :91FED17A: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-03 05:59:46.546554 :91FED183: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-03 05:59:46.547337 :91FED184:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-05-03 06:00:00.017244 :91FED5BD:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 05-03 06:00:00.010 2025-05-03 06:00:00.017246 :91FED5BE:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=1156 pso_num=26 pso_serial#=172 2025-05-03 06:00:00.017357 :91FED5BF:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=1156 osp_idx=24 osp_ver=207231 osp_pg=0 (spawn #143064) 2025-05-03 06:00:00.017357 :91FED5C0: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-03 06:00:00.017358 :91FED5C1:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=98 time=3522789500 2025-05-03 06:00:00.017358 :91FED5C2:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-05-03 06:00:00.017359 :91FED5C3:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-05-03 06:00:00.017359 :91FED5C4:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-05-03 06:00:00.017359 :91FED5C5:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-03 06:00:00.017360 :91FED5C6:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-03 06:00:00.017430 :91FED5C7: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-03 06:00:00.017436 :91FED5C8: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: 1156, VKRM)