oracle standby logfile,网络故障造成备库standby logfile的损坏

某生产库exadata,oracle 11.2.0.4,客户说物理备库与主库不能进行同步，如是要客户把生产库与备库的alert_sid.log文件打包传给我。查看了一个主库的alert.log日志文件发现从15号开始网络就断断续续的，信息如下:

Wed Jun 15 14:25:48 2016

***********************************************************************

Fatal NI connect error 12543, connecting to:

(DESCRIPTION=(ADDRESS_LIST=(ADDRESS=(PROTOCOL=TCP)(HOST=10.137.8.101)(PORT=1521)))

(CONNECT_DATA=(SERVICE_NAME=syyjm)(CID=(PROGRAM=oracle)(HOST=yb01dbadm01.dnsserver)

(USER=oracle))))

VERSION INFORMATION:

TNS for Linux: Version 11.2.0.4.0 - Production

TCP/IP NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

Time: 15-JUN-2016 14:25:48

Tracing not turned on.

Tns error struct:

ns main err code: 12543

TNS-12543: TNS:destination host unreachable

ns secondary err code: 12560

nt main err code: 513

TNS-00513: Destination host unreachable

nt secondary err code: 113

nt OS err code: 0

***********************************************************************

Fatal NI connect error 12543, connecting to:

(DESCRIPTION=(ADDRESS_LIST=(ADDRESS=(PROTOCOL=TCP)(HOST=10.137.8.101)(PORT=1521)))

(CONNECT_DATA=(SERVICE_NAME=syyjm)(CID=(PROGRAM=oracle)(HOST=yb01dbadm01.dnsserver)

(USER=oracle))))

VERSION INFORMATION:

TNS for Linux: Version 11.2.0.4.0 - Production

TCP/IP NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

Time: 15-JUN-2016 14:25:51

Tracing not turned on.

Tns error struct:

ns main err code: 12543

TNS-12543: TNS:destination host unreachable

ns secondary err code: 12560

nt main err code: 513

TNS-00513: Destination host unreachable

nt secondary err code: 113

nt OS err code: 0

Wed Jun 15 14:26:02 2016

...省略...

对应的备库这个时间点的alert.log日志文件信息如下:

Wed Jun 15 14:24:47 2016

***********************************************************************

Fatal NI connect error 12170.

Wed Jun 15 14:24:47 2016

***********************************************************************

VERSION INFORMATION:

TNS for Linux: Version 11.2.0.4.0 - Production

Oracle Bequeath NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

TCP/IP NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

Fatal NI connect error 12170.

Time: 15-JUN-2016 14:24:47

VERSION INFORMATION:

TNS for Linux: Version 11.2.0.4.0 - Production

Oracle Bequeath NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

TCP/IP NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

Tracing not turned on.

Time: 15-JUN-2016 14:24:47

Tns error struct:

Tracing not turned on.

ns main err code: 12535

Tns error struct:

ns main err code: 12535

TNS-12535: TNS:operation timed out

TNS-12535: TNS:operation timed out

ns secondary err code: 12560

ns secondary err code: 12560

nt main err code: 505

nt main err code: 505

TNS-00505: Operation timed out

TNS-00505: Operation timed out

nt secondary err code: 110

nt secondary err code: 110

nt OS err code: 0

nt OS err code: 0

Client address: (ADDRESS=(PROTOCOL=tcp)(HOST=10.137.8.73)(PORT=12282))

Client address: (ADDRESS=(PROTOCOL=tcp)(HOST=10.137.8.73)(PORT=12091))

RFS[4]: Possible network disconnect with primary database

RFS[5]: Possible network disconnect with primary database

Wed Jun 15 14:30:55 2016

Killing 1 processes with pids 25908 (idle RFS by thread/sequence) in order to allow the

in-transit gap to be queued. Requested by OS process 58349

Wed Jun 15 14:30:57 2016

RFS[10]: Assigned to RFS process 58352

RFS[10]: Selected log 8 for thread 1 sequence 40085 dbid 643576469 branch 867103448

Wed Jun 15 14:30:58 2016

Archived Log entry 3997 added for thread 1 sequence 40085 ID 0x265be592 dest 1:

Wed Jun 15 14:30:59 2016

Primary database is in MAXIMUM PERFORMANCE mode

RFS[11]: Assigned to RFS process 58363

RFS[11]: Selected log 7 for thread 1 sequence 40087 dbid 643576469 branch 867103448

RFS[10]: Selected log 8 for thread 1 sequence 40086 dbid 643576469 branch 867103448

Wed Jun 15 14:31:01 2016

Killing 1 processes with pids 25910 (idle RFS by thread/sequence) in order to retry

receiving a log after reattaching. Requested by OS process 58367

Wed Jun 15 14:31:01 2016

Archived Log entry 3998 added for thread 1 sequence 40086 ID 0x265be592 dest 1:

RFS[12]: Assigned to RFS process 58367

RFS[12]: Selected log 10 for thread 2 sequence

35718 dbid 643576469 branch 867103448

从信息RFS[4]: Possible network disconnect with primary database可以看到备库不能与主库进行连接。从信息Killing 1 processes with pids 25908 (idle RFS by thread/sequence) in order to allow the in-transit gap to be queued. Requested by OS process 58349，可知因为不能从主库接受归档日志，且超时而被进程58349给kill掉了，而58349进程是另一个RFS进程用于接受主库所传输过来的归档日志。也就是说，因为网络连接出现了问题，RFS进程在不断的kill，restart重复这样的操作

在2016-06-15 14:34:25这个时间点由于网络恢复日志传输恢复正常,但在2016-05-15 16:59:00这个时间点网络又断了，不能进行日志传输

Wed Jun 15 14:34:25 2016

ARC3: Standby redo logfile selected for thread 1 sequence 40085 for destination

LOG_ARCHIVE_DEST_2

Wed Jun 15 14:34:27 2016

Thread 1 advanced to log sequence 40087 (LGWR switch)

Current log# 1 seq# 40087 mem# 0: +DATAC1/yyjm/onlinelog/group_1.261.867103449

Wed Jun 15 14:34:27 2016

Archived Log entry 139166 added for thread 1 sequence 40086 ID 0x265be592 dest 1:

Wed Jun 15 14:34:27 2016

LNS: Standby redo logfile selected for thread 1 sequence 40087 for destination

LOG_ARCHIVE_DEST_2

ARC3: Standby redo logfile selected for thread 1 sequence 40086 for destination

LOG_ARCHIVE_DEST_2

Wed Jun 15 15:58:19 2016

Thread 1 advanced to log sequence 40088 (LGWR switch)

Current log# 2 seq# 40088 mem# 0: +DATAC1/yyjm/onlinelog/group_2.262.867103449

Wed Jun 15 15:58:19 2016

LNS: Standby redo logfile selected for thread 1 sequence 40088 for destination

LOG_ARCHIVE_DEST_2

Wed Jun 15 15:58:20 2016

Archived Log entry 139173 added for thread 1 sequence 40087 ID 0x265be592 dest 1:

Wed Jun 15 16:59:00 2016

***********************************************************************

Fatal NI connect error 12170.

VERSION INFORMATION:

TNS for Linux: Version 11.2.0.4.0 - Production

Oracle Bequeath NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

TCP/IP NT Protocol Adapter for Linux: Version 11.2.0.4.0 - Production

Time: 15-JUN-2016 16:59:00

Tracing not turned on.

Tns error struct:

ns main err code: 12535

TNS-12535: TNS:operation timed out

ns secondary err code: 12560

nt main err code: 505

TNS-00505: Operation timed out

nt secondary err code: 110

nt OS err code: 0

Client address: (ADDRESS=(PROTOCOL=tcp)(HOST=10.137.32.4)(PORT=1222))

Wed Jun 15 17:05:01 2016

从备库日志信息来看，备库其实在2016-06-15 16:08:43这个时间点网络就已经断开了

Wed Jun 15 16:08:43 2016

RFS[9]: Possible network disconnect with primary database

Wed Jun 15 16:08:43 2016

RFS[6]: Possible network disconnect with primary database

Wed Jun 15 17:56:36 2016

这个DG环境的网络时断时连的情况从2016-06-15一直到2016-06-19号，从备库的日志信息可以看到如下信息，不能在备库创建归档日志文件，从信息RFS[23]: No standby redo logfiles available for thread 1 与RFS[24]: No standby redo logfiles available for thread 2，以及ORA-00312: online log 9 thread 1: '/data/syyjm/datafile/standy03'可知因为备库的standby log的standy03出现了损坏。

Sun Jun 19 12:15:21 2016

RFS[23]: Assigned to RFS process 18969

RFS[23]: No standby redo logfiles available for thread 1

Creating archive destination file : /arch/syyjm/1_40277_867103448.dbf (12905 blocks)

Sun Jun 19 12:15:56 2016

Archiver process freed from errors. No longer stopped

Sun Jun 19 12:15:56 2016

Primary database is in MAXIMUM PERFORMANCE mode

Re-archiving standby log 11 thread 2 sequence 36153

RFS[24]: Assigned to RFS process 18976

RFS[24]: No standby redo logfiles available for thread 2

...省略...

Sun Jun 19 12:21:03 2016

Unable to create archive log file '/arch/syyjm/1_40254_867103448.dbf'

ARC3: Error 19504 Creating archive log file to '/arch/syyjm/1_40254_867103448.dbf'

ARCH: Archival stopped, error occurred. Will continue retrying

ORACLE Instance syyjm - Archival Error

ORA-16038: log 9 sequence# 40254 cannot be archived

ORA-19504: failed to create file ""

ORA-00312: online log 9 thread 1: '/data/syyjm/datafile/standy03'

Sun Jun 19 12:21:03 2016

...省略...

Sun Jun 19 12:31:03 2016

Suppressing further error logging of LOG_ARCHIVE_DEST_1.

Suppressing further error logging of LOG_ARCHIVE_DEST_1.

ARCH: Archival stopped, error occurred. Will continue retrying

ORACLE Instance syyjm - Archival Error

ORA-16038: log 9 sequence# 40254 cannot be archived

ORA-19504: failed to create file ""

ORA-00312: online log 9 thread 1: '/data/syyjm/datafile/standy03'

Sun Jun 19 12:31:03 2016

ARCH: Archival stopped, error occurred. Will continue retrying

ORACLE Instance syyjm - Archival Error

ORA-16014: log 9 sequence# 40254 not archived, no available destinations

ORA-00312: online log 9 thread 1: '/data/syyjm/datafile/standy03'

...省略...

Wed Jun 22 11:22:11 2016

ARCH: Archival stopped, error occurred. Will continue retrying

ORACLE Instance syyjm - Archival Error

ORA-16038: log 9 sequence# 40254 cannot be archived

ORA-19504: failed to create file ""

ORA-00312: online log 9 thread 1: '/data/syyjm/datafile/standy03'

Wed Jun 22 11:22:11 2016

ARCH: Archival stopped, error occurred. Will continue retrying

ORACLE Instance syyjm - Archival Error

ORA-16014: log 9 sequence# 40254 not archived, no available destinations

ORA-00312: online log 9 thread 1: '/data/syyjm/datafile/standy03'

从主库alert日志文件也可以看到与不能进行对远程目录进行归档操作

Wed Jun 22 11:05:22 2016

Thread 1 advanced to log sequence 41141 (LGWR switch)

Current log# 2 seq# 41141 mem# 0: +DATAC1/yyjm/onlinelog/group_2.262.867103449

FAL[server, ARC0]: FAL archive failed, see trace file.

ARCH: FAL archive failed. Archiver continuing

ORACLE Instance yyjm1 - Archival Error. Archiver continuing.

FAL[server, ARC1]: FAL archive failed, see trace file.

ARCH: FAL archive failed. Archiver continuing

ORACLE Instance yyjm1 - Archival Error. Archiver continuing.

Wed Jun 22 11:05:23 2016

Archived Log entry 142098 added for thread 1 sequence 41140 ID 0x265be592 dest 1:

通过主库与备库的alert.log文件信息可以清楚的知道原因是因为DG环境的网络时断时连引起了备库的RFS进程在重复kill,restart操作过程中损坏了standby log文件，解决方法很简单就是重建备库被损不的standby log文件。