Veritas-bu
[Top] [All Lists]
<prev [Date] next>
[Advanced]
 <prev [Thread] next>

[Veritas-bu] Exchange 2007 fails status 5 for NBU 6.5.3

2009-04-01 17:50:00
Subject: [Veritas-bu] Exchange 2007 fails status 5 for NBU 6.5.3
From: Todd Jackxon <netbackup.info AT gmail DOT com>
To: veritas-bu AT mailman.eng.auburn DOT edu
Date: Wed, 1 Apr 2009 17:46:40 -0400
Hello All,
 
This problem is really beating me. We have Netbackup 6.5.3 on a Windows Master
and the Exchange 2007 client server. I am using the virtual name (shortname) to back
up the client. I can restore the client in one location but in two other locations the job fails
with a status 5. All 3 locations are close to the same setup. I am attaching a tar log of a failed client.
The Exchange Admins are setting up the RSG correctly.
 
It appears that some data is restoring but it will not pull the Logs. It fails before restoring the logs.
 
A few facts
 
1- using virtual name of cluster to back up Exchange data
2- Using an authorized domain administrative and Exchange administrative user for NBU service.
 
 
NOTE:  Just tested that 1st location and it now fails. The time it succeeded we restored SG01 .. all other attempts have
been to SG02 or SG03 .. strange
 
 
 
Thanks
Todd
 
 
 
 
Detailed status info from some failure jobs
==============================
* You must restore the logs from the last backup set for Microsoft Information Store:\xxx01-SG03\ by itself for proper recovery.
 
 
*(tar did not find all the files to be restored(185)); restore time 00:03:46
 
 
* unable to create object for restore: Microsoft Information Store:\xxx01-SG03\Log files_1238392377 (BEDS 0xE000032F: The restore failed because a connection could not be made to the Microsoft Extensible Storage Engine (ESE) to load the restore environment. Make sure that there are no other restore operations in process, and then try this operation again.)
 
 
*unable to create object for restore: Microsoft Information Store:\xxx01-SG03\FRD01-DB03 (BEDS 0xE000FF00: Unable to restore some Microsoft Exchange components because one or more of the databases are currently mounted or because the Recovery Storage Group is not properly configured. Use the Exchange System Manager to check the configuration of the Recovery Stora)
=====================================================================================
 
 
 
 
==================================================================================================
TAR LOG
 

1:12:51.986 PM: [6524.9500] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 30 seconds

1:14:29.330 PM: [6524.8984] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForWrite() FS_EXTENDED_ERROR on FS_CreateObj() Result:0xE000FF00 Action:0x1 Error_id:0x3EAD

1:14:29.330 PM: [6524.8984] <2> tar_base::V_vTarMsgW: ERR - unable to create object for restore: Microsoft Information Store:\xxx01-SG03\xxx01-DB03 (BEDS 0xE000FF00: Unable to restore some Microsoft Exchange components because one or more of the databases are currently mounted or because the Recovery Storage Group is not properly configured. Use the Exchange System Manager to check the configuration of the Recovery Stora)

1:14:53.111 PM: [6524.8984] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForWrite():FS_CreateObj() Failed! (0xE000032F:The restore failed because a connection could not be made to the Microsoft Extensible Storage Engine (ESE) to load the restore environment.

Make sure that there are no other restore operations in process, and then try this operation again.

)

1:14:53.111 PM: [6524.8984] <2> tar_base::V_vTarMsgW: ERR - unable to create object for restore: Microsoft Information Store:\xxxx01-SG03\Log files_1238502346 (BEDS 0xE000032F: The restore failed because a connection could not be made to the Microsoft Extensible Storage Engine (ESE) to load the restore environment.

Make sure that there are no other restore operations in process, and then try this operation again.

)

1:14:59.174 PM: [6524.8984] <2> tar_base::V_vTarMsgW: INF - Commit is set, but no log files are included in this restore request. Log files are required to perform Exchange database recovery.

1:14:59.174 PM: [6524.8984] <2> tar_base::V_vTarMsgW: WRN - You must restore the logs from the last backup set for Microsoft Information Store:\xxx01-SG03\ by itself for proper recovery.

1:14:59.174 PM: [6524.8984] <2> tar_base::backup_finish: TAR - restore: 4 files

1:14:59.174 PM: [6524.8984] <2> tar_base::backup_finish: TAR - restore: file data: 620538040 bytes

1:14:59.174 PM: [6524.8984] <2> tar_base::backup_finish: TAR - restore: image data: 620544512 bytes

1:14:59.174 PM: [6524.8984] <2> tar_base::backup_finish: TAR - restore: elapsed time: 128 secs 4848004 bps

1:14:59.174 PM: [6524.8984] <8> tar_restore::processStateEOT: WRN - processStateEOT before keepalive

1:14:59.174 PM: [6524.9500] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)

1:14:59.174 PM: [6524.8984] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)

1:14:59.174 PM: [6524.8984] <2> tar_base::V_Close:

1:14:59.174 PM: [6524.8924] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1

1:14:59.299 PM: [6524.8984] <4> OVStopCmd: INF - EXIT - status = 0

1:14:59.299 PM: [6524.8984] <4> OVShutdown: INF - Finished process

1:14:59.299 PM: [6524.8984] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\tar32.exe

1:15:01.299 PM: [6524.8984] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\TAR\033109.LOG

6:03:24.799 PM: [9876.8408] <4> ov_log::OVInit: INF - Starting log file: C:\Program Files\VERITAS\NetBackup\logs\TAR\033109.LOG

6:03:24.799 PM: [9876.8408] <4> ov_log::OVInit: GENERAL Log Level: 0

6:03:24.799 PM: [9876.8408] <4> ov_log::OVInit: TCP Log Level: 0

6:03:24.799 PM: [9876.8408] <4> ov_log::OVInit: INF - the log mutex: 764

TAR NetBackup TAR 6.5GA [Oct 30 2008]

Copyright 1993 - 2007 VERITAS Software Corporation

All Rights Reserved.

6:03:24.799 PM: [9876.8408] <2> WinMain: DAT - _pgmptr = 'C:\Program Files\VERITAS\NetBackup\bin\tar32.exe'

6:03:24.799 PM: [9876.8408] <2> WinMain: DAT - lpCmdLine = '-x -v -Y -p -P -I 1238536976 -U 16 -k -Q -J clnt_lc_messages=en -J clnt_lc_time=en -J clnt_lc_ctype=en -J clnt_lc_collate=en -J clnt_lc_numeric=en -J restoreid=182529.001 -J job_total=1 -J client=xxxxxxxxxxxx -J requesting_client=xxxxxxxxxxxx -J browse_client=xxxxx -J commit=1 -J dir_byte_count=7 -J temp_dir=/C/temp -J mount_database=1 -J backup_time=1238502346 -L /D/Programà€ Files/VERITAS/NetBackup/logs/user_ops/c-a_t000/logs/NBWIN007 -f - '

6:03:24.799 PM: [9876.8408] <2> date_debug: DAT - timezone: Eastern Standard Time, offset=18000, dst: Eastern Daylight Time

6:03:24.799 PM: [9876.8408] <2> date_debug: DAT - current time: 1238537004, 3/31/2009 6:03:24 PM

6:03:24.799 PM: [9876.8408] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 12/31/1993 8:00:00 PM

6:03:24.799 PM: [9876.8408] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 6/30/1994 8:00:00 PM

6:03:25.783 PM: [9876.8408] <2> WinMain: DAT - standard input handle = 736

6:03:25.783 PM: [9876.8408] <2> WinMain: DAT - standard output handle = 732

6:03:25.783 PM: [9876.8408] <2> WinMain: DAT - standard error handle = 728

6:03:25.783 PM: [9876.8408] <4> backup_list: INF - creating restore object

6:03:25.783 PM: [9876.8408] <4> tar_restore_tfi::create: INF - dwJobData: ffffffff

6:03:25.783 PM: [9876.8408] <4> tar_restore_tfi::create: INF - dwJob: ffffffff

6:03:25.783 PM: [9876.8408] <4> base_restore::tfs_enable_restore_privileges: INF - restore privileges enabled, previous = 0

6:03:25.783 PM: [9876.8408] <4> base_restore::tfs_enable_restore_privileges: INF - backup privileges enabled, previous = 0

6:03:25.783 PM: [9876.8408] <4> base_restore::tfs_enable_restore_privileges: INF - security privileges enabled, previous = 0

6:03:25.783 PM: [9876.8408] <4> base_restore::tfs_enable_restore_privileges: INF - create token privileges enabled, previous = 0

6:03:25.783 PM: [9876.8408] <4> base_restore::tfs_enable_restore_privileges: INF - take ownership privileges enabled, previous = 0

6:03:25.893 PM: [9876.8408] <4> OVGetRestoreToken: INF - successfully wrote restore information to named pipe

6:03:25.893 PM: [9876.8408] <4> base_restore::tfs_enable_restore_privileges: INF - no access token: server directed restore

6:03:25.924 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0

6:03:25.955 PM: [9876.9116] <2> ov_log::V_GlobalLog: WRN - BEDS_Init() Microsoft Exchange Messaging API and Collaboration Data Objects package is required for Mailbox level backup and restore. This package can be found on the Microsoft Web Site.

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E:

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: F:

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Q:

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: R:

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: \\xxxxxail01\Microsoft Exchange Mailboxes

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: \\xxxxxxail01\Microsoft Exchange Public Folders

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: \\xxxxxx01\Microsoft Information Store

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxx\Microsoft Information Store\FRD01-SG01

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxil01\Microsoft Information Store\FRD01-SG02

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxil01\Microsoft Information Store\FRD01-SG03

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxxil01\Microsoft Information Store\FRD01-SG04

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxxxl01\Microsoft Information Store\FRD01-SG05

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\fxxxxxxx1\Microsoft Information Store\FRD01-SG06

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\fxxxxxxxxx01\Microsoft Information Store\FRD01-SG07

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxxxxx\Microsoft Information Store\FRD01-SG08

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxx\Microsoft Information Store\FRD01-SG09

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxxx1\Microsoft Information Store\FRD01-SG10

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxx1\Microsoft Information Store\FRD01-SG11

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\fxxxxxxxl01\Microsoft Information Store\FRD01-SG12

6:03:34.049 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - DumpChildDleInfo() Child DLE Device Name: \\xxxxxxxx01\Microsoft Information Store\Recovery Storage Group

6:03:34.893 PM: [9876.8408] <4> windows_restore::V_Initialize: INF - Exchange Restore - Disabling Rename If Exists.

6:03:34.893 PM: [9876.5052] <4> tar_base::keepaliveThread: INF - keepalive thread is active with an interval of 30 seconds

6:05:14.408 PM: [9876.8408] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForWrite() FS_EXTENDED_ERROR on FS_CreateObj() Result:0xE000FF00 Action:0x1 Error_id:0x3EAD

6:05:14.408 PM: [9876.8408] <2> tar_base::V_vTarMsgW: ERR - unable to create object for restore: Microsoft Information Store:\xxxx01-SG03\FRD01-DB03 (BEDS 0xE000FF00: Unable to restore some Microsoft Exchange components because one or more of the databases are currently mounted or because the Recovery Storage Group is not properly configured. Use the Exchange System Manager to check the configuration of the Recovery Stora)

6:05:38.330 PM: [9876.8408] <2> ov_log::V_GlobalLogEx: ERR - beds_ese_access::V_OpenForWrite():FS_CreateObj() Failed! (0xE000032F:The restore failed because a connection could not be made to the Microsoft Extensible Storage Engine (ESE) to load the restore environment.

Make sure that there are no other restore operations in process, and then try this operation again.

)

6:05:38.330 PM: [9876.8408] <2> tar_base::V_vTarMsgW: ERR - unable to create object for restore: Microsoft Information Store:\xxxx01-SG03\Log files_1238502346 (BEDS 0xE000032F: The restore failed because a connection could not be made to the Microsoft Extensible Storage Engine (ESE) to load the restore environment.

Make sure that there are no other restore operations in process, and then try this operation again.

)

6:05:44.408 PM: [9876.8408] <2> tar_base::V_vTarMsgW: INF - Commit is set, but no log files are included in this restore request. Log files are required to perform Exchange database recovery.

6:05:44.408 PM: [9876.8408] <2> tar_base::V_vTarMsgW: WRN - You must restore the logs from the last backup set for Microsoft Information Store:\xxxx01-SG03\ by itself for proper recovery.

6:05:44.408 PM: [9876.8408] <2> tar_base::backup_finish: TAR - restore: 4 files

6:05:44.408 PM: [9876.8408] <2> tar_base::backup_finish: TAR - restore: file data: 620538040 bytes

6:05:44.408 PM: [9876.8408] <2> tar_base::backup_finish: TAR - restore: image data: 620544512 bytes

6:05:44.408 PM: [9876.8408] <2> tar_base::backup_finish: TAR - restore: elapsed time: 130 secs 4773419 bps

6:05:44.408 PM: [9876.8408] <8> tar_restore::processStateEOT: WRN - processStateEOT before keepalive

6:05:44.408 PM: [9876.5052] <4> tar_base::keepaliveThread: INF - keepalive thread terminating (reason: WAIT_OBJECT_0)

6:05:44.408 PM: [9876.8408] <4> tar_base::stopKeepaliveThread: INF - keepalive thread has exited. (reason: WAIT_OBJECT_0)

6:05:44.408 PM: [9876.8408] <2> tar_base::V_Close:

6:05:44.408 PM: [9876.9116] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1

6:05:44.533 PM: [9876.8408] <4> OVStopCmd: INF - EXIT - status = 0

6:05:44.533 PM: [9876.8408] <4> OVShutdown: INF - Finished process

6:05:44.533 PM: [9876.8408] <4> WinMain: INF - Exiting C:\Program Files\VERITAS\NetBackup\bin\tar32.exe

6:05:46.533 PM: [9876.8408] <4> ov_log::OVClose: INF - Closing log file: C:\Program Files\VERITAS\NetBackup\logs\TAR\033109.LOG

_______________________________________________
Veritas-bu maillist  -  Veritas-bu AT mailman.eng.auburn DOT edu
http://mailman.eng.auburn.edu/mailman/listinfo/veritas-bu
[More with this subject...]
<Prev in Thread] Current Thread [Next in Thread>
  • [Veritas-bu] Exchange 2007 fails status 5 for NBU 6.5.3, Todd Jackxon <=
Previous by Date:  Re: [Veritas-bu] "Stuck Media", Peacock Dennis - dpeaco
Next by Date:  Re: [Veritas-bu] Vaulting from disk storage groups, Peters, Devon C
Previous by Thread:  [Veritas-bu] (no subject), McDonald II, James F.
Next by Thread:  Re: [Veritas-bu] Vaulting from disk storage groups, Peters, Devon C
Indexes:  [Date] [Thread] [Top] [All Lists]