locked
farm backup error RRS feed

  • Question

  • I'm trying to help someone with a MOSS 2007 setup who's having an issue, and I insisted they do a full backup before I touch anything. They just reported that their backup failed, and gave me the error log. In reading through it I can't tell exactly what failed, except that at the end of the log it says "Error: Backup failed for Object SharePoint - 8080 failed in event OnPrepareBackup."

    But earlier in the log it appears that SharePoint - 8080 was backed up.

    Can anyone tell me what went wrong, or what I need to do to pinpoint what went wrong and fix it? Thank you.

     Here's the full log:

    
    [5/4/2011 9:43:46 AM]: Verbose: Using directory: F:\Sharepoint BU\spbr0001\.
     [5/4/2011 9:43:46 AM]: Verbose: The backup/restore process included the following objects:
     [5/4/2011 9:43:46 AM]:   Farm\
         [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1]\
         Windows SharePoint Services Web Application\
           SharePoint - 8080\
             WSS_Content\
             WSS_Content1\
         [WSS_Administration]\
           [Web Application]\
             SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c\
         Windows SharePoint Services Search\
           [Search instance]\
             [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e]\
     
    [5/4/2011 9:43:46 AM]: Progress: Starting Backup.
     [5/4/2011 9:43:46 AM]: Start Time: 5/4/2011 9:43:46 AM.
     [5/4/2011 9:43:46 AM]: Verbose: Requested by DAYSJEWELERS\bryan.
     [5/4/2011 9:43:46 AM]: Verbose: Backup/Restore Settings:
     Backup
     Backup Method: Full
     Top Component: Farm
     Directory: F:\Sharepoint BU\
     Progress updated: 5
     [5/4/2011 9:43:46 AM]: Backup threads created: 1
     
    [5/4/2011 9:43:46 AM]: Verbose: Adding Farm to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1 to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding Windows SharePoint Services Web Application to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding SharePoint - 8080 to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding WSS_Content to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding WSS_Content1 to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding WSS_Administration to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding Web Application to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding Windows SharePoint Services Search to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding Search instance to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Adding WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e to Backup list.
     [5/4/2011 9:43:46 AM]: Verbose: Starting OnPrepareBackup event.
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: Farm.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: Windows SharePoint Services Web Application.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: SharePoint - 8080.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Error: Object SharePoint - 8080 failed in event OnPrepareBackup. For more information, see the error log located in the backup directory.
     KeyNotFoundException: The given key was not present in the dictionary.
     [5/4/2011 9:43:46 AM]: Debug:  at System.ThrowHelper.ThrowKeyNotFoundException()
      at System.Collections.Generic.Dictionary`2.get_Item(TKey key)
      at Microsoft.SharePoint.Administration.SPWebApplication.OnPrepareBackup(Object sender, SPBackupInformation info)
      at Microsoft.SharePoint.Administration.Backup.SPBackup.RunPrepareBackup(SPBackupRestoreObject node)
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: WSS_Content.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: WSS_Content1.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: WSS_Administration.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: Web Application.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: Windows SharePoint Services Search.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: Search instance.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:46 AM]: Verbose: Starting object: WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e.
     [5/4/2011 9:43:46 AM]: Verbose: Saving SPPersistedObject State
     [5/4/2011 9:43:47 AM]: Verbose: Starting OnBackup event.
     [5/4/2011 9:43:47 AM]: Verbose: Starting object: Farm.
     [5/4/2011 9:43:47 AM]: Progress: [Farm] 50 percent complete.
     [5/4/2011 9:43:47 AM]: Verbose: Starting object: SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1.
     [5/4/2011 9:43:47 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL Server Connection String: Data Source=ruby\Microsoft##SSEE;Initial Catalog=SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1;Integrated Security=True;Enlist=False.
     [5/4/2011 9:43:47 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL command started at: 5/4/2011 9:43:47 AM. This command may take a while to complete and without notification.
     [5/4/2011 9:43:47 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL Server Command: BACKUP DATABASE [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] TO DISK=@db_loc WITH NAME=@db_name, STATS=5, NOINIT, NOSKIP, NOFORMAT, NOREWIND 
     @db_name=SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1, @db_loc=F:\Sharepoint BU\spbr0001\0000000D.bak
     [5/4/2011 9:43:47 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL command timeout is set to 1.00 hours.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 5 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 10 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 15 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 20 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 25 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 31 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 36 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 40 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 45 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 50 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 55 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 60 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 65 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 71 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 76 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 80 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 85 percent complete.
     [5/4/2011 9:43:47 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 90 percent complete.
     [5/4/2011 9:43:48 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 95 percent complete.
     [5/4/2011 9:43:48 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL Server Message: Processed 616 pages for database 'SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1', file 'SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1' on file 1.
     [5/4/2011 9:43:48 AM]: Progress: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] 100 percent complete.
     [5/4/2011 9:43:48 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL Server Message: Processed 3 pages for database 'SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1', file 'SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1_log' on file 1.
     [5/4/2011 9:43:48 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] Backup file size changed to 5.10 MB.
     [5/4/2011 9:43:48 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL Server Message: BACKUP DATABASE successfully processed 619 pages in 0.884 seconds (5.736 MB/sec).
     [5/4/2011 9:43:48 AM]: Verbose: [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1] SQL command completed at: 5/4/2011 9:43:48 AM.
     [5/4/2011 9:43:48 AM]: Verbose: Starting object: Windows SharePoint Services Web Application.
     [5/4/2011 9:43:48 AM]: Progress: [Windows SharePoint Services Web Application] 50 percent complete.
     [5/4/2011 9:43:48 AM]: Verbose: Starting object: SharePoint - 8080.
     [5/4/2011 9:43:48 AM]: Progress: [SharePoint - 8080] 50 percent complete.
     [5/4/2011 9:43:48 AM]: Verbose: Starting object: WSS_Content.
     [5/4/2011 9:43:49 AM]: Verbose: [WSS_Content] SQL Server Connection String: Data Source=ruby\Microsoft##SSEE;Initial Catalog=WSS_Content;Integrated Security=True;Enlist=False;Connect Timeout=15.
     [5/4/2011 9:43:49 AM]: Verbose: [WSS_Content] SQL command started at: 5/4/2011 9:43:49 AM. This command may take a while to complete and without notification.
     [5/4/2011 9:43:49 AM]: Verbose: [WSS_Content] SQL Server Command: BACKUP DATABASE [WSS_Content] TO DISK=@db_loc WITH NAME=@db_name, STATS=5, NOINIT, NOSKIP, NOFORMAT, NOREWIND 
     @db_name=WSS_Content, @db_loc=F:\Sharepoint BU\spbr0001\0000000F.bak
     [5/4/2011 9:43:49 AM]: Verbose: [WSS_Content] SQL command timeout is set to 3.75 hours.
     [5/4/2011 9:43:50 AM]: Progress: [WSS_Content] 5 percent complete.
     [5/4/2011 9:43:51 AM]: Progress: [WSS_Content] 10 percent complete.
     [5/4/2011 9:43:52 AM]: Progress: [WSS_Content] 15 percent complete.
     [5/4/2011 9:43:53 AM]: Progress: [WSS_Content] 20 percent complete.
     [5/4/2011 9:43:55 AM]: Progress: [WSS_Content] 25 percent complete.
     [5/4/2011 9:43:55 AM]: Progress: [WSS_Content] 30 percent complete.
     [5/4/2011 9:43:57 AM]: Progress: [WSS_Content] 35 percent complete.
     [5/4/2011 9:43:58 AM]: Progress: [WSS_Content] 40 percent complete.
     [5/4/2011 9:43:59 AM]: Progress: [WSS_Content] 45 percent complete.
     [5/4/2011 9:44:00 AM]: Progress: [WSS_Content] 50 percent complete.
     [5/4/2011 9:44:01 AM]: Progress: [WSS_Content] 55 percent complete.
     [5/4/2011 9:44:02 AM]: Progress: [WSS_Content] 60 percent complete.
     [5/4/2011 9:44:04 AM]: Progress: [WSS_Content] 65 percent complete.
     [5/4/2011 9:44:05 AM]: Progress: [WSS_Content] 70 percent complete.
     [5/4/2011 9:44:06 AM]: Progress: [WSS_Content] 75 percent complete.
     [5/4/2011 9:44:07 AM]: Progress: [WSS_Content] 80 percent complete.
     [5/4/2011 9:44:08 AM]: Progress: [WSS_Content] 85 percent complete.
     [5/4/2011 9:44:09 AM]: Progress: [WSS_Content] 90 percent complete.
     [5/4/2011 9:44:10 AM]: Progress: [WSS_Content] 95 percent complete.
     [5/4/2011 9:44:11 AM]: Verbose: [WSS_Content] SQL Server Message: Processed 72016 pages for database 'WSS_Content', file 'WSS_Content' on file 1.
     [5/4/2011 9:44:11 AM]: Progress: [WSS_Content] 100 percent complete.
     [5/4/2011 9:44:12 AM]: Verbose: [WSS_Content] SQL Server Message: Processed 6 pages for database 'WSS_Content', file 'WSS_Content_log' on file 1.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content] SQL Server Message: BACKUP DATABASE successfully processed 72022 pages in 23.752 seconds (24.840 MB/sec).
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content] SQL command completed at: 5/4/2011 9:44:13 AM.
     [5/4/2011 9:44:13 AM]: Verbose: Starting object: WSS_Content1.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL Server Connection String: Data Source=ruby\Microsoft##SSEE;Initial Catalog=WSS_Content1;Integrated Security=True;Enlist=False;Connect Timeout=15.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL command started at: 5/4/2011 9:44:13 AM. This command may take a while to complete and without notification.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL Server Command: BACKUP DATABASE [WSS_Content1] TO DISK=@db_loc WITH NAME=@db_name, STATS=5, NOINIT, NOSKIP, NOFORMAT, NOREWIND 
     @db_name=WSS_Content1, @db_loc=F:\Sharepoint BU\spbr0001\00000010.bak
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL command timeout is set to 1.00 hours.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 5 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 10 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 15 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 20 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 25 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 30 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 35 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 40 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 45 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 50 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 55 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 60 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 65 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 70 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 75 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 80 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 85 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 90 percent complete.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 95 percent complete.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL Server Message: Processed 1240 pages for database 'WSS_Content1', file 'WSS_Content1' on file 1.
     [5/4/2011 9:44:13 AM]: Progress: [WSS_Content1] 100 percent complete.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL Server Message: Processed 2 pages for database 'WSS_Content1', file 'WSS_Content1_log' on file 1.
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL Server Message: BACKUP DATABASE successfully processed 1242 pages in 0.517 seconds (19.665 MB/sec).
     [5/4/2011 9:44:13 AM]: Verbose: [WSS_Content1] SQL command completed at: 5/4/2011 9:44:13 AM.
     [5/4/2011 9:44:13 AM]: Verbose: Starting object: WSS_Administration.
     [5/4/2011 9:44:14 AM]: Progress: [WSS_Administration] 50 percent complete.
     [5/4/2011 9:44:14 AM]: Verbose: Starting object: Web Application.
     [5/4/2011 9:44:14 AM]: Progress: [Web Application] 50 percent complete.
     [5/4/2011 9:44:14 AM]: Verbose: Starting object: SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c.
     [5/4/2011 9:44:14 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL Server Connection String: Data Source=ruby\Microsoft##SSEE;Initial Catalog=SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c;Integrated Security=True;Enlist=False;Connect Timeout=15.
     [5/4/2011 9:44:14 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL command started at: 5/4/2011 9:44:14 AM. This command may take a while to complete and without notification.
     [5/4/2011 9:44:14 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL Server Command: BACKUP DATABASE [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] TO DISK=@db_loc WITH NAME=@db_name, STATS=5, NOINIT, NOSKIP, NOFORMAT, NOREWIND 
     @db_name=SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c, @db_loc=F:\Sharepoint BU\spbr0001\00000012.bak
     [5/4/2011 9:44:14 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL command timeout is set to 1.00 hours.
     [5/4/2011 9:44:14 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 5 percent complete.
     [5/4/2011 9:44:14 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 10 percent complete.
     [5/4/2011 9:44:14 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 15 percent complete.
     [5/4/2011 9:44:14 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 20 percent complete.
     [5/4/2011 9:44:14 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 25 percent complete.
     [5/4/2011 9:44:14 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 30 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 35 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 40 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 45 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 50 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 55 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 60 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 65 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 70 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 75 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 80 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 85 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 90 percent complete.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 95 percent complete.
     [5/4/2011 9:44:15 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL Server Message: Processed 3464 pages for database 'SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c', file 'SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c' on file 1.
     [5/4/2011 9:44:15 AM]: Progress: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] 100 percent complete.
     [5/4/2011 9:44:15 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL Server Message: Processed 2 pages for database 'SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c', file 'SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c_log' on file 1.
     [5/4/2011 9:44:15 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL Server Message: BACKUP DATABASE successfully processed 3466 pages in 1.427 seconds (19.894 MB/sec).
     [5/4/2011 9:44:15 AM]: Verbose: [SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c] SQL command completed at: 5/4/2011 9:44:15 AM.
     [5/4/2011 9:44:16 AM]: Verbose: Starting object: Windows SharePoint Services Search.
     [5/4/2011 9:44:16 AM]: Progress: [Windows SharePoint Services Search] 50 percent complete.
     [5/4/2011 9:44:16 AM]: Verbose: Starting object: Search instance.
     [5/4/2011 9:44:16 AM]: Progress: [Search instance] 50 percent complete.
     [5/4/2011 9:44:56 AM]: Verbose: Starting object: WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e.
     [5/4/2011 9:44:56 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL Server Connection String: Data Source=ruby\Microsoft##SSEE;Initial Catalog=WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e;Integrated Security=True;Enlist=False;Connect Timeout=15.
     [5/4/2011 9:44:56 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL command started at: 5/4/2011 9:44:56 AM. This command may take a while to complete and without notification.
     [5/4/2011 9:44:56 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL Server Command: BACKUP DATABASE [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] TO DISK=@db_loc WITH NAME=@db_name, STATS=5, NOINIT, NOSKIP, NOFORMAT, NOREWIND 
     @db_name=WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e, @db_loc=F:\Sharepoint BU\spbr0001\00000013.bak
     [5/4/2011 9:44:56 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL command timeout is set to 1.00 hours.
     [5/4/2011 9:44:57 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 5 percent complete.
     [5/4/2011 9:44:57 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 10 percent complete.
     [5/4/2011 9:44:57 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 15 percent complete.
     [5/4/2011 9:44:57 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 20 percent complete.
     [5/4/2011 9:44:57 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 25 percent complete.
     [5/4/2011 9:44:57 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 30 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 35 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 40 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 45 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 50 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 55 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 60 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 65 percent complete.
     [5/4/2011 9:44:58 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 70 percent complete.
     [5/4/2011 9:44:59 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 75 percent complete.
     [5/4/2011 9:44:59 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 80 percent complete.
     [5/4/2011 9:44:59 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 85 percent complete.
     [5/4/2011 9:44:59 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 90 percent complete.
     [5/4/2011 9:44:59 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 95 percent complete.
     [5/4/2011 9:44:59 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL Server Message: Processed 6240 pages for database 'WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e', file 'WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e' on file 1.
     [5/4/2011 9:44:59 AM]: Progress: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] 100 percent complete.
     [5/4/2011 9:44:59 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL Server Message: Processed 7 pages for database 'WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e', file 'WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e_log' on file 1.
     [5/4/2011 9:44:59 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL Server Message: BACKUP DATABASE successfully processed 6247 pages in 3.104 seconds (16.485 MB/sec).
     [5/4/2011 9:44:59 AM]: Verbose: [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e] SQL command completed at: 5/4/2011 9:44:59 AM.
     [5/4/2011 9:44:59 AM]: Verbose: Starting OnBackupComplete event.
     [5/4/2011 9:45:00 AM]: Verbose: Starting object: Farm.
     [5/4/2011 9:45:00 AM]: Progress: [Farm] 100 percent complete.
     [5/4/2011 9:45:00 AM]: Verbose: Starting object: SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1.
     [5/4/2011 9:45:00 AM]: Verbose: Starting object: Windows SharePoint Services Web Application.
     [5/4/2011 9:45:00 AM]: Progress: [Windows SharePoint Services Web Application] 100 percent complete.
     [5/4/2011 9:45:00 AM]: Verbose: Starting object: SharePoint - 8080.
     [5/4/2011 9:45:00 AM]: Progress: [SharePoint - 8080] 100 percent complete.
     [5/4/2011 9:45:00 AM]: Verbose: Starting object: WSS_Content.
     [5/4/2011 9:45:01 AM]: Verbose: Starting object: WSS_Content1.
     [5/4/2011 9:45:01 AM]: Verbose: Starting object: WSS_Administration.
     [5/4/2011 9:45:01 AM]: Progress: [WSS_Administration] 100 percent complete.
     [5/4/2011 9:45:01 AM]: Verbose: Starting object: Web Application.
     [5/4/2011 9:45:01 AM]: Progress: [Web Application] 100 percent complete.
     [5/4/2011 9:45:01 AM]: Verbose: Starting object: SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c.
     [5/4/2011 9:45:02 AM]: Verbose: Starting object: Windows SharePoint Services Search.
     [5/4/2011 9:45:02 AM]: Progress: [Windows SharePoint Services Search] 100 percent complete.
     [5/4/2011 9:45:02 AM]: Verbose: Starting object: Search instance.
     [5/4/2011 9:45:17 AM]: Progress: [Search instance] 100 percent complete.
     [5/4/2011 9:45:17 AM]: Verbose: Starting object: WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e.
     [5/4/2011 9:45:17 AM]: Finish Time: 5/4/2011 9:45:17 AM.
     [5/4/2011 9:45:17 AM]: Progress: Completed with errors, please refer to the log file for details.
     [5/4/2011 9:45:17 AM]: Verbose: Deleting the file F:\Sharepoint BU\spbr0001\spbackup.xml and replacing it.
     [5/4/2011 9:45:18 AM]: Verbose: The backup/restore process included the following objects:
     [5/4/2011 9:45:18 AM]:   Farm\
         [SharePoint_Config_683afb3b-259e-481b-b0a7-4e20ac8711a1]\
         Windows SharePoint Services Web Application\
           *SharePoint - 8080\
             WSS_Content\
             WSS_Content1\
         [WSS_Administration]\
           [Web Application]\
             SharePoint_AdminContent_c68f2e98-9e82-4d26-a97d-dc9cb1a7970c\
         Windows SharePoint Services Search\
           [Search instance]\
             [WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e]\
     
    [5/4/2011 9:45:18 AM]: Completed with 0 warnings.
     [5/4/2011 9:45:18 AM]: Completed with 1 errors.
     [5/4/2011 9:45:18 AM]: Error: Backup failed for Object SharePoint - 8080 failed in event OnPrepareBackup. For more information, see the error log located in the backup directory.
     [5/4/2011 9:45:18 AM]: -------------------------------------------------
    
    

    Wednesday, May 4, 2011 8:12 PM

Answers

All replies

  • Hello,

    WSS_Search_TESTBOX_eceb50f1eb6741fbb9e09abc4552c27e.
    [5/4/2011 9:45:17 AM]: Finish Time: 5/4/2011 9:45:17 AM.
    [5/4/2011 9:45:17 AM]: Progress: Completed with errors, please refer to the log file for details.

    Your search database backup failed,  Try running a backup just on search to confirm this, if that fails, then run a backup excluding search, if that succeeds then the issue is in the search database.

    Thanks!

    Tom


    Tom Molskow - SharePoint Architect - Microsoft Community Contributor 2011 Award - Linked-In - SharePoint Gypsy
    Thursday, May 5, 2011 6:50 PM
  • I tried running a backup just on search and the backup failed with this error:

    Object SharePoint - 8080 failed in event OnPrepareBackup. For more information, see the error log located in the backup directory. KeyNotFoundException: The given key was not present in the dictionary.

    It looks like 8080 is the web application for WSS.

    What's odd, though, is that in the Backup and Restore status page, after the backup ran, it says "completed" in the Progress column for WSS Search. The error came under "Sharepoint - 8080", where it says "Preparing backup" in the "progress" column.

    So maybe search is not the problem here? What does a KeyNotFoundException mean exactly and how would I fix this?

    I tried doing a backup of everything but search, as you suggest, but if I deselect "search" it automatically deselects everyhing listed above. If I just back up the WSS Web Application only, I get the same error as above.

    postscript:

    on further exploration, it looks like the WSS web application was originally on port 80, then a new one got created on port 8080, and I was told the new one was "extended" to port 80. I'm guessing this is where the problem is--SharePoint is still looking at 8080 and not finding anything. CA lists 8080 as the port for the WSS web application.

    Solution?

    Tuesday, May 10, 2011 6:40 PM
  • Hello,

    That's very good analysis, and as it turns out that is a known bug.  Here is a great blog that can guide you through the proper steps to remediate this issue:

    http://blogs.objectsharp.com/cs/blogs/max/archive/2008/08/21/sharepoint-bug-backup-breaks-after-extending-web-application.aspx

    Thanks!

    Tom


    Tom Molskow - SharePoint Architect - Microsoft Community Contributor 2011 Award - Linked-In - SharePoint Gypsy
    • Marked as answer by Megunticook Thursday, May 12, 2011 9:13 PM
    Thursday, May 12, 2011 2:36 PM
  • Thanks a lot. I'll give that a shot and report back.

    Thursday, May 12, 2011 9:13 PM
  • Case closed--I followed the instructions in the blog post you referenced and was able to perform a full farm backup. Thank you.
    Sunday, May 15, 2011 11:00 AM