[10:34:40.096] Restore job [JobID=1024] has report file : C:\Program Files\NetApp\SnapManager for Exchange\Report\Restore [VDUEEXMB001]\06-30-2009_10.34.36.txt. [10:34:40.112] Starting restore from Server... [10:34:40.112] *** RESTORE JOB DESCRIPTION [10:34:40.112] Restore Exchange Server: VDUEEXMB001 [10:34:40.112] The number of databases to be restored: 1 [10:34:40.112] Database Name - 1: MBDSDEU07 [10:34:40.112] Restore from Primary Storage: Yes [10:34:40.112] This is a point-in-time restore! [10:34:40.112] This is not a test restore. [10:34:40.112] Restore to Recovery Storage Group: SGDSDEU07-RSG [10:34:40.112] Transaction log sequences and database metadata verification before restore: Yes. [10:34:40.112] Exhaustive verification will not run before restore. [10:34:40.112] The snapshot has been verified. [10:34:40.112] Stores will be mounted after restore. [10:34:40.112] Move mailboxes to the restored target database after restore: No [10:34:40.112] Restore will use the following SnapInfo file: [10:34:40.112] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\SnapInfo__06-30-2009_09.57.34.sme [10:34:40.112] Job Management is enabled : YES [10:34:40.112] Restore Time Stamp: 06-30-2009_10.34.36 [10:34:40.112] ***SNAPMANAGER RESTORE JOB FOR EXCHANGE SERVER [VDUEEXMB001 - Version 8.1 (Build 240.6)] STARTED AT: [06-30-2009 10.34.40] [10:34:40.112] Start VSS Restore from Server... [10:34:40.112] Getting Cluster Virtual Server Owner Node... [10:34:40.143] Verifying Exchange server VDUEEXMB001... [10:34:40.268] [PowerShell Cmdlet]: get-exchangeserver -identity "VDUEEXMB001" [10:34:40.283] [PowerShell Cmdlet]: Get-Mailboxserver -Identity "VDUEEXMB001" [10:34:40.299] [PowerShell Cmdlet]: (get-exchangeserver -identity VDUEEXMB001).Get_AdminDisplayVersion() [10:34:40.299] Successfully got Exchange version number [using PowerShell]. [10:34:40.299] Exchange Server [Version 8.1 (Build 240.6)] [10:34:40.315] ***PREPARING RESTORE [10:34:40.315] Getting SnapManager backup information from SnapInfo file... [10:34:43.325] Checking SnapManager License on M:\SGDSDEU07\SGDSDEU07\MBDSDEU07.edb... [10:34:43.325] Checking SnapRestore License on M:\SGDSDEU07\SGDSDEU07\MBDSDEU07.edb... [10:34:48.878] Retrieving SnapManager backup information completed successfully. [10:34:48.878] Target Exchange storage group name to be restored: [SGDSDEU07-RSG] [10:34:48.878] ***VERIFY EXCHANGE STORE FOR RESTORE TO ALTERNATE LOCATION/RSG: SGDSDEU07 [10:34:48.878] ***LOG/DATABASE VERIFICATION: SGDSDEU07 [10:34:48.878] Verifying logs by dumping headers... [10:34:48.878] ***TESTING BACKUP LOG FILE [10:34:48.878] The first transaction log available for restore is E0B0000006D.log. [10:34:48.878] Testing backup [T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B0000006D.log]... [10:34:48.878] Testing backup [T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B0000006E.log]... [10:34:48.878] Testing backup [T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B0000006F.log]... [10:34:48.894] Testing backup [T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B00000070.log]... [10:34:48.894] Testing backup [T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B00000071.log]... [10:34:48.894] Testing backup [T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B00000072.log]... [10:34:48.894] Verifying Exchange storage group backup dataset successfully completed. [10:34:49.019] [PowerShell Cmdlet]: get-exchangeserver -status -identity VDUEEXMB001 [10:34:49.081] [PowerShell Cmdlet]: Get-StorageGroup -Server VDUEEXMB001 -DomainController "SDUEDCRE001.res.sbstahl.local" [10:34:49.112] No Recovery Storage Group exists on VDUEEXMB001 [10:34:53.121] Mounting Snapshot [exchsnap__vdueexmb001__recent] for LUN M:\SGDSDEU07\ [10:35:07.051] This Snapshot is mounted as the drive [X]. [10:35:07.051] Mount Snapshot succeeded. [10:35:11.060] Mounting Snapshot [exchsnap__vdueexmb001__recent] for LUN T:\SGDSDEU07\ [10:35:24.256] This Snapshot is mounted as the drive [W]. [10:35:24.256] Mount Snapshot succeeded. [10:35:24.256] Preparing to create new storage group... [10:35:24.256] Copying log files from SnapInfo to _restoredLogs directory... [10:35:24.256] Restore Log Files... [10:35:24.256] Copying Log file from: [10:35:24.256] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B0000006D.log [10:35:24.256] Copying Log file to: [10:35:24.256] W:\SGDSDEU07\_restoredLogs\E0B0000006D.log [10:35:24.272] Copying Log file from: [10:35:24.272] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B0000006E.log [10:35:24.272] Copying Log file to: [10:35:24.272] W:\SGDSDEU07\_restoredLogs\E0B0000006E.log [10:35:24.272] Copying Log file from: [10:35:24.272] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B0000006F.log [10:35:24.272] Copying Log file to: [10:35:24.272] W:\SGDSDEU07\_restoredLogs\E0B0000006F.log [10:35:24.288] Copying Log file from: [10:35:24.288] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B00000070.log [10:35:24.288] Copying Log file to: [10:35:24.288] W:\SGDSDEU07\_restoredLogs\E0B00000070.log [10:35:24.288] Copying Log file from: [10:35:24.288] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B00000071.log [10:35:24.288] Copying Log file to: [10:35:24.288] W:\SGDSDEU07\_restoredLogs\E0B00000071.log [10:35:24.303] Copying Log file from: [10:35:24.303] T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Logs\E0B00000072.log [10:35:24.303] Copying Log file to: [10:35:24.303] W:\SGDSDEU07\_restoredLogs\E0B00000072.log [10:35:24.303] WARNING: Unable to locate E0B.log and E0Btmp.log files from log file directory: [10:35:24.303] W:\SGDSDEU07\_restoredLogs\E0B.log [10:35:24.303] W:\SGDSDEU07\_restoredLogs\E0Btmp.log [10:35:24.303] You may not recover all your data since not all transaction log files are available for the restore. [10:35:24.303] SnapManager will automatically rename the highest generation log available to E0n.log before the recovery. [10:35:24.303] If you have extra log files you need to recover, you can: [10:35:24.303] 1. Do not select "Mount databases automatically after restore". [10:35:24.303] 2. After SnapManager restore, manually copy extra log files to W:\SGDSDEU07\_restoredLogs. [10:35:24.303] 3. Run Exchange database recovery manually. [10:35:24.303] WARNING: The highest generation log available for restore is E0B00000072.log, SnapManager will use this log file to create a new temp log file before recovery. [10:35:24.303] Copying E0B00000072.log file to E0Btmp.log... [10:35:24.303] W:\SGDSDEU07\_restoredLogs\E0B00000072.log [10:35:24.303] W:\SGDSDEU07\_restoredLogs\E0Btmp.log [10:35:24.319] Creating a new storage group [SGDSDEU07-RSG] on VDUEEXMB001... [10:35:24.428] [PowerShell Cmdlet]: (get-exchangeserver -status -identity VDUEEXMB001).get_CurrentConfigDomainController() [10:35:24.506] [PowerShell Cmdlet]: New-StorageGroup -Server "VDUEEXMB001" -Name "SGDSDEU07-RSG" -Recovery -LogFolderPath "W:\SGDSDEU07\" -SystemFolderPath "W:\SGDSDEU07\" -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:24.818] Name Server Replicated Recovery ---- ------ ---------- -------- SGDSDEU07-RSG VDUEEXMB001 None True [10:35:24.818] SGDSDEU07-RSG is created on VDUEEXMB001 successfully [10:35:24.818] Creating a new database [MBDSDEU07]... [10:35:24.818] [PowerShell Cmdlet]: Get-MailboxDatabase -identity "VDUEEXMB001\SGDSDEU07\MBDSDEU07" -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:24.834] [PowerShell Cmdlet]: New-MailboxDatabase -StorageGroup "VDUEEXMB001\SGDSDEU07-RSG" -MailboxDatabaseToRecover "VDUEEXMB001\SGDSDEU07\MBDSDEU07" -EDBFilePath "X:\SGDSDEU07\MBDSDEU07.edb" -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:26.019] Name Server StorageGroup Recovery ---- ------ ------------ -------- MBDSDEU07 VDUEEXMB001 SGDSDEU07-RSG True [10:35:26.035] Setting database overwrite by restore... [10:35:26.035] [PowerShell Cmdlet]: set-MailboxDatabase -Identity "VDUEEXMB001\SGDSDEU07-RSG\MBDSDEU07" -allowfilerestore:$true -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:26.050] ***STARTING VSS RESTORE PROCESS... [10:35:26.050] Building VSS backup document path... [10:35:26.050] Loading backup document metadata: SGDSDEU07 [10:35:26.066] Backup document was loaded from T:\SGDSDEU07\SGDSDEU07\SME_SnapInfo\EXCH__VDUEEXMB001\SG__SGDSDEU07\VDUEEXMB001__recent\Vss__06-30-2009_09.57.34.xml [10:35:26.066] Initializing VSS for restore... [10:35:26.066] Initialize for restore succeeded. [10:35:26.066] Querying extended VSS backup components interface... [10:35:26.066] ***GATHER EXCHANGE WRITER METADATA [10:35:27.080] Identifying the Exchange writer... [10:35:27.080] Totally 11 VSS writer detected... [10:35:27.236] Exchange writer instance [Exchange Information Store] identified. [10:35:27.236] The extended writer instance Id: [{8E5E4534-83FC-4B30-8396-357A33814A96}] [10:35:27.236] ***SELECT EXCHANGE COMPONENT FOR RESTORE [10:35:27.267] Component "868b564e-57fb-4153-a62a-7acd679511c3" is selected for restore. [10:35:27.267] Logical path: [Microsoft Exchange Server\Microsoft Information Store\VDUEEXMB001] [10:35:27.267] Setting select for restore using Exchange writer instance... [10:35:27.267] Setting restore options to restore the backup to a different storage group... [10:35:27.267] Retrieving restore option XML file... [10:35:27.407] [PowerShell Cmdlet]: (get-exchangeserver -status -identity VDUEEXMB001).get_CurrentConfigDomainController() [10:35:27.423] [PowerShell Cmdlet]: Get-StorageGroup -identity "VDUEEXMB001\SGDSDEU07-RSG" -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:27.423] [PowerShell Cmdlet]: Get-MailboxDatabase -identity "VDUEEXMB001\SGDSDEU07-RSG\MBDSDEU07" -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:27.439] Setting restore options... [10:35:27.439] Setting new target database location for restore... [10:35:27.454] ***VSS PRE-RESTORE IN PROGRESS [10:35:28.484] Status after PreRestore (11 writers) [10:35:28.484] Status for writer System Writer: STABLE(0x00000000) [10:35:28.484] Status for writer ASR Writer: STABLE(0x00000000) [10:35:28.484] Status for writer Microsoft Exchange Writer: STABLE(0x00000000) [10:35:28.484] Status for writer Shadow Copy Optimization Writer: STABLE(0x00000000) [10:35:28.484] Status for writer Registry Writer: STABLE(0x00000000) [10:35:28.484] Status for writer COM+ REGDB Writer: STABLE(0x00000000) [10:35:28.484] Status for writer Cluster Database: STABLE(0x00000000) [10:35:28.484] Status for writer IIS Config Writer: STABLE(0x00000000) [10:35:28.484] Status for writer WMI Writer: STABLE(0x00000000) [10:35:28.484] Status for writer BITS Writer: STABLE(0x00000000) [10:35:28.484] Status for writer IIS Metabase Writer: STABLE(0x00000000) [10:35:28.484] Restore Target = Original [10:35:28.484] Setting component restore status before snaprestore... [10:35:28.484] LogicalPath = Microsoft Exchange Server\Microsoft Information Store\VDUEEXMB001 [10:35:28.484] ComponentName = 868b564e-57fb-4153-a62a-7acd679511c3 [10:35:28.484] Setting component post restore status... [10:35:28.484] ***VSS POST-RESTORE IN PROGRESS [10:35:29.513] Status after PostRestore (11 writers) [10:35:29.513] Status for writer System Writer: STABLE(0x00000000) [10:35:29.513] Status for writer ASR Writer: STABLE(0x00000000) [10:35:29.513] Status for writer Microsoft Exchange Writer: STABLE(0x00000000) [10:35:29.513] Status for writer Shadow Copy Optimization Writer: STABLE(0x00000000) [10:35:29.513] Status for writer Registry Writer: STABLE(0x00000000) [10:35:29.513] Status for writer COM+ REGDB Writer: STABLE(0x00000000) [10:35:29.513] Status for writer Cluster Database: STABLE(0x00000000) [10:35:29.513] Status for writer IIS Config Writer: STABLE(0x00000000) [10:35:29.513] Status for writer WMI Writer: STABLE(0x00000000) [10:35:29.513] Status for writer BITS Writer: STABLE(0x00000000) [10:35:29.513] Status for writer IIS Metabase Writer: STABLE(0x00000000) [10:35:29.513] Starting the Exchange stores... [10:35:29.638] [PowerShell Cmdlet]: get-exchangeserver -status -identity VDUEEXMB001 [10:35:29.654] [PowerShell Cmdlet]: Get-StorageGroup -Server VDUEEXMB001 -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:29.685] [PowerShell Cmdlet]: Get-MailboxDatabase -Storagegroup "VDUEEXMB001\SGDSDEU07-RSG" -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:29.685] [PowerShell Cmdlet]: Mount-Database -Identity "VDUEEXMB001\SGDSDEU07-RSG\MBDSDEU07" -confirm:$false -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:30.902] Adding "Disk X:\" to Physical Disk Resource of VDUEEXMB001 ... [10:35:33.304] Failed to add physical drive resource "Disk X:\" to cluster group VDUEEXMB001. [10:35:34.318] Failed to remove "Disk X:\" resource from cluster group. [10:35:34.333] Failed to remove "Disk W:\" resource from cluster group. [10:35:34.333] Removing newly created storage group on VDUEEXMB001... [10:35:34.458] [PowerShell Cmdlet]: (get-exchangeserver -status -identity VDUEEXMB001).get_CurrentConfigDomainController() [10:35:34.474] [PowerShell Cmdlet]: Remove-MailboxDatabase -Identity "VDUEEXMB001\SGDSDEU07-RSG\MBDSDEU07" -confirm:$false -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:35.488] [PowerShell Cmdlet]: Remove-StorageGroup -Identity "VDUEEXMB001\SGDSDEU07-RSG" -confirm:$false -DomainController "SDUEDCRE001.res.sbstahl.local" [10:35:39.496] Dismounting LUN X of Snapshot [exchsnapshot]... [10:35:50.041] Dismount snapshot successfully completed. [10:35:53.473] Dismounting LUN W of Snapshot [exchsnapshot]... [10:36:04.252] Dismount snapshot successfully completed. [10:36:06.592] ***SNAPMANAGER RESTORE JOB ENDED AT: [06-30-2009 10.36.06] [10:36:06.592] Failed to restore storage group. [10:36:06.592] Checking virtual server state... [10:36:06.638] Warning: the state of Exchange virtual server VDUEEXMB001 is not online. [10:36:06.638] At least one resource in the group has failed. **** RESTORE RESULT SUMMARY***** Restore failed. Error Code: 0x139A The cluster resource could not be brought online by the resource monitor.