vagrant-projects: Database - seed database restore fails

Hi All,

Having problems setting up a new vagrant box for Oracle 18.3.

After some issues with YUM, I’ve modified the script to keep retrying yum install/updates until completion. How when creating the database i get the following in /opt/oracle/cfgtoollogs/dbca/ORCLCDB/trace.log_2020-04-01_HH-MM-SSPM :

[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANEngine.executeImpl:1380]  DONE EXECUTING GIVEN COMMAND=CATALOG START WITH   '/opt/oracle/product/18c/dbhome_1/assistants/dbca/templates//Seed_Database.dfb'  NOPROMPT;
[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANUtil.catalogBackup:730]  done catalog
[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANUtil.getRestoreCommandForDataFiles:339]   generate set new name/restore command per datafile basis
[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANUtil.restoreDataFilesFromBackup:519]   set new name command RUN {

set newname for datafile 1 to  '/opt/oracle/oradata/ORCLCDB/system01.dbf' ;

set newname for datafile 3 to  '/opt/oracle/oradata/ORCLCDB/sysaux01.dbf' ;

set newname for datafile 4 to  '/opt/oracle/oradata/ORCLCDB/undotbs01.dbf' ;

set newname for datafile 7 to  '/opt/oracle/oradata/ORCLCDB/users01.dbf' ;

restore datafile 1;

restore datafile 3;

restore datafile 4;

restore datafile 7; }
[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANEngine.executeImpl:1285]  m_bExecQuery=false
[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANEngine.executeImpl:1293]  Command being written to rman process=RUN {

set newname for datafile 1 to  '/opt/oracle/oradata/ORCLCDB/system01.dbf' ;

set newname for datafile 3 to  '/opt/oracle/oradata/ORCLCDB/sysaux01.dbf' ;

set newname for datafile 4 to  '/opt/oracle/oradata/ORCLCDB/undotbs01.dbf' ;

set newname for datafile 7 to  '/opt/oracle/oradata/ORCLCDB/users01.dbf' ;

restore datafile 1;

restore datafile 3;

restore datafile 4;

restore datafile 7; }
[progressPage.flowWorker] [ 2020-04-01 15:47:03.401 GMT+01:00 ] [RMANEngine.executeImpl:1321]  Notify reader to start reading
[Thread-232] [ 2020-04-01 15:47:03.402 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=echo set off
[Thread-232] [ 2020-04-01 15:47:03.402 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:03.405 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17>
[Thread-232] [ 2020-04-01 15:47:03.405 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:03.506 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:03.507 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:03.614 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:03.615 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:03.710 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:03.712 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:03.804 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:03.811 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=Starting restore at 01-APR-20
[Thread-232] [ 2020-04-01 15:47:03.986 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=allocated channel: ORA_DISK_1
[Thread-232] [ 2020-04-01 15:47:03.990 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=channel ORA_DISK_1: SID=21 device type=DISK
[Thread-232] [ 2020-04-01 15:47:04.229 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:04.434 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=channel ORA_DISK_1: starting datafile backup set restore
[Thread-232] [ 2020-04-01 15:47:04.439 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=channel ORA_DISK_1: specifying datafile(s) to restore from backup set[Thread-232] [ 2020-04-01 15:47:04.440 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=channel ORA_DISK_1: restoring datafile 00001 to /opt/oracle/oradata/ORCLCDB/system01.dbf
[Thread-232] [ 2020-04-01 15:47:04.446 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=channel ORA_DISK_1: reading from backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.dfb
[Thread-232] [ 2020-04-01 15:47:49.569 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=channel ORA_DISK_1: ORA-19870: error while restoring backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.dfb
[Thread-232] [ 2020-04-01 15:47:49.569 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=ORA-19612: datafile 1 not restored due to missing or corrupt data
[Thread-232] [ 2020-04-01 15:47:49.570 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:49.601 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=failover to previous backup
[Thread-232] [ 2020-04-01 15:47:49.602 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:49.750 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=creating datafile file number=1 name=/opt/oracle/oradata/ORCLCDB/system01.dbf
[Thread-232] [ 2020-04-01 15:47:49.833 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=RMAN-00571: ===========================================================
==
[Thread-232] [ 2020-04-01 15:47:49.833 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=RMAN-00571: ===========================================================
[Thread-232] [ 2020-04-01 15:47:49.833 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=RMAN-03002: failure of restore command at 04/01/2020 15:47:49
[Thread-232] [ 2020-04-01 15:47:49.833 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=ORA-01180: can not create datafile 1
[Thread-232] [ 2020-04-01 15:47:49.833 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=ORA-01110: data file 1: '/opt/oracle/oradata/ORCLCDB/system01.dbf'
[Thread-232] [ 2020-04-01 15:47:49.838 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=
[Thread-232] [ 2020-04-01 15:47:49.838 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=RMAN>
[Thread-232] [ 2020-04-01 15:47:49.839 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=echo set on
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANEngine.readSqlOutput:988]  Log RMAN Output=set echo off;
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANEngine.readSqlOutput:1031]  hasError is true
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANEngine.readSqlOutput:1037]  ERROR TRACE DETECTED
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANEngine.readSqlOutput:1044]  m_errHdlr=oracle.sysman.assistants.util.rmanUtil.RMANUtil$RMANUtilErrorListener@32b3f57c
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1328]  Number of ERRORS:25
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=echo set off
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17>
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=executing command: SET NEWNAME
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=Starting restore at 01-APR-20
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=allocated channel: ORA_DISK_1
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=channel ORA_DISK_1: SID=21 device type=DISK
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=channel ORA_DISK_1: starting datafile backup set restore
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=channel ORA_DISK_1: specifying datafile(s) to restore from backup set
[Thread-232] [ 2020-04-01 15:47:49.844 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=channel ORA_DISK_1: restoring datafile 00001 to /opt/oracle/oradata/ORCLCDB/system01.dbf
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=channel ORA_DISK_1: reading from backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.df
b
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=channel ORA_DISK_1: ORA-19870: error while restoring backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templa
tes/Seed_Database.dfb
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=ORA-19612: datafile 1 not restored due to missing or corrupt data
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=failover to previous backup
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=creating datafile file number=1 name=/opt/oracle/oradata/ORCLCDB/system01.dbf
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=RMAN-00571: ===========================================================
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=RMAN-00571: ===========================================================
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=RMAN-03002: failure of restore command at 04/01/2020 15:47:49
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=ORA-01180: can not create datafile 1
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=ORA-01110: data file 1: '/opt/oracle/oradata/ORCLCDB/system01.dbf'
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=RMAN>
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANUtil$RMANUtilErrorListener.handleError:1331]  ERROR=echo set on
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANEngine.readSqlOutput:1068]  FATAL ERROR DETECTED
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANEngine.readSqlOutput:1132]  Notifying writer to proceed because m_bInterrupted=falsebNotify=truem_bReaderDone=truem_bFatalErrorOccured=true
[Thread-232] [ 2020-04-01 15:47:49.845 GMT+01:00 ] [RMANEngine.run:841]  Return Value from readSqlOutput=null
[progressPage.flowWorker] [ 2020-04-01 15:47:49.846 GMT+01:00 ] [RMANEngine.executeImpl:1333]  Completed wait from reader
[progressPage.flowWorker] [ 2020-04-01 15:47:49.846 GMT+01:00 ] [RMANEngine.executeImpl:1348]  Calling done as got exception
[progressPage.flowWorker] [ 2020-04-01 15:47:49.846 GMT+01:00 ] [RMANEngine.done:1654]  Done called
[progressPage.flowWorker] [ 2020-04-01 15:47:49.846 GMT+01:00 ] [RMANEngine.spoolOff:1540]  Setting spool off = /opt/oracle/cfgtoollogs/dbca/ORCLCDB/rmanUtil
[progressPage.flowWorker] [ 2020-04-01 15:47:49.858 GMT+01:00 ] [RMANEngine.executeImpl:1375]  GOT FATAL Error=echo set off
RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17>
executing command: SET NEWNAME
executing command: SET NEWNAME
executing command: SET NEWNAME
executing command: SET NEWNAME
Starting restore at 01-APR-20
allocated channel: ORA_DISK_1
channel ORA_DISK_1: SID=21 device type=DISK
channel ORA_DISK_1: starting datafile backup set restore
channel ORA_DISK_1: specifying datafile(s) to restore from backup set
channel ORA_DISK_1: restoring datafile 00001 to /opt/oracle/oradata/ORCLCDB/system01.dbf
channel ORA_DISK_1: reading from backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.dfb
channel ORA_DISK_1: ORA-19870: error while restoring backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.dfb
ORA-19612: datafile 1 not restored due to missing or corrupt data
failover to previous backup
creating datafile file number=1 name=/opt/oracle/oradata/ORCLCDB/system01.dbf
RMAN-00571: ===========================================================
RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
RMAN-00571: ===========================================================
RMAN-03002: failure of restore command at 04/01/2020 15:47:49
ORA-01180: can not create datafile 1
ORA-01110: data file 1: '/opt/oracle/oradata/ORCLCDB/system01.dbf'
RMAN>
echo set on

[progressPage.flowWorker] [ 2020-04-01 15:47:49.858 GMT+01:00 ] [RMANUtil.restoreDataFilesFromBackup:535]  Error while doing set new name echo set off
RMAN> 2> 3> 4> 5> 6> 7> 8> 9> 10> 11> 12> 13> 14> 15> 16> 17>
executing command: SET NEWNAME
executing command: SET NEWNAME
executing command: SET NEWNAME
executing command: SET NEWNAME
Starting restore at 01-APR-20
allocated channel: ORA_DISK_1
channel ORA_DISK_1: SID=21 device type=DISK
channel ORA_DISK_1: starting datafile backup set restore
channel ORA_DISK_1: specifying datafile(s) to restore from backup set
channel ORA_DISK_1: restoring datafile 00001 to /opt/oracle/oradata/ORCLCDB/system01.dbf
channel ORA_DISK_1: reading from backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.dfb
channel ORA_DISK_1: ORA-19870: error while restoring backup piece /opt/oracle/product/18c/dbhome_1/assistants/dbca/templates/Seed_Database.dfb
ORA-19612: datafile 1 not restored due to missing or corrupt data
failover to previous backup
creating datafile file number=1 name=/opt/oracle/oradata/ORCLCDB/system01.dbf
RMAN-00571: ===========================================================
RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
RMAN-00571: ===========================================================
RMAN-03002: failure of restore command at 04/01/2020 15:47:49
ORA-01180: can not create datafile 1
ORA-01110: data file 1: '/opt/oracle/oradata/ORCLCDB/system01.dbf'
RMAN>
echo set on

Any tips?

About this issue

  • Original URL
  • State: closed
  • Created 4 years ago
  • Comments: 15 (6 by maintainers)

Most upvoted comments

Database came up successfully, with the original config downloaded from github… everything it hasn’t been doing this week!

+1
harvybob on Apr 4, 2020

Shot in the dark… Is the problem identical with VirtualBox 6.0? (Latest 6.0 can be downloaded from https://www.virtualbox.org/wiki/Download_Old_Builds_6_0 )

May be a shot in the dark - but seems to still be aimed in the right direction!

Still testing but installing 6.0 threw up an error with regards to hyperv when trying to start a new box. So i disabled this, and the box successfully started, and completed without errors and without trying to re get data from repo’s. I have now removed the changes i made to the install.sh and am re-running, once that is complete, i will enable hyperv and try, followed by upgrading to 6.1 and trying with and without hypervm… will report back progress tomorrow… but either 6.0 or hyperv may be causing the issues here…

+1
harvybob on Apr 4, 2020
Read more comments on GitHub
← vagrant-projects: BoxManage.exe: error: Failed to create the host-only adapter (FPP)
vagrant-projects: Metadata file does not match checksum - 12.2c, 18c, ol7 etc →