Skip to Content

Archived discussions are read-only. Learn more about SAP Q&A

Problem with point-in-time recovery

Hallo.

I am trying to execute a disaster recovery + point-in-time recovery.

All is right but when it tries to apply redolog I have some problems:

BR0657I Input menu 119 - please enter/check input values

-


Applying archivelog group of database instance SSM

1 - Apply unattended (unattend) ....... [no]

2 - First sequence number (first_seq) . [14759]

3 - Last sequence number (last_seq) ... [14769]

4 - SQLPLUS command (command) ......... [recover from 'Z:\oracle\SSM\oraarch' database until time '2012-02-22 08.30.00' using backup controlfile]

Standard keys: c - cont, b - back, s - stop, r - refr, h - help

-


BR0662I Enter your choice:

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.07

BR0663I Your choice: 'c'

BR0259I Program execution will be continued...

BR0783I Archivelog files with sequence number 14759-14769 of database instance SSM will be applied now

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.07

BR0670I Enter 'c[ont]' to continue, 'b[ack]' to go back, 's[top]' to abort:

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.09

BR0257I Your reply: 'c'

BR0259I Program execution will be continued...

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.09

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14759_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14760_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14761_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14762_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14763_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14764_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14765_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14766_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14767_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14768_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14769_663672022.DBF ...

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.11

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14759_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.15

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14760_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.18

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14761_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.23

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14762_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.26

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14763_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.29

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14764_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.32

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14765_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.35

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14766_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.40

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14767_663672022.DBF applied successfully

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.44

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14768_663672022.DBF applied successfully

BR0278E Command output of 'D:\oracle\SSM\11202\BIN\sqlplus /nolog < Z:\oracle\SSM\sapbackup\.vehyfwif.spi':

SQL*Plus: Release 11.2.0.2.0 Production on Wed Feb 22 10:58:09 2012

Copyright (c) 1982, 2010, Oracle. All rights reserved.

SQL> SQL> SQL> SQL> Connected.

SQL>

SQL> SQL> ORA-00279: change 1031320902 generated at 02/21/2012 17:02:41 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14759_663672022.DBF

ORA-00280: change 1031320902 for thread 1 is in sequence #14759

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031329198 generated at 02/21/2012 18:34:14 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14760_663672022.DBF

ORA-00280: change 1031329198 for thread 1 is in sequence #14760

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14759_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031343735 generated at 02/21/2012 20:34:08 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14761_663672022.DBF

ORA-00280: change 1031343735 for thread 1 is in sequence #14761

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14760_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031360808 generated at 02/21/2012 23:01:23 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14762_663672022.DBF

ORA-00280: change 1031360808 for thread 1 is in sequence #14762

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14761_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031368849 generated at 02/22/2012 00:00:47 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14763_663672022.DBF

ORA-00280: change 1031368849 for thread 1 is in sequence #14763

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14762_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031376210 generated at 02/22/2012 00:05:27 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14764_663672022.DBF

ORA-00280: change 1031376210 for thread 1 is in sequence #14764

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14763_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031379652 generated at 02/22/2012 00:31:38 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14765_663672022.DBF

ORA-00280: change 1031379652 for thread 1 is in sequence #14765

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14764_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031397824 generated at 02/22/2012 01:40:52 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14766_663672022.DBF

ORA-00280: change 1031397824 for thread 1 is in sequence #14766

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14765_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031406399 generated at 02/22/2012 02:42:42 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14767_663672022.DBF

ORA-00280: change 1031406399 for thread 1 is in sequence #14767

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14766_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031418495 generated at 02/22/2012 04:43:39 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14768_663672022.DBF

ORA-00280: change 1031418495 for thread 1 is in sequence #14768

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14767_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00279: change 1031433152 generated at 02/22/2012 07:00:59 needed for thread 1

ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14769_663672022.DBF

ORA-00280: change 1031433152 for thread 1 is in sequence #14769

ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14768_663672022.DBF' no longer needed for this recovery

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

ORA-00308: cannot open archived log 'Z:\oracle\SSM\oraarch\SSMARCH1_14769_663672022.DBF'

ORA-27041: unable to open file

OSD-04002: unable to open file

O/S-Error: (OS 2) The system cannot find the file specified.

Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

Media recovery cancelled.

SQL> Disconnected from Oracle Database 11g Enterprise Edition Release 11.2.0.2.0 - 64bit Production

With the Partitioning, OLAP, Data Mining and Real Application Testing options

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.45

BR0279E Return code from 'D:\oracle\SSM\11202\BIN\sqlplus /nolog < Z:\oracle\SSM\sapbackup\.vehyfwif.spi': 0

BR0302E SQLPLUS call for database instance SSM failed

BR0338E Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14769_663672022.DBF failed

BR0548I Please check Oracle alert log Z:\oracle\SSM\saptrace\diag\rdbms\ssm\SSM\trace\alert_SSM.log for further information about this error

So, the only solution that works for me is to recover by means of sqlplus /nolog and:

SQL> connect / as sysdba

SQL> set autorecovery off

SQL> startup mount

SQL> recover database using backup controlfile until time '2012-02-22 08.30.00' ;

I choose AUTO so it searched the offline redolog on oraarch.

SQL> alter database open resetlogs;

After, I continue on brtools and I am able to proceed.

I don't understand why this problem on brtools.

Could you help me?

Thanks.

Mario

replied

> 1 - Apply unattended (unattend) ....... [no]

> 2 - First sequence number (first_seq) . [14759]

> 3 - Last sequence number (last_seq) ... [14769]

> 4 - SQLPLUS command (command) ......... recover from 'Z:\oracle\SSM\oraarch' database until time '2012-02-22 08.30.00' using backup controlfile

:

> BR0783I Archivelog files with sequence number 14759-14769 of database instance SSM will be applied now

:

BR0280I BRRECOVER time stamp: 2012-02-22 10.58.09

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14759_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14760_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14761_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14762_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14763_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14764_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14765_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14766_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14767_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14768_663672022.DBF ...

BR0336I Applying offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14769_663672022.DBF ...

:

:

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14759_663672022.DBF applied successfully

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14760_663672022.DBF applied successfully

:

BR0337I Offline redolog file Z:\oracle\SSM\oraarch\SSMARCH1_14768_663672022.DBF applied successfully

!!!! 14769 is missing here !!!!

> ORA-00289: suggestion : Z:\ORACLE\SSM\ORAARCH\1_14769_663672022.DBF

> ORA-00280: change 1031433152 for thread 1 is in sequence #14769

> ORA-00278: log file 'Z:\oracle\SSM\oraarch\SSMARCH1_14768_663672022.DBF' no longer needed for this recovery

>

> Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

> ORA-00308: cannot open archived log 'Z:\oracle\SSM\oraarch\SSMARCH1_14769_663672022.DBF'

> ORA-27041: unable to open file

> OSD-04002: unable to open file

> O/S-Error: (OS 2) The system cannot find the file specified.

>

> Specify log: {<RET>=suggested | filename | AUTO | CANCEL}

> Media recovery cancelled.

Hi,

allthough there is a wrong interpretation from brtools of the parameters and it is logging wrong "suggestions"

of possible logfiles it does indeed recover the correct files up to 14768.

The missmatch might come from LOG_ARCHIVE_DEST_1 being a path prefix and not a directory,

but for the recover command (red above) the FROM clause is converted to a real directory (This might be a brtools bug).

This is where the fileprefix gets ticked off.

No matter, because brtools does not use the suggestion at all, but is using the correct path from V$ARCHIVEDLOG to

generate "RECOVER LOGFILE ..." commands.

And 14769 is MISSING!!

BRTOOLS do recover with a "RECOVER LOGFILE ..." command, which REQUIRES the log to be recovered to be an archive log.

Check the alert log for the corresponding "ALTER DATABASE RECOVER LOGFILE ..." commands.

Most likely, 14769 was an ONLINE LOG at the point of you recovery start.

So BRTOOLS missed that for whatever reason (did you restore the controlfile?)

The RECOVER DATABASE command checks automaticly the remaining ONLINE LOGS in case the ARCHIVE Logs run out,

so it is recovering the last data from the ONLINE log automaticlly.

If you check the alert log, you might find that 14769 had never been copied to your archive destination.

You should surely check the alert log from your successfull recovery from what source the log #14769 was taken.

Volker

0 View this answer in context
Not what you were looking for? View more on this topic or Ask a question