Exchange Information Store Restore to RSG

[Martin_Mailes]

Hi All,

(Netbackup 6 MP5, exchange 2003 active/passive cluster)

I'm trying to perform a restore of the information store backup to a Recovery Storage Group and the restore job keeps failing about half way through. The restore itself seems to keep pausing approximately every 3Gb and I get Media not ready messages in the bpbrm log file as shown below.

I have tried a couple of different information store backups from different tapes and all the jobs are all failing at exactly 46734912 current kilobytes written.

I would appreciate some pointers if anyone can assist. Would this be something to do with buffer sizes?

17:21:49.319 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA NOT READY
17:21:49.319 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:24:49.507 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA READY
17:24:49.507 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:24:49.819 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA NOT READY
17:24:49.819 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:27:49.944 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA READY
17:27:49.944 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:27:50.257 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA NOT READY
17:27:50.257 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:30:50.444 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA READY
17:30:50.444 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:30:50.757 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA NOT READY
17:30:50.757 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:33:50.882 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA READY
17:33:50.882 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:33:51.194 [6012.816] <2> bpbrm read_media_msg: read from media manager: MEDIA NOT READY
17:33:51.194 [6012.816] <2> bpbrm signal_bpbrm_child: sending Media Ready to bpbrm child 4436
17:33:51.554 [4436.860] <32> bpbrm handle_restore: from client cl-exc: FTL - Unexpected EOF in tar file
17:33:51.554 [4436.860] <2> set_job_details: Sending Tfile jobid (52291)
17:33:51.554 [4436.860] <2> set_job_details: LOG 1241541231 32 bpbrm 4436 from client cl-exc: FTL - Unexpected EOF in tar file

17:33:51.554 [4436.860] <2> set_job_details: Done
17:33:51.554 [4436.860] <2> db_error_add_to_file: dberrorq.c:midnite = 1241478000
17:33:51.554 [4436.860] <2> bpbrm write_msg_to_progress_file: (52291.001) (52291.001) FTL - Unexpected EOF in tar file

17:33:51.569 [6012.816] <2> bpbrm read_media_msg: read from media manager: ERROR 85
17:33:51.569 [6012.816] <2> bpbrm send_parent_msg: ERROR 85
17:33:51.569 [6012.816] <2> bpbrm process_media_msg: got ERROR 85 from media manager
17:33:51.569 [6012.816] <2> bpbrm kill_bpbrm_child: killing bpbrm child 4436.
17:33:51.569 [6012.816] <2> bpbrm signal_bpbrm_child: sending Terminate to bpbrm child 4436

Reagrds

Martin

[Gerald_W__Gitau]

In the restore options window did you leave the default  - restore everything to its original location? If so change to a different location according to this article and try again  http://seer.entsupport.symantec.com/docs/297412.htm



 

[Martin_Mailes]

Hi Gerald, thanks for your reply.

Below are the results from my attempt following  those instructions.

11:34:19 (52370.xxx) Restore job id 52370 will require 1 image.
11:34:19 (52370.xxx) Media id 0046L3 is needed for the restore.

11:34:23 (52370.001) Restoring from image created 28/02/2009 14:00:00
11:34:24 (52370.001) INF - If Media id 0046L3 is not in a robotic library administrative interaction may be required to satisfy this mount request.
11:34:27 (52370.001) INF - Waiting for mount of media id 0046L3 on server svr004 for reading.
11:35:04 (52370.001) INF - Waiting for positioning of media id 0046L3 on server svr004 for reading.
11:36:01 (52370.001) INF - Beginning restore from server svr004 to client cl-exc.
11:36:03 (52370.001) TAR - Microsoft Information Store:\First Storage Group\Mailbox Store (CL-EXC)
11:36:03 (52370.001) MNR - The file was renamed to the following:
11:36:03 (52370.001) UTF - Microsoft Information Store:\Recovery Storage Group\Mailbox Store (CL-EXC)
11:36:03 (52370.001) (52370.001) INF - Restoring Exchange 2000/2003 ESE object S:\EXCHSRVR\mdbdata\priv1.edb
12:50:53 (52370.001) The following files/folders were not restored:
12:50:53 (52370.001) UTF - /Microsoft Information Store/First Storage Group/Mailbox Store (CL-EXC)
12:50:53 (52370.001) UTF - /Microsoft Information Store/First Storage Group/Log files_1235829600
12:50:53 (52370.001) (52370.001) FTL - Unexpected EOF in tar file
12:50:53 (52370.001) (52370.001) INF - Commit is set, but no log files are included in this restore request. Log files are required to perform Exchange 2000/2003 database recovery.
12:50:53 (52370.001) Status of restore from image created 28/02/2009 14:00:00 = media read error

12:50:56 INF - Server status = 5
12:50:56 (52370.xxx) INF - Status = the restore failed to recover the requested files.

The full job is about 96Gb in size and this failed at exactly the same kilobytes written as previously. I am ticking the log files and the database but the error says that there are no lof files included in the restore.

Any more ideas?

[Gerald_W__Gitau]

Ok...check the C:\temp directory and delete the "old restores". Also try to recreate the RSG and then try again...I had a similar issue but cannot remember exactly how I resolved it. And include the logs when you select the storage group for restore.

[Martin_Mailes]

OK, I've deleted the RSG & all associated files & folders.

Recreated the RSG with defaults apart from changing the drive letter to a drive with enough free space to accomodate the restore.

Opened Backup, Archive & Restore, selected a different backup and ticked the log files and mailbox store.
Started restore, changed the drive letter for the temp files to the drive with the free space and left all defaults. Selected Restore everything to a different location and changed destination to Microsoft Information Store:\Recovery storage Group\ as per document instructions.

The restore has begun and I can see the edb file in the recovery storage group folder increasing in size.

AAAAAAGH!!!!

This is a completely different tape than I have tried before and it has failed again after restoring exactly the same amount of 46734912KB

Job details as follows:

06/05/2009 13:55:03 - begin Restore
06/05/2009 13:55:05 - 1 images required
06/05/2009 13:55:05 - media 0044L3 required
06/05/2009 13:55:09 - restoring image cl-exc_1238763600
06/05/2009 13:55:11 - connecting
06/05/2009 13:55:12 - started process bptm (1780)
06/05/2009 13:55:12 - mounting 0044L3
06/05/2009 13:55:10 - requesting resource 0044L3
06/05/2009 13:55:10 - granted resource 0044L3
06/05/2009 13:55:10 - granted resource IBM.ULT3580-TD3.001
06/05/2009 13:55:18 - connected; connect time: 00:00:07
06/05/2009 13:55:37 - mounted; mount time: 00:00:25
06/05/2009 13:55:37 - positioning 0044L3 to file 1
06/05/2009 13:55:37 - positioned 0044L3; position time: 00:00:00
06/05/2009 13:55:37 - begin reading
06/05/2009 15:53:03 - Error bptm(pid=1780) cannot read image from media id 0044L3, drive index 1, err = 1117
06/05/2009 15:53:04 - Error bptm(pid=5148) The following files/folders were not restored:
06/05/2009 15:53:04 - Error bptm(pid=5148) UTF - /Microsoft Information Store/First Storage Group/Mailbox Store (CL-EXC)
06/05/2009 15:53:04 - Error bptm(pid=5148) UTF - /Microsoft Information Store/First Storage Group/Log files_1238763600
06/05/2009 15:53:04 - Critical bpbrm(pid=4100) from client cl-exc: FTL - Unexpected EOF in tar file
06/05/2009 15:53:04 - restored image cl-exc_1238763600 - (media read error(85)); restore time 01:57:55
06/05/2009 15:53:07 - end Restore; elapsed time: 01:58:04
the restore failed to recover the requested files(5)