the restore failed to recover the requested files(5)

[markdavies1978]

Hi All,

I'm unable to restore from several tapes & I've tried on 2 separate Master Servers.

I have just had a server failure & need to restore data to another Win 2003 Server & I'm receiving the following status with an incomple job:

"the restore failed to recover the requested files(5)"

Below is the contents of the detailed ststus:

22/06/2010 17:19:29 - end reading; read time: 00:00:07
22/06/2010 17:19:30 - restored image alicosan2_1276380434 - (tar did not find all the files to be restored(185)); restore time 00:01:39
22/06/2010 17:19:30 - Warning bprd(pid=6704) Restore must be resumed prior to first image expiration on 12/06/2011 23:07:14
22/06/2010 17:19:30 - end Restore; elapsed time: 00:01:44
the restore failed to recover the requested files(5)

Below is my Tar log.

11:39:25.203: [1592.6744] <2> WinMain: DAT - _pgmptr = 'D:\Veritas\NetBackup\bin\tar32.exe'
11:39:25.203: [1592.6744] <2> WinMain: DAT - lpCmdLine = '-x -v -Y -p -P -I 1277289556 -U 13 -E /D/Veritas/NetBackup/logs/ALTPATH/ALTPATH.326 -k -J clnt_lc_messages=ENG -J clnt_lc_time=ENG -J clnt_lc_ctype=ENG -J clnt_lc_collate=ENG -J clnt_lc_numeric=ENG -J restoreid=11760.001 -J job_total=1 -J client=CAHP3R3CBAK01 -J requesting_client=CAHP3R3CBAK01 -J browse_client=ALICOSAN2 -J backup_time=1276792201 -L /D/Veritas/NetBackup/logs/user_ops/SERVICES/logs/NBWIN026 -f -'
11:39:25.203: [1592.6744] <2> date_debug: DAT - timezone: GMT Standard Time, offset=0, dst: GMT Daylight Time
11:39:25.203: [1592.6744] <2> date_debug: DAT - current time: 1277289565, 23/06/2010 11:39:25
11:39:25.203: [1592.6744] <2> date_debug: DAT - 01/01/94 UCT: 757382400, 01/01/1994 01:00:00
11:39:25.203: [1592.6744] <2> date_debug: DAT - 07/01/94 UCT: 773020800, 01/07/1994 01:00:00
11:39:30.203: [1592.6744] <2> WinMain: DAT - standard input handle = 1920
11:39:30.203: [1592.6744] <4> WinMain: INF - not using socket for standard input handle
11:39:30.203: [1592.6744] <2> WinMain: DAT - standard output handle = 1912
11:39:30.203: [1592.6744] <2> WinMain: DAT - standard error handle = 1908
11:39:30.218: [1592.6744] <2> tar_process_restore_args: TRV - did not change name of file: /D/Veritas/NetBackup/logs/ALTPATH/ALTPATH.326
11:39:30.218: [1592.6744] <4> tar_restore::backup_list: INF - MPX Restore ID:11760.001
11:39:30.218: [1592.6744] <4> tar_restore::backup_list: INF - dwJobData: ffffffff
11:39:30.218: [1592.6744] <4> tar_restore::backup_list: INF - dwJob: ffffffff
11:39:30.218: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - restore privileges enabled, previous = 0
11:39:30.218: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - backup privileges enabled, previous = 0
11:39:30.218: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - security privileges enabled, previous = 0
11:39:30.218: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - create token privileges enabled, previous = 0
11:39:30.234: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - take ownership privileges enabled, previous = 0
11:39:30.437: [1592.6744] <4> OVGetRestoreToken: INF - successfully wrote restore information to named pipe
11:39:30.437: [1592.6744] <4> OVGetRestoreToken: INF - success obtaining access token
11:39:30.437: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - success obtaining access token: (1708)
11:39:30.437: [1592.6744] <4> base_restore::tfs_enable_restore_privileges: INF - user has restore privileges
11:39:30.437: [1592.2248] <2> ov_log::V_GlobalLog: INF - BEDS_Init() Enter InitFlags:0x0
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: C:
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: D:
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: E:
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Terminal Services
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Microsoft Windows Network
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Web Client Network
11:39:30.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - DumpDleInfo() DLE Device Name: Shadow?Copy?Components
11:39:31.437: [1592.5876] <4> tar_base::V_KeepaliveThread: INF - The Keepalive thread is active. Keepalive interval 30 Seconds
11:40:23.390: [1592.6744] <2> tar_base::V_vTarMsgM: CKP - 11760.001 1277289623 0 0 6 1 0 U 12 /E/BPATARCH/
11:40:23.390: [1592.6744] <2> tar_base::V_vTarMsgW: WRN - 'Server' service needs to be restarted for share to take effect: E:\NetBackup_Restores\E\BPATARCH
11:40:23.390: [1592.6744] <2> tar_base::V_vTarMsgW: FTL - Unexpected EOF in tar file
11:40:23.390: [1592.6744] <2> tar_base::backup_finish: TAR - restore: 3 files
11:40:23.390: [1592.6744] <2> tar_base::backup_finish: TAR - restore: file data: 1020 bytes
11:40:23.390: [1592.6744] <2> tar_base::backup_finish: TAR - restore: image data: 9216 bytes
11:40:23.390: [1592.6744] <2> tar_base::backup_finish: TAR - restore: elapsed time: 52 secs 177 bps
11:40:23.437: [1592.5876] <4> tar_base::V_KeepaliveThread: INF - Keepalive Thread Terminating. Mutex:WAIT_OBJECT_0
11:40:23.437: [1592.6744] <4> tar_base::V_StopKeepaliveThread: INF - The Keepalive Thread has Exited. Wait Reason:WAIT_OBJECT_0
11:40:23.484: [1592.2248] <2> ov_log::V_GlobalLog: INF - BEDS_Term() Enter InitFlags:0x1
11:40:23.484: [1592.6744] <4> OVStopCmd: INF - EXIT - status = 0
11:40:23.484: [1592.6744] <4> OVShutdown: INF - Finished process
11:40:23.531: [1592.6744] <4> WinMain: INF - Exiting D:\Veritas\NetBackup\bin\tar32.exe
11:40:25.531: [1592.6744] <4> ov_log::OVClose: INF - Closing log file: D:\Veritas\NetBackup\logs\TAR\062310.LOG


Any ideas please?

 

[Martin999]

Have you got the bpbrm log ?

Martin

 

[markdavies1978]

Does this help?


09:24:55 (11754.xxx) Restore job id 11754 will require 1 image.
09:24:55 (11754.xxx) Media id CR4654 is needed for the restore.
09:24:59 (11754.001) Restoring from image created 17/06/2010 17:30:01
09:25:02 (11754.001) INF - Waiting for mount of media id CR4654 on server cahp3r3cbak01 for reading.
09:25:08 (11754.001) INF - TAR STARTED
09:26:01 (11754.001) INF - Waiting for positioning of media id CR4654 on server cahp3r3cbak01 for reading.
09:26:01 (11754.001) INF - Beginning restore from server cahp3r3cbak01 to client CAHP3R3CBAK01.
09:26:02 (11754.001) FTL - Unexpected EOF in tar file
09:26:02 (11754.001) INF - TAR EXITING WITH STATUS = 5
09:26:02 (11754.001) INF - TAR RESTORED 0 OF 0 FILES SUCCESSFULLY
09:26:02 (11754.001) INF - TAR KEPT 0 EXISTING FILES
09:26:02 (11754.001) INF - TAR PARTIALLY RESTORED 0 FILES
09:26:03 (11754.001) The following files/folders were not restored:
09:26:03 (11754.001) UTF - /E/BPATARCH/006/927/6927195.bpat
09:26:04 (11754.001) Status of restore from image created 17/06/2010 17:30:01 = tar did not find all the files to be restored
09:26:04 (11754.xxx) INF - Status = the restore failed to recover the requested files.09:24:54 23/06/2010: Restore Started