Netbackup Exchange IS backups fail with Backup Exec error?

[Status_Code_0]

Has anyone seen this before.  I am getting backup exec errors in my MS Exchange Info Store backups that fail with status code 1.  Here is the bpbkar log from the ExchServer:

I've checker permissions on the service account, made sure the mailbox for it is "active", and recycled services on both client and master?

Thanks in advance for any help...

10:31:41.974 AM: [2760.8068] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\Veritas\NetBackup\logs\BPBKAR\032009.LOG

10:31:41.974 AM: [2760.8068] <4> ov_log::OVInit: GENERAL Log Level: 0
10:31:41.974 AM: [2760.8068] <4> ov_log::OVInit: TCP Log Level: 0
10:31:41.974 AM: [2760.8068] <4> ov_log::OVInit: INF - the log mutex: 1780
BPBKAR  NetBackup Backup/Archive  6.5GA  [Jul 23 2007]
Copyright 1993 - 2007 VERITAS Software Corporation
All Rights Reserved.

10:31:41.974 AM: [2760.8068] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe'
10:31:41.974 AM: [2760.8068] <2> WinMain: DAT - lpCmdLine = '-r 2678400 -ru root -dt 0 -to 0 -clnt ExchServer -class Exchange -sched Weekly_Exchange_Full -st FULL -bpstart_to 300 -bpend_to 300 -read_to 300 -blks_per_buffer 512 -use_otm -fso -b ExchServer_1237559499 -kl 28 -ct 16 -use_ofb '
10:31:41.974 AM: [2760.8068] <2> date_debug: DAT - timezone: Eastern Standard Time, offset=18000, dst: Eastern Daylight Time
10:31:41.974 AM: [2760.8068] <2> date_debug: DAT - current time: 1237559501, 3/20/2009 10:31:41 AM
10:31:41.974 AM: [2760.8068] <2> date_debug: DAT - 01/01/94 UCT:  757382400, 12/31/1993 8:00:00 PM
10:31:41.974 AM: [2760.8068] <2> date_debug: DAT - 07/01/94 UCT:  773020800, 6/30/1994 8:00:00 PM
10:31:41.974 AM: [2760.8068] <2> WinMain: DAT - standard input handle = 512
10:31:41.974 AM: [2760.8068] <2> WinMain: DAT - standard output handle = 1880
10:31:41.974 AM: [2760.8068] <2> WinMain: DAT - standard error handle = 1824
10:31:41.990 AM: [2760.8068] <4> tar_backup::V_SetupJobData: INF - dwJobData: ffffffff
10:31:41.990 AM: [2760.8068] <4> tar_backup::V_SetupJobData: INF -     dwJob: ffffffff
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - backup privileges enabled, previous = 0
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - restore privileges enabled, previous = 0
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - security privileges enabled, previous = 0
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - tcb privileges enabled, previous = 0
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - create token privileges enabled, previous = 0
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - user name: root
10:31:42.006 AM: [2760.8068] <4> dos_backup::V_PreProcessing: INF - no access token: scheduled backup
10:31:42.178 AM: [2760.8068] <2> tar_backup::V_SetupProcessContinue: TAR - CONTINUE BACKUP received
10:31:42.178 AM: [2760.8068] <2> tar_backup_tfi::setupFileDirectives: TAR - backup filename = Microsoft Information Store:\
10:31:42.178 AM: [2760.1380] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 60 seconds
10:31:43.256 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Q:
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: T:
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: U:
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: X:
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: \\ExchServer\Microsoft Exchange Mailboxes
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: \\ExchServer\Microsoft Exchange Public Folders
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: \\ExchServer\Microsoft Information Store
10:31:43.912 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\ExchServer\Microsoft Information Store\First Storage Group
10:31:43.912 AM: [2760.8068] <4> dos_backup::V_VerifyFileList: INF - UBS Local Type for 'Microsoft Information Store:\' --> 10020020
10:31:43.912 AM: [2760.8068] <4> dos_backup::V_Initialize: INF - Exchange Backup - Disabling Open File Backups, Archive Bit processing, TIR and Job Estimating.
10:31:43.912 AM: [2760.8068] <4> backup_create: INF - NetBackup Temp Directory: 'C:\Program Files\Veritas\\NetBackup\Temp'
10:31:44.365 AM: [2760.8068] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForRead():FS_OpenObj() Failed! (0xE000FEA9:The Backup Exec data store encountered a problem during the operation. See the job log for details.
)
10:31:44.365 AM: [2760.8068] <2> tar_base::V_vTarMsgW: WRN - can't open object: Microsoft Information Store:\First Storage Group\Mailbox Store (ExchServer) (BEDS 0xE000FEA9: The Backup Exec data store encountered a problem during the operation. See the job log for details.
)
10:31:44.475 AM: [2760.8068] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForRead():FS_OpenObj() Failed! (0xE000FEA9:The Backup Exec data store encountered a problem during the operation. See the job log for details.
)
10:31:44.475 AM: [2760.8068] <2> tar_base::V_vTarMsgW: WRN - can't open object: Microsoft Information Store:\First Storage Group\Public Folder Store (ExchServer) (BEDS 0xE000FEA9: The Backup Exec data store encountered a problem during the operation. See the job log for details.
)
10:31:44.584 AM: [2760.8068] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForRead():FS_OpenObj() Failed! (0xE000FEA9:The Backup Exec data store encountered a problem during the operation. See the job log for details.
)
10:31:44.584 AM: [2760.8068] <2> tar_base::V_vTarMsgW: WRN - can't open object: Microsoft Information Store:\First Storage Group\Log files (BEDS 0xE000FEA9: The Backup Exec data store encountered a problem during the operation. See the job log for details.
)
10:31:44.584 AM: [2760.8068] <2> tar_base::backup_finish: TAR - backup:                     2 files
10:31:44.584 AM: [2760.8068] <2> tar_base::backup_finish: TAR - backup:          file data:          0 bytes
10:31:44.584 AM: [2760.8068] <2> tar_base::backup_finish: TAR - backup:         image data:       1024 bytes
10:31:44.584 AM: [2760.8068] <2> tar_base::backup_finish: TAR - backup:       elapsed time:          1 secs         1024 bps
10:31:44.584 AM: [2760.8068] <4> tar_backup::backup_done_state: INF - number of file directives not found: 0
10:31:44.584 AM: [2760.8068] <4> tar_backup::backup_done_state: INF -     number of file directives found: 1
10:31:44.584 AM: [2760.1380] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)
10:31:44.584 AM: [2760.8068] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)
10:31:44.584 AM: [2760.8068] <2> tar_base::V_vTarMsgW: INF - EXIT STATUS 1: the requested operation was partially successful
10:31:45.584 AM: [2760.8068] <2> tar_base::V_vTarMsgW: WRN - Abnormal backup status, Exchange Transaction Logs were not truncated
10:31:45.584 AM: [2760.8068] <4> dos_backup::tfs_reset: INF - Snapshot deletion start
10:31:45.584 AM: [2760.8068] <4> OVStopCmd: INF - EXIT - status = 0
10:31:45.584 AM: [2760.8068] <2> tar_base::V_Close:
10:31:45.584 AM: [2760.8068] <4> dos_backup::tfs_reset: INF - Snapshot deletion start
10:31:45.584 AM: [2760.8068] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1
10:31:45.600 AM: [2760.8068] <4> OVShutdown: INF - Finished process
10:31:45.600 AM: [2760.8068] <4> WinMain: INF - Exiting C:\Program Files\Veritas\NetBackup\bin\bpbkar32.exe
10:31:47.600 AM: [2760.8068] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\Veritas\NetBackup\logs\BPBKAR\032009.LOG
 

[Swapnil_Dighe]

Hi This is Swapnil

I guess , we are backign up exchange 2007 , we dont need mailbox for backing up Information Store.
As the error is showing for BEDS . To troubleshoot , we need the BEDS logs fromt he exchange server

we can easily find out what is the error for Beds int he beds logs.

[Zahid_Haseeb]

 HI Swapnil

How do we can get the BEDS logs ?????
 

[rjrumfelt]

Marianne explained that it looks like Symantec is using some of the same underlying code for their agents.   She said she disregards the backup exec messages in the error logs.  It appears that the fact that you see backup exec in the logs has nothing to do with the actual backup failures.