11.2.0.3 Linux X86-64 VKTM問題處理

今早在新作的一個11.2.0.3 Linux X86-64 Standby DB的預警文件中發現有規律的報警信息

Wed Dec 21 22:30:19 2011
Time drift detected. Please check VKTM trace file for more details.
Wed Dec 21 23:30:21 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 00:30:22 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 01:30:23 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 02:30:24 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 03:30:25 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 04:30:26 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 05:30:27 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 06:03:11 2011
RFS[4]: Selected log 5 for thread 1 sequence 121 dbid 2107288066 branch 770032517
Thu Dec 22 06:03:11 2011
Media Recovery Waiting for thread 1 sequence 121 (in transit)
Recovery of Online Redo Log: Thread 1 Group 5 Seq 121 Reading mem 0
  Mem# 0: /u01/product/oradata/wmsdb/standby_log5.log
Thu Dec 22 06:03:11 2011
Archived Log entry 22 added for thread 1 sequence 120 ID 0x7d9a3902 dest 1:
Thu Dec 22 06:30:28 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 07:30:30 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 08:30:31 2011
Time drift detected. Please check VKTM trace file for more details.
Thu Dec 22 09:30:32 2011
Time drift detected. Please check VKTM trace file for more details.

看Trace文件,發現和Bug 11837095: "TIME DRIFT DETECTED" APPEARS INTERMITTENTLY IN ALERT LOG, THO' EVENT 10795 SET相當像
          
             

Bug 11837095: "TIME DRIFT DETECTED" APPEARS INTERMITTENTLY IN ALERT LOG, THO' EVENT 10795 SET

[hr]

        Bug 属性
       
           [hr]                                                  
                               
                               
                               
                               
                               
                               
                               
               
类型B - Defect已在产品版本中修复-
严重性2 - Severe Loss of Service产品版本11.2.0.2
状态11 - Code Bug (Response/Resolution)平台226 - Linux x86-64
创建时间04-Mar-2011平台版本NO DATA
更新时间08-Dec-2011基本 Bug-
数据库版本11.2.0.2

影响平台 Generic

产品源Oracle

       相关产品
      
               [hr]                                                                                                                                                     

               
产品线Oracle Database Products系列Oracle Database
区域Oracle Database产品5 - Oracle Server - Enterprise Edition
Hdr: 11837095 11.2.0.2 RDBMS 11.2.0.2 VOS COMP SVCS PRODID-5 PORTID-226
Abstract: "TIME DRIFT DETECTED" APPEARS INTERMITTENTLY IN ALERT LOG, THO' EVENT 10795 SET

*** 03/04/11 02:59 pm ***
*** 03/06/11 05:10 pm ***
*** 03/06/11 05:50 pm *** (CHG: Sta->11)
*** 03/06/11 05:50 pm ***
*** 03/06/11 05:50 pm ***
*** 03/06/11 05:50 pm ***
*** 04/17/11 05:55 pm ***
*** 04/19/11 08:36 pm ***
*** 05/10/11 03:57 am ***
*** 05/11/11 07:38 am ***
*** 05/11/11 07:38 am ***
*** 05/12/11 11:08 am ***
*** 05/12/11 11:08 am ***
*** 05/16/11 06:01 am ***
*** 08/02/11 06:35 am ***
*** 08/02/11 07:33 am ***
*** 08/02/11 08:20 pm ***
*** 08/03/11 12:40 am ***
Thanks a lot for your update, Much appreciated.
*** 09/01/11 09:28 pm ***
*** 10/28/11 02:38 am ***
*** 11/15/11 04:36 am ***
*** 11/15/11 04:38 am ***
*** 11/15/11 04:38 am ***
*** 11/15/11 04:38 am ***
*** 11/15/11 05:23 am ***
*** 11/15/11 05:23 am ***
*** 11/18/11 05:59 am ***
*** 12/08/11 09:41 am ***
返回页首

這個Bug沒有帶Trace文件,而Windows 64 Bit平台下另外一個Bug 13260973: TIME DRIFT DETECED IN ALERT.LOG WITH EVENT 10795 ENABLED
與它相關聯,Trace文件能對起來          
             

Bug 13260973: TIME DRIFT DETECED IN ALERT.LOG WITH EVENT 10795 ENABLED

[hr]

        Bug 属性
       
           [hr]                                                  
                               
                               
                               
                               
                               
                               
                               
               
类型B - Defect已在产品版本中修复-
严重性2 - Severe Loss of Service产品版本11.2.0.2
状态36 - Duplicate Bug.  To Filer平台233 - Microsoft Windows x64 (64-bit)
创建时间27-Oct-2011平台版本2008 R2
更新时间24-Nov-2011基本 Bug11837095
数据库版本11.2.0.2

影响平台 Generic

产品源Oracle

       相关产品
      
               [hr]                                                                                                                                                     

               
产品线Oracle Database Products系列Oracle Database
区域Oracle Database产品5 - Oracle Server - Enterprise Edition
Hdr: 13260973 11.2.0.2 RDBMS 11.2.0.2 VOS COMP SVCS PRODID-5 PORTID-233 11837095
Abstract: TIME DRIFT DETECED IN ALERT.LOG WITH EVENT 10795 ENABLED

*** 10/27/11 03:50 am ***
  
  
  BUG TYPE CHOSEN
  ===============
  Code
  
  SubComponent: VOS Compile Time Services
  =======================================
  DETAILED PROBLEM DESCRIPTION
  ============================
  Found the following in the alert log file :
  Time drift detected. Please check VKTM trace file for more details.
  Trace file is filled with the following:
  kstmmainvktm: succeeded in setting elevated priority
  highres_enabled
  
  *** 2011-03-01 17:19:29.321
  VKTM running at (10)millisec precision with DBRM quantum (100)ms
  [Start] HighResTick = 248456066
  kstmrmtickcnt = 0 : ksudbrmseccnt[0] = 1298996369
  kstmchkdrift (kstmhighrestimecntkeeper:lowres): Time stalled at 1299001603
  
  *** 2011-03-01 18:46:45.001
  kstmchkdrift (kstmhighrestimecntkeeper:lowres): Stall, backward drift ended
  at 1299001605 drift: 2
  kstmchkdrift (kstmhighrestimecntkeeper:lowres): Time stalled at 1299078529
  
  DIAGNOSTIC ANALYSIS
  ===================
  Performed the following to set the parameter:
  alter system set event="10795 trace name context forever, level 2"
  scope=spfile;
  then shutdown and startup the database.
  However, the error message and trace files are still being generated.
  
  WORKAROUND?
  ===========
  No
  
  TECHNICAL IMPACT
  ================
  Trace files consume disk space
  
  RELATED ISSUES (bugs, forums, RFAs)
  ===================================
  Bug 11837095 - "TIME DRIFT DETECTED" APPEARS INTERMITTENTLY IN ALERT LOG,
  THO' EVENT 10795 SET
  
  HOW OFTEN DOES THE ISSUE REPRODUCE AT CUSTOMER SITE?
  ====================================================
  Always
  
  DOES THE ISSUE REPRODUCE INTERNALLY?
  ====================================
  Not attempted
  
  EXPLAIN WHY THE ISSUE WAS NOT TESTED INTERNALLY.
  ================================================
  Not Feasible.
  Not sure how can it be reproduced in-house
  
  IS A TESTCASE AVAILABLE?
  ========================
  No
  
  Link to IPS Package:
  ====================
  not available
  
*** 10/27/11 05:28 am ***
*** 10/27/11 05:41 am ***
*** 10/27/11 05:41 am *** (CHG: Sta->10)
*** 10/27/11 05:41 am ***
*** 10/28/11 02:18 am *** (CHG: Sta->16)
*** 10/28/11 02:18 am ***
*** 10/28/11 02:36 am ***
*** 10/28/11 02:38 am *** (CHG: Base Bug-> NULL -> 11837095)
*** 10/28/11 02:38 am *** (CHG: Sta->11)
*** 10/28/11 02:38 am ***
*** 10/28/11 02:38 am ***
*** 10/28/11 02:38 am ***
*** 11/21/11 02:17 am ***
*** 11/21/11 02:17 am ***
*** 11/24/11 12:41 am ***
*** 11/24/11 12:45 am *** (CHG: Sta->36)
*** 11/24/11 12:45 am ***
返回页首

看metalink上另外一個Solaris X86-64Bit下的 Bug 13371247: TIME DRIFT DETECTED. PLEASE CHECK VKTM TRACE FILE FOR MORE DETAILS
說設置10795事件只能屏蔽掉trace文件的生成,Bug 13371247又與Bug 13260973類似

event="10795 trace name context forever, level 2"
Oracle對VKTM後台進程解釋如下:

VKTM (virtual keeper of time) is responsible for providing a wall-clock time
(updated every second) and reference-time counter (updated every 20 ms and
available only when running at elevated priority).



猜測這個Bug可能是在所有平台上都有,而我們的機器每到整點與半點就與NTP Server對時,有可能Time Difference差異大,所以觸發這個Bug

在Alert中這個錯誤信息出現的相當有規律,每到半點就報一次,暫時改一下crontab排程,改成15分鐘對一次時再繼續觀察





请使用浏览器的分享功能分享到微信等