ADG歸檔不傳輸

環境說明

OS：Red Hat Enterprise Linux Server release 6.5 (Santiago)

資料庫版本：SQL*Plus: Release 11.2.0.4.0 Production

背景介紹

測試使用的一套單機ADG，早上到單位之後開啟主庫並啟動備庫報錯，從以下報錯資訊來看提示datafile 1需要介質恢復

SQL> startup
ORACLE instance started.
Total System Global Area 1027182592 bytes
Fixed Size                  2259680 bytes
Variable Size             864027936 bytes
Database Buffers          155189248 bytes
Redo Buffers                5705728 bytes
Database mounted.
ORA-10458: standby database requires recovery
ORA-01196: file 1 is inconsistent due to a failed media recovery session
ORA-01110: data file 1: '/oradata/orcl/system01.dbf'

檢視對應備庫的alert日誌資訊發現如下報錯：ORA-16016,歸檔27號檔案沒有傳輸到本節點

alter database open
Beginning Standby Crash Recovery.
Serial Media Recovery started
Managed Standby Recovery starting Real Time Apply
Media Recovery Log /oradata/arch/1_26_1086360874.dbf
Media Recovery of Online Log [Thread=1, Seq=27]
Recovery of Online Redo Log: Thread 1 Group 21 Seq 27 Reading mem 0
  Mem# 0: /oradata/orcl/sr121.log
Tue Mar 01 09:00:14 2022
Standby crash recovery failed to bring standby database to a consistent
point because needed redo hasn't arrived yet.
MRP: Wait timeout: thread 1 sequence# 27
Standby Crash Recovery aborted due to error 16016.
Errors in file /u01/app/oracle/diag/rdbms/orcldg/orcl/trace/orcl_ora_5275.trc:
ORA-16016: archived log for thread 1 sequence# 27 unavailable
Recovery interrupted!
Some recovered datafiles maybe left media fuzzy
Media recovery may continue but open resetlogs may fail
Completed Standby Crash Recovery.
Errors in file /u01/app/oracle/diag/rdbms/orcldg/orcl/trace/orcl_ora_5275.trc:
ORA-10458: standby database requires recovery
ORA-01196: file 1 is inconsistent due to a failed media recovery session
ORA-01110: data file 1: '/oradata/orcl/system01.dbf'
ORA-10458 signalled during: alter database open...
Tue Mar 01 09:02:48 2022

檢視主庫是否有27號歸檔日誌，這裡發現從27號歸檔開始就已經不傳輸到備庫

[oracle@oracle11g arch]$ ls -lrt
total 27688
-rw-r----- 1 oracle oinstall 21533184 Feb 28 22:18 1_27_1086360874.dbf
-rw-r----- 1 oracle oinstall    66048 Feb 28 22:18 1_28_1086360874.dbf
-rw-r----- 1 oracle oinstall  6461440 Mar  1 08:57 1_29_1086360874.dbf
-rw-r----- 1 oracle oinstall    91648 Mar  1 08:57 1_30_1086360874.dbf
-rw-r----- 1 oracle oinstall    86016 Mar  1 09:00 1_31_1086360874.dbf
-rw-r----- 1 oracle oinstall   102400 Mar  1 09:03 1_32_1086360874.dbf

檢查監聽以及資料庫配置是否正確，經檢查所有的配置資訊都沒有問題，包括密碼檔案

問題解決處理

臨時修改log_archive_dest_state_2引數為defer，並立即修改為enable

SQL> alter system set log_archive_dest_state_2=defer;
System altered.
SQL> alter system set log_archive_dest_state_2=enable;
System altered.

檢視主庫alert日誌資訊

Tue Mar 01 09:02:51 2022
Starting background process SMCO
Tue Mar 01 09:02:51 2022
SMCO started with pid=28, OS id=4349 
Tue Mar 01 09:03:51 2022
ARC0: Standby redo logfile selected for thread 1 sequence 27 for destination LOG_ARCHIVE_DEST_2
Tue Mar 01 09:03:53 2022
Thread 1 advanced to log sequence 33 (LGWR switch)
  Current log# 3 seq# 33 mem# 0: /oradata/orcl/redo03.log
Tue Mar 01 09:03:53 2022
Archived Log entry 44 added for thread 1 sequence 32 ID 0x603645aa dest 1:
ARC0: Standby redo logfile selected for thread 1 sequence 32 for destination LOG_ARCHIVE_DEST_2
Tue Mar 01 09:03:53 2022
******************************************************************
LGWR: Setting 'active' archival for destination LOG_ARCHIVE_DEST_2
******************************************************************
LNS: Standby redo logfile selected for thread 1 sequence 33 for destination LOG_ARCHIVE_DEST_2
Tue Mar 01 09:05:07 2022
Using STANDBY_ARCHIVE_DEST parameter default value as /oradata/arch/
ALTER SYSTEM SET log_archive_dest_state_2='DEFER' SCOPE=BOTH;
ALTER SYSTEM SET log_archive_dest_state_2='ENABLE' SCOPE=BOTH;
Tue Mar 01 09:05:17 2022
Thread 1 advanced to log sequence 34 (LGWR switch)
  Current log# 1 seq# 34 mem# 0: /oradata/orcl/redo01.log
Tue Mar 01 09:05:17 2022
Archived Log entry 46 added for thread 1 sequence 33 ID 0x603645aa dest 1:
Tue Mar 01 09:05:17 2022
******************************************************************
LGWR: Setting 'active' archival for destination LOG_ARCHIVE_DEST_2
******************************************************************
LNS: Standby redo logfile selected for thread 1 sequence 34 for destination LOG_ARCHIVE_DEST_2

檢視備庫alert日誌資訊，從庫alert日誌資訊中可以看到歸檔27號檔案之後都已經傳輸到備庫並且應用

Tue Mar 01 09:03:51 2022
RFS[1]: Assigned to RFS process 7916
RFS[1]: Opened log for thread 1 sequence 29 dbid 1614141610 branch 1086360874
Tue Mar 01 09:03:51 2022
RFS[2]: Assigned to RFS process 7918
RFS[2]: Opened log for thread 1 sequence 28 dbid 1614141610 branch 1086360874
Archived Log entry 17 added for thread 1 sequence 28 rlc 1086360874 ID 0x603645aa dest 2:
RFS[2]: Opened log for thread 1 sequence 30 dbid 1614141610 branch 1086360874
Archived Log entry 18 added for thread 1 sequence 29 rlc 1086360874 ID 0x603645aa dest 2:
Tue Mar 01 09:03:51 2022
RFS[3]: Assigned to RFS process 7920
RFS[3]: Selected log 21 for thread 1 sequence 27 dbid 1614141610 branch 1086360874
Archived Log entry 19 added for thread 1 sequence 30 rlc 1086360874 ID 0x603645aa dest 2:
RFS[1]: Opened log for thread 1 sequence 31 dbid 1614141610 branch 1086360874
Archived Log entry 20 added for thread 1 sequence 31 rlc 1086360874 ID 0x603645aa dest 2:
Tue Mar 01 09:03:51 2022
Archived Log entry 21 added for thread 1 sequence 27 ID 0x603645aa dest 1:
RFS[3]: Selected log 21 for thread 1 sequence 32 dbid 1614141610 branch 1086360874
Tue Mar 01 09:03:53 2022
Archived Log entry 22 added for thread 1 sequence 32 ID 0x603645aa dest 1:
Tue Mar 01 09:03:53 2022
Primary database is in MAXIMUM PERFORMANCE mode
RFS[4]: Assigned to RFS process 8028
RFS[4]: Selected log 21 for thread 1 sequence 33 dbid 1614141610 branch 1086360874
Tue Mar 01 09:05:17 2022
Archived Log entry 23 added for thread 1 sequence 33 ID 0x603645aa dest 1:
Tue Mar 01 09:05:17 2022
Primary database is in MAXIMUM PERFORMANCE mode
RFS[5]: Assigned to RFS process 8508
RFS[5]: Selected log 21 for thread 1 sequence 34 dbid 1614141610 branch 1086360874