Subscribe

SMO 3.4.1 Restore from secondary

Hi @ll

 

I have an issue/question with SnapManager for Oracle and the restore from secondary storage (Restore Option, Restore to specific time). 

 

The restore is aborting due to missing archive logs. It is able to mount the snapshots and is able to see the files but anyhow the restore process is interrupted. As soon i apply the missing archivelogs by hand from the same Snapshot i can bring up the database.

 

Here you can see the part of the log:

 

--[ INFO] DS-10006: Finished restore of [/ORATEST/sqllims_multimedia.dbf, /ORATEST/undotbs01.dbf, /ORATEST/sqllims1.dbf, /ORATEST/system01.dbf, /ORATEST/sqllims_qa.dbf, /ORATEST/sqllims_index.dbf, /ORATEST/statspack01.dbf, /ORATEST/users01.dbf, /ORATEST/sysaux01.dbf, /ORATEST/rman01.dbf, /ORATEST/BALVI_CFG_1.DBF, /ORATEST/ILAB_DATA.DBF, /ORATEST/BALVI_INDEX_1.DBF] from copy 1107731 of backup 1472677485 of dataset smo_fziorasrv5.bl.ch_oratest.

--[ INFO] SMO-03064: Beginning Recovery

--[ INFO] SMO-03062: Perform automatic recovery from active file system

--[ INFO] ORACLE-30023: Beginning batch recovery process for database ORATEST.

--[ INFO] ORACLE-30009: Recovery will be performed using backup controlfile.

--[ INFO] ORACLE-30008: Beginning recovery process for database ORATEST.

--[ INFO] ORACLE-30010: Database recovery point objective: date 2016-08-31:23:06:18

--[ WARN] ORACLE-30003: Archived log file "/ORATESTLOG/arch1_7549_901731542.dbf" containing change 51247246 for thread 1 is not available.  Media recovery halted at change 51247246.

--[ WARN] ORACLE-30003: Archived log file "/ORATESTLOG/arch1_7549_901731542.dbf" containing change 51247246 for thread 1 is not available.  Media recovery halted at change 51247246.

--[ WARN] ORACLE-30027: Archived log file "/ORATESTLOG/arch1_7549_901731542.dbf" containing change 51247246 for thread 1 is required for completing recovery.

--[ WARN] ORACLE-30018: Recovery point objective of date 2016-08-31:23:06:18 not reached.  Required archived/online redo logs may be missing from the filesystem.

--[ INFO] ORACLE-30024: Finished batch recovery process for database ORATEST.

--[ INFO] SMO-03056: Archived log file for thread 1 and change 51247246 not found in the active file system.

--[ INFO] SMO-03057: Archived log file for thread 1 and change 51247246 found in the backup L_H_20160831230347CEST.

--[ INFO] SMO-03054: Mounting backup L_H_20160831230347CEST on host fziorasrv5.bl.ch to feed archivelogs.

--[ INFO] DS-10001: Connecting mountpoints [/ORATESTLOG] from snapshot smo_oratest_oratest_d_h_3_8a8ccb0356e2677f0156e267abee0001_0 in copy 1107718 of backup 1472677457 of dataset smo_fziorasrv5.bl.ch_oratest_logs.

--[ INFO] SD-00016: Discovering storage resources for /opt/NetApp/smo/mnt/-ORATESTLOG-20160930085523762_0.

--[ INFO] SD-00017: Finished storage discovery for /opt/NetApp/smo/mnt/-ORATESTLOG-20160930085523762_0.

--[ INFO] DS-10002: Connected mountpoints [/ORATESTLOG] at [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085523762_0].

--[ INFO] SMO-03061: Perform recovery using backup L_H_20160831230347CEST

--[ INFO] SMO-03081: Perform recovery using all connected backups L_H_20160831230347CEST

--[ INFO] ORACLE-30023: Beginning batch recovery process for database ORATEST.

--[ INFO] ORACLE-30008: Beginning recovery process for database ORATEST.

--[ INFO] ORACLE-30010: Database recovery point objective: date 2016-08-31:23:06:18

--[ WARN] ORACLE-30027: Archived log file "/ORATESTLOG/arch1_7550_901731542.dbf" containing change 51247269 for thread 1 is required for completing recovery.

--[ INFO] ORACLE-30024: Finished batch recovery process for database ORATEST.

--[ INFO] SMO-03055: Disconnecting backup L_H_20160831230347CEST.

--[ INFO] SD-00031: Beginning to disconnect filesystem(s) [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085523762_0].

--[ INFO] SD-00032: Finished disconnecting filesystem(s) [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085523762_0].

--[ INFO] SMO-03069: Retrying automatic recovery for redo log file for thread 1 and change 51247269.

--[ INFO] SMO-03062: Perform automatic recovery from active file system

--[ INFO] ORACLE-30023: Beginning batch recovery process for database ORATEST.

--[ INFO] ORACLE-30009: Recovery will be performed using backup controlfile.

--[ INFO] ORACLE-30008: Beginning recovery process for database ORATEST.

--[ INFO] ORACLE-30010: Database recovery point objective: date 2016-08-31:23:06:18

--[ WARN] ORACLE-30003: Archived log file "/ORATESTLOG/arch1_7550_901731542.dbf" containing change 51247269 for thread 1 is not available.  Media recovery halted at change 51247269.

--[ WARN] ORACLE-30003: Archived log file "/ORATESTLOG/arch1_7550_901731542.dbf" containing change 51247269 for thread 1 is not available.  Media recovery halted at change 51247269.

--[ WARN] ORACLE-30027: Archived log file "/ORATESTLOG/arch1_7550_901731542.dbf" containing change 51247269 for thread 1 is required for completing recovery.

--[ WARN] ORACLE-30018: Recovery point objective of date 2016-08-31:23:06:18 not reached.  Required archived/online redo logs may be missing from the filesystem.

--[ INFO] ORACLE-30024: Finished batch recovery process for database ORATEST.

--[ INFO] SMO-03069: Retrying automatic recovery for redo log file for thread 1 and change 51247269.

--[ INFO] SMO-03057: Archived log file for thread 1 and change 51247269 found in the backup L_H_20160901230322CEST.

--[ INFO] SMO-03054: Mounting backup L_H_20160901230322CEST on host fziorasrv5.bl.ch to feed archivelogs.

--[ INFO] DS-10001: Connecting mountpoints [/ORATESTLOG] from snapshot smo_oratest_oratest_d_h_3_8a8ccb0356e78df20156e78e20090001_0 in copy 1108345 of backup 1472763834 of dataset smo_fziorasrv5.bl.ch_oratest_logs.

--[ INFO] SD-00016: Discovering storage resources for /opt/NetApp/smo/mnt/-ORATESTLOG-20160930085650559_1.

--[ INFO] SD-00017: Finished storage discovery for /opt/NetApp/smo/mnt/-ORATESTLOG-20160930085650559_1.

--[ INFO] DS-10002: Connected mountpoints [/ORATESTLOG] at [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085650559_1].

--[ INFO] SMO-03061: Perform recovery using backup L_H_20160901230322CEST

--[ INFO] SMO-03081: Perform recovery using all connected backups L_H_20160901230322CEST

--[ INFO] ORACLE-30023: Beginning batch recovery process for database ORATEST.

--[ INFO] ORACLE-30008: Beginning recovery process for database ORATEST.

--[ INFO] ORACLE-30010: Database recovery point objective: date 2016-08-31:23:06:18

--[ WARN] ORACLE-30027: Archived log file "/ORATESTLOG/arch1_7550_901731542.dbf" containing change 51247269 for thread 1 is required for completing recovery.

--[ INFO] ORACLE-30024: Finished batch recovery process for database ORATEST.

--[ INFO] SMO-03070: Skipping disconnecting backup L_H_20160901230322CEST as it may be required later.

--[ INFO] SMO-03058: Archived log file for thread 1 and change 51247269 not available in the backup L_H_20160901230322CEST.

--[ INFO] SMO-03060: Archived log file for thread 1 and change 51247269 required to complete recovery.

--[ INFO] SMO-03062: Perform automatic recovery from active file system

--[ INFO] SMO-03081: Perform recovery using all connected backups L_H_20160901230322CEST

--[ INFO] ORACLE-30023: Beginning batch recovery process for database ORATEST.

--[ INFO] ORACLE-30008: Beginning recovery process for database ORATEST.

--[ INFO] ORACLE-30010: Database recovery point objective: date 2016-08-31:23:06:18

--[ WARN] ORACLE-30027: Archived log file "/ORATESTLOG/arch1_7550_901731542.dbf" containing change 51247269 for thread 1 is required for completing recovery.

--[ WARN] ORACLE-30019: Recovery point objective of date 2016-08-31:23:06:18 not reached after applying archived/online redo logs [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085650559_1/arch1_7550_901731542.dbf].  Required archived/online redo logs may be missing from the filesystem.

--[ INFO] ORACLE-30024: Finished batch recovery process for database ORATEST.

--[ERROR] SMO-03060: Archived log file for thread 1 and change 51247269 required to complete recovery.

--[ERROR] SMO-03067: Recovery failed.

--[ INFO] SMO-03065: End of Database Recovery

--[ INFO] SMO-03055: Disconnecting backup L_H_20160901230322CEST.

--[ INFO] SD-00031: Beginning to disconnect filesystem(s) [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085650559_1].

--[ INFO] SD-00032: Finished disconnecting filesystem(s) [/opt/NetApp/smo/mnt/-ORATESTLOG-20160930085650559_1].

--[ INFO] SMO-07131: Unlocked database for SnapManager operations - removed lock file "/oracle/product/11.2.0.4/db/dbs/.sm_lock_ORATEST" on host fziorasrv5.bl.ch.

--[ WARN] SMO-07432: Skipping step of returning database to its original state because manual database recovery may be required. Database left in state MOUNTED.

--[ INFO] SMO-13048: Backup Restore Operation Status: SUCCESS

--[ INFO] SMO-13049: Elapsed Time: 0:21:12.626

 

As i mentioned if i mount the same snapshot which is reported in the log i can apply the logs just fine.

A restore from primary is working without these problems.

 

Does anybody has an hint?