[5/11/2009 6:17:00 PM, (13)] Exchange Name: exch07prod1 [5/11/2009 6:17:00 PM, (13)] Business Continuance Plan: WALPROD to BOSDR FAILOVER [5/11/2009 6:17:00 PM, (13)] Business Continuance Plan Path: \\EXCH07DRCLUS1\SMEBCFolder\exch07prod1#exch07clus1#EXCH07DRPROD1#WALPROD to BOSDR FAILOVER.xml [5/11/2009 6:17:00 PM, (13)] From: exch07clus1, To: EXCH07DRPROD1 [5/11/2009 6:17:00 PM, (13)] Source: walprod,Destination: bosdr [5/11/2009 6:17:00 PM, (13)] The following tasks will be executed. Cleanup of Business Continuance Destination Offline Exchange & Update SnapMirror Quisce and Break SnapMirrors ReConnect Exchange LUNs Exchange Instance Recreation Restore backups [5/11/2009 6:17:00 PM, (13)] Initializing Business continuance information [5/11/2009 6:17:00 PM, (13)] Initializing the DR Operations Manager [5/11/2009 6:17:00 PM, (13)] Retrieving information from the Business Continuance Plan: [Processing] [5/11/2009 6:17:00 PM, (13)] Retrieving information from the Business Continuance Plan: [Completed] [5/11/2009 6:17:00 PM, (13)] Loading information from SnapManager Configuration file: [Processing] [5/11/2009 6:17:00 PM, (13)] Loading information from SnapManager Configuration file: [Completed] [5/11/2009 6:17:00 PM, (13)] Initializing LUN information in the host [5/11/2009 6:17:00 PM, (13)] Enumerating LUNs connected to the host:[Processing] [5/11/2009 6:17:02 PM, (13)] Enumerating LUNs connected to the host:[Completed] [5/11/2009 6:17:02 PM, (13)] Constructing the volume layout: [Processing] [5/11/2009 6:17:02 PM, (13)] Constructing the volume layout: [Completed] [5/11/2009 6:17:02 PM, (13)] Retrieving the current state of the host:[Processing] [5/11/2009 6:17:02 PM, (13)] [lun: /vol/exch07prod1_log_mirvol/exch07prod1_log_qt/exch07prod1_log_lun, storage system: bosstor1, host mount point: L] [5/11/2009 6:17:02 PM, (13)] [lun: /vol/exch07clus1_sg1db_mirvol/exch07clus1_sg1db_qt/exch07clus1_sg1db_lun, storage system: bosstor1, host mount point: R] [5/11/2009 6:17:02 PM, (13)] Retrieving the current state of the host:[Completed] [5/11/2009 6:17:02 PM, (13)] Target Host: [EXCH07DRCLUS1] [5/11/2009 6:17:02 PM, (13)] Initialization of Business continuance information completed [5/11/2009 6:17:02 PM, (13)] Checking the business continuance information... [5/11/2009 6:17:02 PM, (13)] It is not a storage only failover scenario. [5/11/2009 6:17:02 PM, (13)] ------------------------------------------------------------------------- [5/11/2009 6:17:02 PM, (13)] *** Initializing workflow: [Business Continuance Plan Execution] [5/11/2009 6:17:02 PM, (13)] ------------------------------------------------------------------------- [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Cleanup of Business Continuance Destination] [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Cleanup of Business Continuance Destination] completed [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Offline Exchange & Update SnapMirror] [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Offline Exchange & Update SnapMirror] completed [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Quisce and Break SnapMirrors] [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Quisce and Break SnapMirrors] completed [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [ReConnect Exchange LUNs] [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [ReConnect Exchange LUNs] completed [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Exchange Instance Recreation] [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Exchange Instance Recreation] completed [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Restore backups] [5/11/2009 6:17:02 PM, (13)] *** Initializing task: [Restore backups] completed [5/11/2009 6:17:02 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:17:02 PM, (31)] *** Executing workflow: [Business Continuance Plan Execution] [5/11/2009 6:17:02 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:17:02 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:17:02 PM, (31)] *** Executing task: [Cleanup of Business Continuance Destination] [5/11/2009 6:17:02 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:17:02 PM, (31)] Retrieving information from the Business continuance plan [5/11/2009 6:17:02 PM, (31)] Completed retrieving information from the Business continuance plan [5/11/2009 6:17:02 PM, (31)] Clearing Volume Mount point directories and Registry settings for volumes that are no longer in the system [processing] [5/11/2009 6:17:02 PM, (31)] Clearing Volume Mount point directories and Registry settings for volumes that are no longer in the system [completed] [5/11/2009 6:17:02 PM, (31)] Cleaning up Exchange Cluster Resources [processing] [5/11/2009 6:17:02 PM, (31)] Retrieving information from the Business continuance plan [5/11/2009 6:17:02 PM, (31)] Failed to offline Resource Group [exch07prod1] [5/11/2009 6:17:02 PM, (31)] Determining if /clearlocalcms needs to be run [5/11/2009 6:17:02 PM, (31)] Initializing information for /ClearLocalCMS [processing] [5/11/2009 6:17:02 PM, (31)] Initializing information for /ClearLocalCMS [completed] [5/11/2009 6:17:02 PM, (31)] Invoking Exchange 2007 Setup.com /ClearLocalCMS [processing] [5/11/2009 6:17:02 PM, (31)] Invoking Setup.com /ClearLocalCMS. [5/11/2009 6:17:02 PM, (31)] Path: D:\Program Files\Microsoft\Exchange Server\bin\Setup.com [5/11/2009 6:17:02 PM, (31)] Arguments: /ClearLocalCMS [5/11/2009 6:17:02 PM, (31)] Invoking Setup.com [5/11/2009 6:17:02 PM, (31)] Initializing Output Read pipe to Setup.com [5/11/2009 6:17:02 PM, (31)] Initializing Error Read pipe to Setup.com [5/11/2009 6:17:02 PM, (31)] Waiting for Setup.com /clearlocalcms to complete [5/11/2009 6:17:02 PM, (17)] Welcome to Microsoft Exchange Server 2007 Unattended Setup [5/11/2009 6:17:02 PM, (17)] Preparing Exchange Setup [5/11/2009 6:17:05 PM, (15)] Please specify an existing server role(s) to uninstall. [5/11/2009 6:17:05 PM, (17)] Exchange Server setup encountered an error. [5/11/2009 6:17:05 PM, (31)] Setup.com /clearlocalcms completed with Return code: [1] [5/11/2009 6:17:05 PM, (31)] Setup.com /clearlocalcms completed with errors. Please check the Windows Event Log for more details.[This error will be ignored !][Error Code: 1] Error Information: Please specify an existing server role(s) to uninstall. [5/11/2009 6:17:05 PM, (31)] Invoking Exchange 2007 Setup.com /ClearLocalCMS [completed] [5/11/2009 6:17:05 PM, (31)] Cleanup of Recovery Destination completed : [Completed] [5/11/2009 6:17:05 PM, (31)] Determining if Resource Group Name has changed [processing] [5/11/2009 6:17:05 PM, (31)] Retrieving information from the Business continuance plan [5/11/2009 6:17:06 PM, (31)] Determining if Resource Group Name has changed [completed] [5/11/2009 6:17:06 PM, (31)] Completed retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:06 PM, (31)] There are no cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:06 PM, (31)] Cleaning up Exchange Cluster Resources [completed] [5/11/2009 6:17:06 PM, (31)] Online disk resources [processing] [5/11/2009 6:17:07 PM, (31)] Completed retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:07 PM, (31)] There are no cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:07 PM, (31)] Online disk resources [completed] [5/11/2009 6:17:32 PM, (31)] ***Resource Group details****************************** [5/11/2009 6:17:32 PM, (31)] Completed retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:32 PM, (31)] There are no cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:32 PM, (31)] ******************************************************* [5/11/2009 6:17:32 PM, (31)] Reinitializing the Host LUN information [processing] [5/11/2009 6:17:32 PM, (31)] Initializing LUN information in the host [5/11/2009 6:17:32 PM, (31)] Enumerating LUNs connected to the host:[Processing] [5/11/2009 6:17:34 PM, (31)] Enumerating LUNs connected to the host:[Completed] [5/11/2009 6:17:34 PM, (31)] Constructing the volume layout: [Processing] [5/11/2009 6:17:34 PM, (31)] Constructing the volume layout: [Completed] [5/11/2009 6:17:34 PM, (31)] Reinitializing the Host LUN information [completed] [5/11/2009 6:17:34 PM, (31)] Reinitializing the Current State of the Host for Cleanup [processing] [5/11/2009 6:17:34 PM, (31)] Retrieving the current state of the host:[Processing] [5/11/2009 6:17:34 PM, (31)] [lun: /vol/exch07prod1_log_mirvol/exch07prod1_log_qt/exch07prod1_log_lun, storage system: bosstor1, host mount point: L] [5/11/2009 6:17:34 PM, (31)] [lun: /vol/exch07clus1_sg1db_mirvol/exch07clus1_sg1db_qt/exch07clus1_sg1db_lun, storage system: bosstor1, host mount point: R] [5/11/2009 6:17:34 PM, (31)] Retrieving the current state of the host:[Completed] [5/11/2009 6:17:34 PM, (31)] Reinitializing the Current State of the Host for Cleanup [completed] [5/11/2009 6:17:34 PM, (31)] Disconnecting LUNs [processing] [5/11/2009 6:17:34 PM, (31)] Cleaning up LUNs in the Recovery Destination [5/11/2009 6:17:34 PM, (31)] Determing LUNs to be cleaned up in the destination : [processing] [5/11/2009 6:17:34 PM, (31)] [Storage System: bosstor1, Lun: /vol/exch07prod1_log_mirvol/exch07prod1_log_qt/exch07prod1_log_lun, Host Mount Point: L] need not be cleaned up. [5/11/2009 6:17:34 PM, (31)] [Storage System: bosstor1, Lun: /vol/exch07clus1_sg1db_mirvol/exch07clus1_sg1db_qt/exch07clus1_sg1db_lun, Host Mount Point: R] need not be cleaned up. [5/11/2009 6:17:34 PM, (31)] Determing LUNs to be cleaned up in the destination : [completed] [5/11/2009 6:17:34 PM, (31)] There are no LUNs to be cleaned up. [5/11/2009 6:17:34 PM, (31)] SmeDROperationsMgr::CleanupLUNs returned null.[No LUNs need to cleaned up before failover.] [5/11/2009 6:17:34 PM, (31)] Disconnecting LUNs [completed] [5/11/2009 6:17:34 PM, (31)] Cleaning up any failed resources [processing] [5/11/2009 6:17:34 PM, (31)] Retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:35 PM, (31)] Completed retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:35 PM, (31)] There are no cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:17:35 PM, (31)] Cleaning up any failed resources [completed] [5/11/2009 6:17:35 PM, (31)] Renaming the Resource Group back (if required) [processing] [5/11/2009 6:17:35 PM, (31)] Renaming Resource Group back ( if required ) [5/11/2009 6:17:35 PM, (31)] Determining if the Resource Group needs to be renamed.. [5/11/2009 6:17:35 PM, (31)] It is an Exchange 2007 CMS.Renaming Resource Group back might be required [5/11/2009 6:17:35 PM, (31)] Renaming the Resource Group back (if required) [completed] [5/11/2009 6:17:35 PM, (31)] *** Execution of Task: [Cleanup of Business Continuance Destination] completed successfully. [5/11/2009 6:17:35 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:17:35 PM, (31)] *** Executing task: [Offline Exchange & Update SnapMirror] [5/11/2009 6:17:35 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:17:35 PM, (31)] Retrieving information from the Business continuance plan [5/11/2009 6:17:35 PM, (31)] Completed retrieving information from the Business continuance plan [5/11/2009 6:17:35 PM, (31)] Offlining Exchange Instance [processing] [5/11/2009 6:17:35 PM, (31)] Checking if Exchange Network Name [exch07prod1] is alive [processing] [5/11/2009 6:17:35 PM, (31)] Checking if Exchange Network Name[ exch07prod1] is alive [completed] [5/11/2009 6:17:35 PM, (31)] Exchange Network Name [exch07prod1] is alive. The Exchange Instance will be offlined. [5/11/2009 6:17:35 PM, (31)] Offlining Exchange: exch07prod1 [processing] [5/11/2009 6:18:04 PM, (31)] Exchange Instance [exch07prod1] has been offlined successfully [5/11/2009 6:18:12 PM, (31)] Offlining Exchange: exch07prod1 [completed] [5/11/2009 6:18:12 PM, (31)] Offlining Exchange Instance [completed] [5/11/2009 6:18:12 PM, (31)] Updating SnapMirror [processing] [5/11/2009 6:18:12 PM, (31)] Attempting to update SnapMirror all for Exchange Volumes [5/11/2009 6:18:12 PM, (31)] Retrieving information from the Business continuance plan [5/11/2009 6:18:12 PM, (31)] Completed retrieving information from the Business continuance plan [5/11/2009 6:18:14 PM, (31)] Completed retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:18:14 PM, (31)] Onlining Resource: Disk L:\\[Type: Physical Disk] [5/11/2009 6:18:15 PM, (31)] Onlining Resource: Disk L:\\[Type: Physical Disk] completed. [5/11/2009 6:18:15 PM, (31)] Onlining Resource: Disk P:\\[Type: Physical Disk] [5/11/2009 6:18:17 PM, (31)] Onlining Resource: Disk P:\\[Type: Physical Disk] completed. [5/11/2009 6:18:17 PM, (31)] Onlining Resource: Disk R:\\[Type: Physical Disk] [5/11/2009 6:18:19 PM, (31)] Onlining Resource: Disk R:\\[Type: Physical Disk] completed. [5/11/2009 6:18:19 PM, (31)] Onlining disk resources for SnapMirror update : [completed] [5/11/2009 6:18:19 PM, (31)] SnapMirror update will be attempted for the following disks..... [5/11/2009 6:18:19 PM, (31)] [lun: /vol/exch07prod1_log_vol/exch07prod1_log_qt/exch07prod1_log_lun, storage system: walstor1, host mount point: L] [5/11/2009 6:18:19 PM, (31)] [lun: /vol/exch07clus1_sg1db_vol/exch07clus1_sg1db_qt/exch07clus1_sg1db_lun, storage system: walstor1, host mount point: R] [5/11/2009 6:18:20 PM, (31)] Updating SnapMirror : [processing] [5/11/2009 6:18:52 PM, (31)] Updating SnapMirror completed successfully [5/11/2009 6:18:52 PM, (31)] Offlining Resource Group [exch07prod1]: [processing] [5/11/2009 6:19:19 PM, (31)] Offlining Resource Group [exch07prod1]: [completed] [5/11/2009 6:19:19 PM, (31)] Updating SnapMirror [completed] [5/11/2009 6:19:19 PM, (31)] *** Execution of Task: [Offline Exchange & Update SnapMirror] completed successfully. [5/11/2009 6:19:19 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:19:19 PM, (31)] *** Executing task: [Quisce and Break SnapMirrors] [5/11/2009 6:19:19 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:19:19 PM, (31)] Retrieving the mirrors to be broken from the Business Continuance Plan. [5/11/2009 6:19:19 PM, (31)] Retrieved mirrors to be broken from the Business Continuance Plan. [5/11/2009 6:19:19 PM, (31)] Breaking SnapMirror Relationships. [5/11/2009 6:19:19 PM, (31)] Determining SnapMirror Relationships that need to be broken: [processing] [5/11/2009 6:19:20 PM, (31)] Mirror: [Mirror: [walstor1:exch07prod1_log_vol=bosstor1:exch07prod1_log_mirvol], Status: eMirrorStatusIdle, State: SNAPMIRROR_STATESNAPMIRRORED]] needs to be broken [5/11/2009 6:19:20 PM, (31)] Mirror: [Mirror: [walstor1:exch07clus1_sg1db_vol=bosstor1:exch07clus1_sg1db_mirvol], Status: eMirrorStatusIdle, State: SNAPMIRROR_STATESNAPMIRRORED]] needs to be broken [5/11/2009 6:19:20 PM, (31)] Determining SnapMirror Relationships that need to be broken: [completed] [5/11/2009 6:19:20 PM, (31)] Number of Mirrors that need to be broken = 2 [5/11/2009 6:19:26 PM, (31)] Completed breaking SnapMirror Relationships. [5/11/2009 6:19:26 PM, (31)] Checking the status of DR SnapMirror Relationships. [5/11/2009 6:19:26 PM, (31)] Mirror: [walstor1:exch07prod1_log_vol=bosstor1:exch07prod1_log_mirvol], Status: eMirrorStatusIdle, State: SNAPMIRROR_STATEBBROKENOFF] [5/11/2009 6:19:26 PM, (31)] Mirror: [walstor1:exch07clus1_sg1db_vol=bosstor1:exch07clus1_sg1db_mirvol], Status: eMirrorStatusIdle, State: SNAPMIRROR_STATEBBROKENOFF] [5/11/2009 6:19:26 PM, (31)] The Task: [Quisce and Break SnapMirrors] completed successfully. All the DR Mirrors are in broken-off state. [5/11/2009 6:19:26 PM, (31)] *** Execution of Task: [Quisce and Break SnapMirrors] completed successfully. [5/11/2009 6:19:26 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:19:26 PM, (31)] *** Executing task: [ReConnect Exchange LUNs] [5/11/2009 6:19:26 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:19:26 PM, (31)] Retrieving information from the Business continuance plan [5/11/2009 6:19:26 PM, (31)] Completed retrieving information from the Business continuance plan [5/11/2009 6:19:26 PM, (31)] Clearing Volume Mount point directories and Registry settings for volumes that are no longer in the system [processing] [5/11/2009 6:19:28 PM, (31)] Clearing Volume Mount point directories and Registry settings for volumes that are no longer in the system [completed] [5/11/2009 6:19:28 PM, (31)] Initializing the Business Continuance Cluster Resource Group and resources [5/11/2009 6:19:28 PM, (31)] Resource Group specified in the DR Plan: exch07prod1 [5/11/2009 6:19:28 PM, (31)] Checking if a Network Name Resource with [Name =exch07prod1] exists [5/11/2009 6:19:28 PM, (31)] Error: There is no resource available that meets the input criteria. Error Information: WQL: SELECT * FROM MSCLUSTER_RESOURCE WHERE PrivateProperties.Name = "exch07prod1" AND Type = "Network Name" [5/11/2009 6:19:28 PM, (31)] Network Name Resource with NetName = [exch07prod1] does not exist. The resource will be created. [5/11/2009 6:19:28 PM, (31)] Retrieving the status for Resource Group: exch07prod1 [5/11/2009 6:19:28 PM, (31)] Completed retrieving the status for Resource Group: exch07prod1 [5/11/2009 6:19:28 PM, (31)] The Business Continuance Resource Group: exch07prod1 does not exist. Creating Resource Group: [exch07prod1] [5/11/2009 6:19:28 PM, (31)] Creation of Resource Group: [exch07prod1] completed [5/11/2009 6:19:28 PM, (31)] Moving the Resource Group: [exch07prod1] to [EXCH07DRCLUS1] (if required) processing [5/11/2009 6:19:28 PM, (31)] Moving the Resource Group: [exch07prod1] to [EXCH07DRCLUS1] (if required) completed [5/11/2009 6:19:28 PM, (31)] Resource Group: exch07prod1 is not offline. [5/11/2009 6:19:53 PM, (31)] Retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:19:54 PM, (31)] Completed retrieving information on all the cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:19:54 PM, (31)] There are no cluster resources in the Resource Group: [exch07prod1] [5/11/2009 6:19:54 PM, (31)] Initializing the Network Name Resource for DR [5/11/2009 6:19:54 PM, (31)] *** Initializing IP Address Resource [5/11/2009 6:19:54 PM, (31)] IP Address Resource: [IP Address (exch07prod1), 172.20.1.219] [5/11/2009 6:19:54 PM, (31)] Initializing IP Address Resource data for creation [5/11/2009 6:19:54 PM, (31)] Completed initializing IP Address Resource data for creation [5/11/2009 6:19:54 PM, (31)] Creating IP Address Resource [5/11/2009 6:19:56 PM, (31)] Competed creating the IP Address Resource [5/11/2009 6:19:56 PM, (31)] *** Initializing NetName Resource [5/11/2009 6:19:56 PM, (31)] NetName Resource: [Network Name (exch07prod1), exch07prod1] [5/11/2009 6:19:56 PM, (31)] Initializing NetName Resource data for creation [5/11/2009 6:19:56 PM, (31)] Completed initializing NetName Resource data for creation [5/11/2009 6:19:56 PM, (31)] Creating NetName Resource [5/11/2009 6:20:18 PM, (31)] Competed creating the required NetName Resource [5/11/2009 6:20:18 PM, (31)] Adding dependency to the IP Address Resource [5/11/2009 6:20:18 PM, (31)] Adding dependency to the IP Address Resource-completed. [5/11/2009 6:20:18 PM, (31)] Onlining Resource Group: exch07prod1 [5/11/2009 6:20:23 PM, (31)] Onlining Resource Group: exch07prod1 completed successfully. [5/11/2009 6:20:23 PM, (31)] LUN Connections for DR : [Processing] [5/11/2009 6:20:23 PM, (31)] Initialization of Snapshot information : [Processing] [5/11/2009 6:20:23 PM, (31)] Initializing Volume SnapShot Information. [5/11/2009 6:20:23 PM, (31)] Retrieving Snapshots for Volume: [bosstor1:exch07prod1_log_mirvol] [5/11/2009 6:20:25 PM, (31)] Processing Snapshot Information for Volume: [bosstor1:exch07prod1_log_mirvol] [5/11/2009 6:20:25 PM, (31)] Completed processing Snapshot Information for Volume: [bosstor1:exch07prod1_log_mirvol] [5/11/2009 6:20:25 PM, (31)] Retrieving Snapshots for Volume: [bosstor1:exch07clus1_sg1db_mirvol] [5/11/2009 6:20:26 PM, (31)] Processing Snapshot Information for Volume: [bosstor1:exch07clus1_sg1db_mirvol] [5/11/2009 6:20:26 PM, (31)] Completed processing Snapshot Information for Volume: [bosstor1:exch07clus1_sg1db_mirvol] [5/11/2009 6:20:26 PM, (31)] Initialization of Snapshot information : [Completed] [5/11/2009 6:20:26 PM, (31)] Volume SnapRestore : [Processing] [5/11/2009 6:20:26 PM, (31)] Evaluating Exchange Storage layout for Volume SnapRestore [5/11/2009 6:20:26 PM, (31)] Volume: bosstor1:exch07prod1_log_mirvol qualifies for Volume SnapRestore[Logs/SnapInfo/Exchange Data only volume] [5/11/2009 6:20:26 PM, (31)] Volume: bosstor1:exch07prod1_log_mirvol will be Volume SnapRestored using snapshot: @snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40} [5/11/2009 6:20:26 PM, (31)] Volume: bosstor1:exch07clus1_sg1db_mirvol qualifies for Volume SnapRestore[Database Only Volume] [5/11/2009 6:20:26 PM, (31)] Volume: bosstor1:exch07clus1_sg1db_mirvol will be Volume SnapRestored using snapshot: exchsnap__exch07prod1_05-11-2009_18.02.02 [5/11/2009 6:20:26 PM, (31)] Volume SnapRestore : [Processing] [5/11/2009 6:20:52 PM, (31)] Volume SnapRestore : [Completed] [5/11/2009 6:20:52 PM, (31)] Volume SnapRestore [Volume: bosstor1:exch07prod1_log_mirvol SnapShot: @snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}]-Successful. [5/11/2009 6:20:52 PM, (31)] Volume SnapRestore [Volume: bosstor1:exch07clus1_sg1db_mirvol SnapShot: exchsnap__exch07prod1_05-11-2009_18.02.02]-Successful. [5/11/2009 6:20:52 PM, (31)] Volume SnapRestore : [Completed] [5/11/2009 6:20:52 PM, (31)] Retrieving SnapInfo,Log,Exchange Data LUNs to be connected : [Processing] [5/11/2009 6:20:52 PM, (31)] Retrieving SnapManager SnapInfo,Log,Exchange Data LUNs to be connected .. [5/11/2009 6:20:52 PM, (31)] Determining SnapInfo,Log,Exchange Data LUNs to be connected for Storage groups: [processing] [5/11/2009 6:20:52 PM, (31)] SnapInfo Path: L:\SME_SnapInfo\ [5/11/2009 6:20:52 PM, (31)] Retrieving SnapShot to be used for connecting LUN:[/vol/exch07prod1_log_mirvol/exch07prod1_log_qt/exch07prod1_log_lun] [5/11/2009 6:20:52 PM, (31)] Snapshot: @snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40} [5/11/2009 6:20:52 PM, (31)] Identifying the Snapshot to be used with reference to the list of already VBSRed Volumes. [5/11/2009 6:20:52 PM, (31)] Volume [bosstor1:exch07prod1_log_mirvol] Snapshot [@snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}] [5/11/2009 6:20:52 PM, (31)] Volume [bosstor1:exch07prod1_log_mirvol] was volume snap restored using snapshot [@snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}]. [5/11/2009 6:20:52 PM, (31)] Log Path: L:\Program Files\Microsoft\Exchange Server\Mailbox\SG1 [5/11/2009 6:20:52 PM, (31)] Log LUN is not connected. [5/11/2009 6:20:52 PM, (31)] Retrieving Snapshot to be used for restoring Log Lun : [processing] [5/11/2009 6:20:52 PM, (31)] Snapshot: @snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40} [5/11/2009 6:20:52 PM, (31)] Identifying the Snapshot to be used with reference to the list of already VBSRed Volumes. [5/11/2009 6:20:52 PM, (31)] Volume [bosstor1:exch07prod1_log_mirvol] Snapshot [@snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}] [5/11/2009 6:20:52 PM, (31)] Volume [bosstor1:exch07prod1_log_mirvol] was volume snap restored using snapshot [@snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}]. [5/11/2009 6:20:52 PM, (31)] System Path: L:\Program Files\Microsoft\Exchange Server\Mailbox\SG1 [5/11/2009 6:20:52 PM, (31)] 'System files' LUN is not connected. [5/11/2009 6:20:52 PM, (31)] Retrieving Snapshot to be used for restoring 'System Files' Lun : [processing] [5/11/2009 6:20:52 PM, (31)] Snapshot: @snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40} [5/11/2009 6:20:52 PM, (31)] Identifying the Snapshot to be used with reference to the list of already VBSRed Volumes. [5/11/2009 6:20:52 PM, (31)] Volume [bosstor1:exch07prod1_log_mirvol] Snapshot [@snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}] [5/11/2009 6:20:52 PM, (31)] Volume [bosstor1:exch07prod1_log_mirvol] was volume snap restored using snapshot [@snapmir@{DBD0E2CD-C343-448A-9E81-0B5892FC3E40}]. [5/11/2009 6:20:52 PM, (31)] Determining SnapInfo and Log LUNs to be connected for Storage groups: [completed] [5/11/2009 6:20:52 PM, (31)] No LUNs need to be connected for SMTP [5/11/2009 6:20:52 PM, (31)] No LUNs need to be connected for MTA [5/11/2009 6:20:52 PM, (31)] Retrieving SnapInfo,Log,Exchange Data LUNs to be connected : [Completed] [5/11/2009 6:20:52 PM, (31)] Connecting SnapInfo,Log,Exchange Data LUNs : [Processing] [5/11/2009 6:20:52 PM, (31)] Connecting LUNs..... [5/11/2009 6:21:02 PM, (31)] Successfully connected LUN: [[Storage System: bosstor1, Lun: /vol/exch07prod1_log_mirvol/exch07prod1_log_qt/exch07prod1_log_lun, Host Mount Point: L]] [5/11/2009 6:21:02 PM, (31)] Connecting SnapInfo,Log,Exchange Data LUNs : [Completed] [5/11/2009 6:21:02 PM, (31)] Checking SnapInfo,Log,Exchange Data LUNs Connection Results [5/11/2009 6:21:02 PM, (31)] Connection of SnapManager SnapInfo,Log,Exchange Data LUNs completed successfully. [Storage System: bosstor1, Lun: /vol/exch07prod1_log_mirvol/exch07prod1_log_qt/exch07prod1_log_lun, Host Mount Point: L] [Connected] [5/11/2009 6:21:02 PM, (31)] Initializing Backup set information: [Processing] [5/11/2009 6:21:02 PM, (31)] *** Initializing BackupSet information [Storage Group: SG1] [5/11/2009 6:21:02 PM, (31)] Retrieving Backup set to be used for Storage Group: [SG1][processing] [5/11/2009 6:21:02 PM, (31)] Retrieving Backup sets for Storage Group: SG1 [5/11/2009 6:21:02 PM, (31)] Checking if Snapshot: exchsnap__exch07prod1_05-11-2009_18.02.02 can be used for restoring the Database LUNs [5/11/2009 6:21:02 PM, (31)] Snapshot: exchsnap__exch07prod1_05-11-2009_18.02.02 will be used for connecting the Database LUNs for Storage Group: SG1 [5/11/2009 6:21:02 PM, (31)] Retrieving Backup set to be used for Storage Group: [SG1][completed] [5/11/2009 6:21:02 PM, (31)] Database LUNs for Storage Group: [SG1] will be connected using SnapShot:[exchsnap__exch07prod1_05-11-2009_18.02.02] [5/11/2009 6:21:02 PM, (31)] Initializing Backup set information: [Completed] [5/11/2009 6:21:02 PM, (31)] Retrieving Exchange Database LUNs for connection: [Processing] [5/11/2009 6:21:02 PM, (31)] Determining LUNs to be connected for Exchange Storage groups: [processing] [5/11/2009 6:21:02 PM, (31)] Storage Group: SG1 [5/11/2009 6:21:02 PM, (31)] Backup Set Snapshot: exchsnap__exch07prod1_05-11-2009_18.02.02 [5/11/2009 6:21:02 PM, (31)] EDB Path: R:\Program Files\Microsoft\Exchange Server\Mailbox\SG1\MB1.edb [5/11/2009 6:21:02 PM, (31)] EDB LUN is not connected. [5/11/2009 6:21:02 PM, (31)] Identifying the Snapshot to be used with reference to the list of already VBSRed Volumes. [5/11/2009 6:21:02 PM, (31)] Volume [bosstor1:exch07clus1_sg1db_mirvol] Snapshot [exchsnap__exch07prod1_05-11-2009_18.02.02] [5/11/2009 6:21:02 PM, (31)] Volume [bosstor1:exch07clus1_sg1db_mirvol] was volume snap restored using snapshot [exchsnap__exch07prod1_05-11-2009_18.02.02]. [5/11/2009 6:21:02 PM, (31)] Determining LUNs to be connected for Exchange Storage groups: [completed] [5/11/2009 6:21:02 PM, (31)] The following LUNs will be connected. [5/11/2009 6:21:02 PM, (31)] [lun: /vol/exch07clus1_sg1db_mirvol/exch07clus1_sg1db_qt/exch07clus1_sg1db_lun, storage system: bosstor1, host mount point: R] [5/11/2009 6:21:02 PM, (31)] Retrieving Exchange Database LUNs for connection: [Completed] [5/11/2009 6:21:02 PM, (31)] Connecting Exchange Database LUNs : [Processing] [5/11/2009 6:21:02 PM, (31)] Connecting LUNs..... [5/11/2009 6:21:15 PM, (31)] Successfully connected LUN: [[Storage System: bosstor1, Lun: /vol/exch07clus1_sg1db_mirvol/exch07clus1_sg1db_qt/exch07clus1_sg1db_lun, Host Mount Point: R]] [5/11/2009 6:21:16 PM, (31)] Connecting Exchange LUNs : [Completed] [5/11/2009 6:21:16 PM, (31)] Checking Exchange Database LUNs Connection Results [5/11/2009 6:21:16 PM, (31)] LUN Connections for DR : [Completed] [5/11/2009 6:21:16 PM, (31)] *** Execution of Task: [ReConnect Exchange LUNs] completed successfully. [5/11/2009 6:21:16 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:21:16 PM, (31)] *** Executing task: [Exchange Instance Recreation] [5/11/2009 6:21:16 PM, (31)] ------------------------------------------------------------------------- [5/11/2009 6:21:16 PM, (31)] Recreating Exchange Cluster Resources. [5/11/2009 6:21:16 PM, (31)] Exchange Version: [Exchange2007] [5/11/2009 6:21:16 PM, (31)] Retrieving Recreation Strategy from Strategy Manager: [Processing] [5/11/2009 6:21:16 PM, (31)] Retrieved Recreation Strategy from Strategy Manager: [Completed] [5/11/2009 6:21:16 PM, (31)] Initializing Exchange 2007 Recreation strategy [5/11/2009 6:21:16 PM, (31)] Business Continuance Resource Group : exch07prod1 [5/11/2009 6:21:16 PM, (31)] Network Name Resource : Network Name (exch07prod1)[Name=exch07prod1] [5/11/2009 6:21:16 PM, (31)] Invoking Exchange 2007 Setup.com to recover Clustered Mailbox Server instance [5/11/2009 6:21:16 PM, (31)] Invoking Setup.com /ClearLocalCMS. [5/11/2009 6:21:16 PM, (31)] Path: D:\Program Files\Microsoft\Exchange Server\bin\Setup.com [5/11/2009 6:21:16 PM, (31)] Arguments: /ClearLocalCMS [5/11/2009 6:21:16 PM, (31)] Invoking Setup.com [5/11/2009 6:21:16 PM, (31)] Initializing Output Read pipe to Setup.com [5/11/2009 6:21:16 PM, (31)] Initializing Error Read pipe to Setup.com [5/11/2009 6:21:16 PM, (31)] Waiting for Setup.com /clearlocalcms to complete [5/11/2009 6:21:16 PM, (17)] Welcome to Microsoft Exchange Server 2007 Unattended Setup [5/11/2009 6:21:16 PM, (17)] Preparing Exchange Setup [5/11/2009 6:21:18 PM, (25)] Please specify an existing server role(s) to uninstall. [5/11/2009 6:21:19 PM, (17)] Exchange Server setup encountered an error. [5/11/2009 6:21:19 PM, (31)] Setup.com /clearlocalcms completed with Return code: [1] [5/11/2009 6:21:19 PM, (31)] Setup.com /clearlocalcms completed with errors. Please check the Windows Event Log for more details.[This error will be ignored !][Error Code: 1] Error Information: Please specify an existing server role(s) to uninstall. [5/11/2009 6:21:19 PM, (31)] Clearing any pending state [processing] [5/11/2009 6:21:19 PM, (31)] Clearing any pending state [completed] [5/11/2009 6:21:19 PM, (31)] Recovering Exchange 2007 Clustered MailBox Server. [5/11/2009 6:21:19 PM, (31)] Path: D:\Program Files\Microsoft\Exchange Server\bin\Setup.com [5/11/2009 6:21:19 PM, (31)] Arguments: /RecoverCMS /CMSName:exch07prod1 /CMSIPaddress:172.20.1.219 [5/11/2009 6:21:19 PM, (31)] Invoking Setup.com [5/11/2009 6:21:19 PM, (31)] Initializing Output Read pipe to Setup.com [5/11/2009 6:21:19 PM, (31)] Initializing Error Read pipe to Setup.com [5/11/2009 6:21:19 PM, (31)] Waiting for Setup.com to complete [5/11/2009 6:21:19 PM, (25)] Welcome to Microsoft Exchange Server 2007 Unattended Setup [5/11/2009 6:21:19 PM, (25)] Preparing Exchange Setup [5/11/2009 6:21:21 PM, (25)] The following server roles will be recovered [5/11/2009 6:21:21 PM, (25)] Clustered Mailbox Server [5/11/2009 6:21:21 PM, (25)] Performing Microsoft Exchange Server Prerequisite Check [5/11/2009 6:21:21 PM, (25)] Configuring Microsoft Exchange Server [5/11/2009 6:21:36 PM, (25)] Clustered Mailbox Server ... FAILED [5/11/2009 6:21:36 PM, (25)] The Exchange Server Setup operation did not complete. For more information, visit http://support.microsoft.com and enter the Error ID. [5/11/2009 6:21:36 PM, (25)] Exchange Server setup encountered an error. [5/11/2009 6:21:36 PM, (31)] Setup.com completed with Return code: [1] [5/11/2009 6:21:36 PM, (31)] Recovery of Exchange 2007 Clustered MailBox Server completed with error(s). Please check the Windows Event Log for more details.[Error Code: 1] Error Information: Please specify an existing server role(s) to uninstall. Cluster Common Failure Exception: The group or resource is not in the correct state to perform the requested operation. (Exception from HRESULT: 0x8007139F) [5/11/2009 6:21:36 PM, (31)] An error occured while recovering Exchange 2007 Clustered Mailbox Server. Please check the Reports/Window Event Logs for more details. SnapManager will attempt to online the Exchange Resources. If the error is not critical, the online operation may succeed. [5/11/2009 6:21:36 PM, (31)] Checking if it is a retriable error [processing] [5/11/2009 6:21:36 PM, (31)] Checking if it is a retriable error [completed] [5/11/2009 6:21:36 PM, (31)] Onlining Resource Group: exch07prod1 [5/11/2009 6:22:02 PM, (31)] Onlining Resource Group: exch07prod1 - Completed. [5/11/2009 6:22:02 PM, (31)] An error occured while recovering Exchange 2007 Clustered Mailbox Server. Please check the Reports/Window Event Logs for more details. [5/11/2009 6:22:02 PM, (31)] Restoring any pending state [processing] [5/11/2009 6:22:02 PM, (31)] Restoring any pending state [completed] [5/11/2009 6:22:02 PM, (31)] Task Result Details: [5/11/2009 6:22:02 PM, (31)] An error occured while recovering Exchange 2007 Clustered Mailbox Server. Please check the Reports/Window Event Logs for more details. [5/11/2009 6:22:02 PM, (31)] *** Task: Execution of Task[Exchange Instance Recreation] failed with Error(s)/Warning(s). *** Execution of [Business Continuance Plan Execution] failed with Error(s)/Warning(s) [5/11/2009 6:22:02 PM, (31)] *****Result Summary ********************** Cleanup of Business Continuance Destination : [Successful] Offline Exchange & Update SnapMirror : [Successful] Quisce and Break SnapMirrors : [Successful] ReConnect Exchange LUNs : [Successful] Exchange Instance Recreation : [Error] Restore backups : [Initialized] ******************************************