Video Screencast Help
Scheduled Maintenance: Symantec Connect is scheduled to be down Saturday, April 19 from 10am to 2pm Pacific Standard Time (GMT: 5pm to 9pm) for server migration and upgrades.
Please accept our apologies in advance for any inconvenience this might cause.

Backupexec oracle agent backup fails on 2nd day

Created: 26 Jan 2012 | 7 comments

I am running backupexec 12 with oracle agent. It is strange that first time oracle backup is 100% successful, next day it will fail. Then I have to restart the server, oracle backup will be success for first run , next day fails again. The open file option is set to 'without lock'
Can anyone help on this issue? I could upload job log if requested. Thanks, Yang

Discussion Filed Under:

Comments 7 CommentsJump to latest comment

pkh's picture

What are the error messages?

As a general rule, it is not advisable to use AOFO for database backups.

Hypertensivecrisis's picture

Thanks pkh, AOFO is not used, please see the property line as below

 

Open file backup when Advanced Open File Option is not used:

Without a lock

Sergio_Maroni's picture

First of all we need error message. or job log with error. What version of oracle ? and etc.

I`m sorry for my bad eanglish.

If you find this is a solution, please mark it as such. Thx

Hypertensivecrisis's picture

Thanks Sergio_Maroni, please see the log file, and let me know if you would like more details

 

 

Job Log for Oracle Daily Backup

 

Completed status: Failed    See error(s)
Expand AllCollapse All

 

Job Information
Job server: RDSYDAUW2K301
Job name: Oracle Daily Backup
Job started: Wednesday, 25 January 2012 at 10:00:04 PM
Job type: Backup
Job Log: BEX_RDSYDAUW2K301_03244.xml
Drive and media mount requested: 25/01/2012 10:00:04 PM
No appendable media could be mounted.
Switching to overwrite operation on scratch media.

Device and Media Information
Job Operation - Backup
Job Operation - Verify
Job Completion Status
Job ended: Friday, 27 January 2012 at 9:14:15 AM
Completed status: Failed
Final error: 0xe000150a - The operation was terminated by the database application.
Final error category: Resource Errors

For additional information regarding this error refer to link V-79-57344-5386

Errors
Click an error below to locate it in the job log

 

Backup- Oracle-Win::\\RDSYDAUW2K301\opera 
V-79-57344-33928 - Access is denied.
Access denied to directory .
Access is denied to Remote Agent.  Check the login account for the specified device.
The Database script returned an error.  Refer to the Database script output section in job logs for more details.
Storage device "HP 2" reported an error on a request to write data to media.
Error reported:
Incorrect function.
V-79-57344-34030 - An invalid command was sent to the storage device.

 

Verify
Storage device "HP 2" reported an error on a request to read data from media.
Error reported:
Incorrect function.
V-79-57344-34030 - An invalid command was sent to the storage device.
Storage device "HP 2" reported an error on a request to read data from media.
Error reported:
Incorrect function.
V-79-57344-34030 - An invalid command was sent to the storage device.
pkh's picture

You appear to have a login problem to your Oracle database.

Hypertensivecrisis's picture

Thanks pkh. It can not explain why first run is 100% successful. The second time fails in the middle after 22gb data backup. This confuses me. Is RMAN version higher than database could be the problem? (I will need to confirm the version tomorrow)

Just checked today, RMAN version is 10.2.0.3.0, not newer than the database.

Hypertensivecrisis's picture

Please see below the RMAN output, it maybe caused by corrupt blocks in opera_data03.dbf. But it still confuses me as it alway can run successful after server restarts, then next day fails.

The network connection to the Backup Exec Remote Agent has been lost. Please check for network errors.
 

RMAN Output
Recovery Manager: Release 10.2.0.3.0 - Production on Wed Jan 25 22:00:23 2012

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

 
 # -----------------------------------------------------------------
 # RMAN command section
 # -----------------------------------------------------------------
 
 RUN {
 ALLOCATE CHANNEL ch0
     TYPE 'SBT_TAPE';
 SEND 'BSA_SERVICE_HOST=RDSYDAUW2K301,NBBSA_TOTAL_STREAMS=1,NBBSA_JOB_COOKIE={0EA477B2-41C7-4607-9FF9-A67FA2B0125E},NBBSA_DB_DEVICE_NAME=Oracle-Win::\\RDSYDAUW2K301\opera';
 BACKUP
     INCREMENTAL LEVEL=0
     FORMAT 'BE_U'
     DATABASE FORCE PLUS ARCHIVELOG FORCE;
 BACKUP CURRENT CONTROLFILE 
     FORMAT 'BE_U';
 
 RELEASE CHANNEL ch0;
 }
 
connected to target database: OPERA (DBID=2276256358)

using target database control file instead of recovery catalog
allocated channel: ch0
channel ch0: sid=518 devtype=SBT_TAPE
channel ch0: Symantec/BackupExec/1.1.0

sent command to channel: ch0


Starting backup at 25-JAN-12
current log archived
channel ch0: starting archive log backupset
channel ch0: specifying archive log(s) in backup set
input archive log thread=1 sequence=19711 recid=19538 stamp=773109056
input archive log thread=1 sequence=19712 recid=19539 stamp=773109330
input archive log thread=1 sequence=19713 recid=19540 stamp=773113116
input archive log thread=1 sequence=19714 recid=19541 stamp=773118593
input archive log thread=1 sequence=19715 recid=19542 stamp=773157691
input archive log thread=1 sequence=19716 recid=19543 stamp=773186426
input archive log thread=1 sequence=19717 recid=19544 stamp=773207611
input archive log thread=1 sequence=19718 recid=19545 stamp=773207805
input archive log thread=1 sequence=19719 recid=19546 stamp=773209392
input archive log thread=1 sequence=19720 recid=19547 stamp=773214445
input archive log thread=1 sequence=19721 recid=19548 stamp=773236935
input archive log thread=1 sequence=19722 recid=19549 stamp=773261346
input archive log thread=1 sequence=19723 recid=19550 stamp=773272826
input archive log thread=1 sequence=19724 recid=19551 stamp=773282250
input archive log thread=1 sequence=19725 recid=19552 stamp=773282502
input archive log thread=1 sequence=19726 recid=19553 stamp=773284736
input archive log thread=1 sequence=19727 recid=19554 stamp=773290068
input archive log thread=1 sequence=19728 recid=19555 stamp=773319638
input archive log thread=1 sequence=19729 recid=19556 stamp=773321350
input archive log thread=1 sequence=19730 recid=19557 stamp=773335926
input archive log thread=1 sequence=19731 recid=19558 stamp=773359212
input archive log thread=1 sequence=19732 recid=19559 stamp=773373610
input archive log thread=1 sequence=19733 recid=19560 stamp=773373805
input archive log thread=1 sequence=19734 recid=19561 stamp=773379391
input archive log thread=1 sequence=19735 recid=19562 stamp=773385323
input archive log thread=1 sequence=19736 recid=19563 stamp=773397998
input archive log thread=1 sequence=19737 recid=19564 stamp=773398191
input archive log thread=1 sequence=19738 recid=19565 stamp=773400159
input archive log thread=1 sequence=19739 recid=19566 stamp=773404354
input archive log thread=1 sequence=19740 recid=19567 stamp=773412114
input archive log thread=1 sequence=19741 recid=19568 stamp=773423511
input archive log thread=1 sequence=19742 recid=19569 stamp=773444587
input archive log thread=1 sequence=19743 recid=19570 stamp=773445626
input archive log thread=1 sequence=19744 recid=19571 stamp=773460344
input archive log thread=1 sequence=19745 recid=19572 stamp=773460539
input archive log thread=1 sequence=19746 recid=19573 stamp=773462096
input archive log thread=1 sequence=19747 recid=19574 stamp=773467498
input archive log thread=1 sequence=19748 recid=19575 stamp=773483111
input archive log thread=1 sequence=19749 recid=19576 stamp=773483309
input archive log thread=1 sequence=19750 recid=19577 stamp=773484848
input archive log thread=1 sequence=19751 recid=19578 stamp=773489328
input archive log thread=1 sequence=19752 recid=19579 stamp=773497323
input archive log thread=1 sequence=19753 recid=19580 stamp=773498500
input archive log thread=1 sequence=19754 recid=19581 stamp=773510246
input archive log thread=1 sequence=19755 recid=19582 stamp=773527529
input archive log thread=1 sequence=19756 recid=19583 stamp=773532027
channel ch0: starting piece 1 at 25-JAN-12
channel ch0: finished piece 1 at 25-JAN-12
piece handle=BE_i4n1mabv_1_1 tag=TAG20120125T220028 comment=API Version 2.0,MMS Version 1.1.0.0
channel ch0: backup set complete, elapsed time: 00:01:26
Finished backup at 25-JAN-12

Starting backup at 25-JAN-12
channel ch0: starting incremental level 0 datafile backupset
channel ch0: specifying datafile(s) in backupset
input datafile fno=00012 name=D:\ORACLE\ORADATA\OPERA\NAMEDATA01.DBF
input _cke_saved_name=D:\ORACLE\ORADATA\OPERA\NAMEDATA01.DBF
input datafile fno=00016 name=D:\ORACLE\ORADATA\OPERA\OPERA_DATA01.DBF
input datafile fno=00018 name=D:\ORACLE\ORADATA\OPERA\OPERA_INDX01.DBF
input datafile fno=00020 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA01.DBF
input datafile fno=00021 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA02.DBF
input datafile fno=00022 name=D:\ORACLE\ORADATA\OPERA\OXI_INDX01.DBF
input datafile fno=00041 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA05.DBF
input datafile fno=00042 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA06.DBF
input datafile fno=00043 name=D:\ORACLE\ORADATA\OPERA\FINDATA03.DBF
input datafile fno=00044 name=D:\ORACLE\ORADATA\OPERA\FININDX03.DBF
input datafile fno=00045 name=D:\ORACLE\ORADATA\OPERA\LOGDATA03.DBF
input datafile fno=00049 name=D:\ORACLE\ORADATA\OPERA\FININDX04.DBF
input datafile fno=00051 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA07.DBF
input datafile fno=00011 name=D:\ORACLE\ORADATA\OPERA\LOGINDX02.DBF
input datafile fno=00046 name=D:\ORACLE\ORADATA\OPERA\LOGINDX03.DBF
input datafile fno=00050 name=D:\ORACLE\ORADATA\OPERA\LOGINDX04.DBF
input datafile fno=00001 name=D:\ORACLE\ORADATA\OPERA\SYSTEM01.DBF
input datafile fno=00002 name=D:\ORACLE\ORADATA\OPERA\UNDOTBS01.DBF
input datafile fno=00003 name=D:\ORACLE\ORADATA\OPERA\SYSAUX01.DBF
input datafile fno=00007 name=D:\ORACLE\ORADATA\OPERA\FININDX02.DBF
input datafile fno=00008 name=D:\ORACLE\ORADATA\OPERA\LOGDATA01.DBF
input datafile fno=00009 name=D:\ORACLE\ORADATA\OPERA\LOGDATA02.DBF
input datafile fno=00010 name=D:\ORACLE\ORADATA\OPERA\LOGINDX01.DBF
input datafile fno=00017 name=D:\ORACLE\ORADATA\OPERA\OPERA_DATA02.DBF
input datafile fno=00019 name=D:\ORACLE\ORADATA\OPERA\OPERA_INDX02.DBF
input datafile fno=00032 name=D:\ORACLE\ORADATA\OPERA\RESVDATA01.DBF
input datafile fno=00033 name=D:\ORACLE\ORADATA\OPERA\RESVDATA02.DBF
input datafile fno=00034 name=D:\ORACLE\ORADATA\OPERA\RESVINDX01.DBF
input datafile fno=00035 name=D:\ORACLE\ORADATA\OPERA\RESVINDX02.DBF
input datafile fno=00036 name=D:\ORACLE\ORADATA\OPERA\TOOLS01.DBF
input datafile fno=00037 name=D:\ORACLE\ORADATA\OPERA\OPERA_INDX03.DBF
input datafile fno=00038 name=D:\ORACLE\ORADATA\OPERA\OPERA_DATA03.DBF
input datafile fno=00039 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA03.DBF
input datafile fno=00040 name=D:\ORACLE\ORADATA\OPERA\OXI_DATA04.DBF
input datafile fno=00048 name=D:\ORACLE\ORADATA\OPERA\.OPERA_DATA04.DBF
input datafile fno=00006 name=D:\ORACLE\ORADATA\OPERA\FININDX01.DBF
input datafile fno=00029 name=D:\ORACLE\ORADATA\OPERA\RATEDATA02.DBF
input datafile fno=00054 name=D:\ORACLE\ORADATA\OPERA\UNDOTBS02.DBF
input datafile fno=00004 name=D:\ORACLE\ORADATA\OPERA\FINDATA01.DBF
input datafile fno=00053 name=D:\ORACLE\ORADATA\OPERA\FINTMP01.DBF
input datafile fno=00014 name=D:\ORACLE\ORADATA\OPERA\NAMEINDX01.DBF
input datafile fno=00047 name=D:\ORACLE\ORADATA\OPERA\RATEINDX03.DBF
input datafile fno=00024 name=D:\ORACLE\ORADATA\OPERA\QUICKDATA01.DBF
input datafile fno=00026 name=D:\ORACLE\ORADATA\OPERA\QUICKINDX01.DBF
input datafile fno=00028 name=D:\ORACLE\ORADATA\OPERA\RATEDATA01.DBF
input datafile fno=00030 name=D:\ORACLE\ORADATA\OPERA\RATEINDX01.DBF
input datafile fno=00052 name=D:\ORACLE\ORADATA\OPERA\SYSTEM02.DBF
input datafile fno=00005 name=D:\ORACLE\ORADATA\OPERA\FINDATA02.DBF
input datafile fno=00015 name=D:\ORACLE\ORADATA\OPERA\NAMEINDX02.DBF
input datafile fno=00031 name=D:\ORACLE\ORADATA\OPERA\RATEINDX02.DBF
input datafile fno=00013 name=D:\ORACLE\ORADATA\OPERA\NAMEDATA02.DBF
input datafile fno=00023 name=D:\ORACLE\ORADATA\OPERA\OXI_INDX02.DBF
input datafile fno=00025 name=D:\ORACLE\ORADATA\OPERA\QUICKDATA02.DBF
input datafile fno=00027 name=D:\ORACLE\ORADATA\OPERA\QUICKINDX02.DBF
channel ch0: starting piece 1 at 25-JAN-12
RMAN-03009: failure of backup command on ch0 channel at 01/25/2012 22:14:34
ORA-19566: exceeded limit of 0 corrupt blocks for file D:\ORACLE\ORADATA\OPERA\OPERA_DATA03.DBF
continuing other job steps, job failed will not be re-run
channel ch0: starting incremental level 0 datafile backupset
channel ch0: specifying datafile(s) in backupset
including current control file in backupset
channel ch0: starting piece 1 at 25-JAN-12
channel ch0: finished piece 1 at 25-JAN-12
piece handle=BE_i6n1mb6a_1_1 tag=TAG20120125T220157 comment=API Version 2.0,MMS Version 1.1.0.0
channel ch0: backup set complete, elapsed time: 00:02:16
RMAN-00571: ===========================================================
RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
RMAN-00571: ===========================================================
RMAN-03002: failure of backup plus archivelog command at 01/25/2012 22:16:50

RMAN-03009: failure of backup command on ch0 channel at 01/25/2012 22:14:34
ORA-19566: exceeded limit of 0 corrupt blocks for file D:\ORACLE\ORADATA\OPERA\OPERA_DATA03.DBF
RMAN-00571: ===========================================================
RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
RMAN-00571: ===========================================================
ORA-03113: end-of-file on communication channel
RMAN-00571: ===========================================================
RMAN-00569: =============== ERROR MESSAGE STACK FOLLOWS ===============
RMAN-00571: ===========================================================
RMAN-03002: failure of backup plus archivelog command at 01/25/2012 22:16:50

RMAN-03009: failure of backup command on ch0 channel at 01/25/2012 22:14:34
ORA-19566: exceeded limit of 0 corrupt blocks for file D:\ORACLE\ORADATA\OPERA\OPERA_DATA03.DBF

Devices acquired: 1
Data streams used: 2