Video Screencast Help
Symantec to Separate Into Two Focused, Industry-Leading Technology Companies. Learn more.

NDMP Restore - Status 2813

Created: 25 Sep 2013 | 10 comments

Hi

We are having an issue when we try to restore over a file on NDMP from Netbackup.

Only all the files on that particular directory is failing when we try to perform a restore. The rest of the files (those that reside on different directory but same volume) - restore is successful with no issues.

Below are the logs we received:

9/24/2013 10:50:09 AM - Info bprd(pid=10104) Restoring from copy 1 of image created 09/13/13 19:00:37   
9/24/2013 10:50:09 AM - requesting resource @xxxbn
9/24/2013 10:50:09 AM - granted resource MediaID=@xxxbn;DiskVolume=AADC_NDMP_PRI_CS06;DiskPool=AADC-PRIMARY-NDMP;Path=AADC_NDMP_PRI_CS06;StorageServer=172.XX.XXX.XXX;MediaServer=zzzzzzz
9/24/2013 10:50:10 AM - Info bpbrm(pid=10176) rsexmwssxxx-b is the host to restore to     
9/24/2013 10:50:10 AM - Info bpbrm(pid=10176) reading file list from client       
9/24/2013 10:50:12 AM - connecting
9/24/2013 10:50:12 AM - Info bpbrm(pid=10176) starting bptm          
9/24/2013 10:50:12 AM - Info ndmpagent(pid=8108) Restore started          
9/24/2013 10:50:12 AM - connected; connect time: 00:00:00
9/24/2013 10:50:12 AM - Info bptm(pid=9336) start           
9/24/2013 10:50:12 AM - started process bptm (9336)
9/24/2013 10:50:12 AM - Info bpdm(pid=9336) reading backup image         
9/24/2013 10:50:17 AM - begin reading
9/24/2013 10:50:17 AM - Info ndmpagent(pid=8108) INF - Restoring NDMP files from /vol/s002_vol15/qtree_tok_data/Aspac/SECTIONS/ACCOUNT/????/????/FOLDER/FOLDER2/file.xls to [See line below] 
9/24/2013 10:50:19 AM - Info ndmpagent(pid=8108) INF - Restoring NDMP files from [See line above] to /vol/s706_ndmp_restore/qtree_ndmp_restore/Japanese.xls 
9/24/2013 10:50:24 AM - Info ndmpagent(pid=8108) DAR enabled          
9/24/2013 10:50:31 AM - Info ndmpagent(pid=8108) xxxxxxxx-b: RESTORE: Destination volume is read-only. Aborting.     
9/24/2013 10:50:33 AM - Info ndmpagent(pid=8108) xxxxxxxx-b: RESTORE: RESTORE IS ABORTED       
9/24/2013 10:50:36 AM - Info ndmpagent(pid=8108) xxxxxxxx-b: Dump aborted.         
9/24/2013 10:50:38 AM - Error ndmpagent(pid=8108) NDMP restore failed from path /vol/s002_vol15      

9/24/2013 10:50:40 AM - end reading; read time: 00:00:23
9/24/2013 10:50:40 AM - Info ndmpagent(pid=8108) done. status: 5         
9/24/2013 10:50:41 AM - Info bptm(pid=9336) completed reading backup image        
9/24/2013 10:50:41 AM - Info bptm(pid=9336) EXITING with status 0 <----------       
9/24/2013 10:50:42 AM - Info ndmpagent(pid=8108) done. status: 5: the restore failed to recover the requested files 
9/24/2013 10:50:42 AM - Error bpbrm(pid=10176) client restore EXIT STATUS 5: the restore failed to recover the requested files
9/24/2013 10:50:42 AM - restored image xxxxxxxx-b_1379070037 - (the restore failed to recover the requested files(5)); restore time 00:00:33
9/24/2013 10:50:46 AM - end Restore; elapsed time: 00:00:40
NDMP policy restore error(2813)

 Could it be due to credentials/rights issue set from the filer on that particular directory that is causing the restore failure?

 Apprciate your inputs/thoughts..

 Thanks

Operating Systems:

Comments 10 CommentsJump to latest comment

RamNagalla's picture

9/24/2013 10:50:31 AM - Info ndmpagent(pid=8108) xxxxxxxx-b: RESTORE: Destination volume is read-only. Aborting.    

its clearly saying that its read-only... check with your  Filer admin if that volume is set to read-only for all , or allow read/write for specific users where the user accout that is being  used by Netbackup is not listed...

owsumkid's picture

Hi Nagalla

Thanks for the prompt reply..

To give you a brief overview.

We have F1 (filer1 source) and F2 (filer2 destination)

The thing is, we have F2 (filer2) as destination for restores with unrestricted state, but from the error it is saying that the DESTINATION is read-only.

We tried other files on different directory on F1 and restore them to F2 and restore was completed with no issues. 

Thanks

RamNagalla's picture

so the destination on F2 is same for the both successfull restore job and the failed restore job with error "read-only"

or they are to diffrerent diestination in F2?

owsumkid's picture

Hi Nagalla

Yes you are correct. There are files that can and cannot be restored on F2 from F1

RamNagalla's picture

that is strage.... 

could post the restore log of successfull job too?

owsumkid's picture

Hi

Pls find below

9/25/2013 5:25:11 PM - restoring image rxxxxxxxxx-b_1378465235
9/25/2013 5:25:11 PM - Info bprd(pid=1672) Restoring from copy 1 of image created 09/06/13 19:00:35   
9/25/2013 5:25:11 PM - requesting resource @xxxxbn
9/25/2013 5:25:11 PM - granted resource 9/25/2013 5:25:12 PM - Info bpbrm(pid=10804) xxxxxxxxxx-b is the host to restore to   

MediaID=@xxxbn;DiskVolume=AADC_NDMP_PRI_CS06;DiskPool=AADC-PRIMARY-NDMP;Path=AADC_NDMP_PRI_CS06;StorageServer=172.XX.XXX.XXX;MediaServer=zzzzzzz
  
9/25/2013 5:25:12 PM - Info bpbrm(pid=10804) reading file list from client       
9/25/2013 5:25:14 PM - connecting
9/25/2013 5:25:14 PM - Info bpbrm(pid=10804) starting bptm          
9/25/2013 5:25:14 PM - Info ndmpagent(pid=10656) Restore started          
9/25/2013 5:25:14 PM - connected; connect time: 00:00:00
9/25/2013 5:25:15 PM - Info bptm(pid=12560) start           
9/25/2013 5:25:15 PM - started process bptm (12560)
9/25/2013 5:25:15 PM - Info bpdm(pid=12560) reading backup image         
9/25/2013 5:25:19 PM - begin reading
9/25/2013 5:25:20 PM - Info ndmpagent(pid=10656) INF - Restoring NDMP files from /vol/s002_vol15/qtree_tok_data/File/File/ to [See line below] 
9/25/2013 5:25:22 PM - Info ndmpagent(pid=10656) INF - Restoring NDMP files from [See line above] to /vol/s705_ndmptest/qtree_ndmptest/File/file

9/25/2013 5:25:26 PM - Info ndmpagent(pid=10656) DAR enabled          
9/25/2013 5:30:29 PM - Info ndmpagent(pid=10656) xxxxxxxxxx-b: RESTORE: Wed Sep 25 17:30:29 2013 : We have read 284546 KB from the backup.
9/25/2013 5:33:49 PM - Info ndmpagent(pid=10656) xxxxxxxxxx-b: RESTORE: RESTORE IS DONE       
9/25/2013 5:33:54 PM - Info ndmpagent(pid=10656) NDMP restore successful from path /vol/s002_vol15      
9/25/2013 5:33:58 PM - end reading; read time: 00:08:39
9/25/2013 5:33:58 PM - Info ndmpagent(pid=10656) done. status: 0         
9/25/2013 5:34:00 PM - Info bptm(pid=12560) completed reading backup image        
9/25/2013 5:34:00 PM - Info bptm(pid=12560) EXITING with status 0 <----------       
9/25/2013 5:34:00 PM - Info ndmpagent(pid=10656) done. status: 0: the requested operation was successfully completed   
9/25/2013 5:34:01 PM - restored image xxxxxxxxxxx-b_1378465235 - (the requested operation was successfully completed(0)); restore time 00:08:50
9/25/2013 5:34:04 PM - end Restore; elapsed time: 00:08:56
the requested operation was successfully completed(0)

Thanks

owsumkid's picture

Hi Nagalla

Pls find below logs for successful restore :

9/25/2013 4:14:16 PM - restoring image xxxxxxxx_1378465235
9/25/2013 4:14:16 PM - requesting resource @xXXXX
9/25/2013 4:14:16 PM - granted resource MediaID=@XXXXXX;DiskVolume=AADC_NDMP_PRI_CS06;DiskPool=AADC-PRIMARY-NDMP;Path=AADC_NDMP_PRI_CS06;StorageServer=172.XX.XXX.XXX;MediaServer=XXXXXXXX
9/25/2013 4:14:17 PM - Info bprd(pid=13300) Restoring from copy 1 of image created 09/06/13 19:00:35   
9/25/2013 4:14:17 PM - Info bpbrm(pid=7676) XXXXXXXXXX-b is the host to restore to     
9/25/2013 4:14:17 PM - Info bpbrm(pid=7676) reading file list from client       
9/25/2013 4:14:19 PM - connecting
9/25/2013 4:14:19 PM - Info bpbrm(pid=7676) starting bptm          
9/25/2013 4:14:20 PM - Info ndmpagent(pid=728) Restore started          
9/25/2013 4:14:20 PM - connected; connect time: 00:00:01
9/25/2013 4:14:20 PM - Info bptm(pid=13000) start           
9/25/2013 4:14:20 PM - started process bptm (13000)
9/25/2013 4:14:20 PM - Info bpdm(pid=13000) reading backup image         
9/25/2013 4:14:24 PM - begin reading
9/25/2013 4:14:25 PM - Info ndmpagent(pid=728) INF - Restoring NDMP files from /vol/s002_vol15/qtree_tok_data/XXXXXX/XXXXXXXX/ to [See line below] 
9/25/2013 4:14:27 PM - Info ndmpagent(pid=728) INF - Restoring NDMP files from [See line above] to /vol/s705_ndmptest/qtree_ndmptest/XXXXXX/XXXXXXXX 
9/25/2013 4:14:32 PM - Info ndmpagent(pid=728) DAR enabled          
9/25/2013 4:19:38 PM - Info ndmpagent(pid=728) xXXXXXXXX-b: RESTORE: Wed Sep 25 16:19:38 2013 : We have read 282498 KB from the backup.
9/25/2013 4:22:40 PM - Info ndmpagent(pid=728) XXXXXXXXX-b: RESTORE: RESTORE IS DONE       
9/25/2013 4:22:45 PM - Info ndmpagent(pid=728) NDMP restore successful from path /vol/s002_vol15      
9/25/2013 4:22:49 PM - end reading; read time: 00:08:25
9/25/2013 4:22:49 PM - Info ndmpagent(pid=728) done. status: 0         
9/25/2013 4:22:50 PM - Info bptm(pid=13000) completed reading backup image        
9/25/2013 4:22:50 PM - Info bptm(pid=13000) EXITING with status 0 <----------       
9/25/2013 4:22:51 PM - Info ndmpagent(pid=728) done. status: 0: the requested operation was successfully completed   
9/25/2013 4:22:52 PM - restored image XXXXXXXXX-b_1378465235 - (the requested operation was successfully completed(0)); restore time 00:08:36
9/25/2013 4:22:55 PM - end Restore; elapsed time: 00:08:42
the requested operation was successfully completed(0)

Where: F1 is vols002 and F2 is vols705

owsumkid's picture

Please ignore topmost post as i used a different destination filer there.

Kindly instead refer to below for the failed job:

9/25/2013 4:58:14 PM - restoring image XXXXXXXXXXX_1378465235
9/25/2013 4:58:14 PM - Info bprd(pid=11040) Restoring from copy 1 of image created 09/06/13 19:00:35   
9/25/2013 4:58:14 PM - requesting resource @aaabn
9/25/2013 4:58:14 PM - granted resource MediaID=@XXXXXX;DiskVolume=AADC_NDMP_PRI_CS06;DiskPool=MSDC-PRIMARY-NDMP;Path=AADC_NDMP_PRI_CS06;StorageServer=172.XX.XXX.XXX;MediaServer=XXXXXXXXX
9/25/2013 4:58:15 PM - Info bpbrm(pid=12156) XXXXXXXXX-b is the host to restore to     
9/25/2013 4:58:15 PM - Info bpbrm(pid=12156) reading file list from client       
9/25/2013 4:58:17 PM - connecting
9/25/2013 4:58:17 PM - Info bpbrm(pid=12156) starting bptm          
9/25/2013 4:58:17 PM - Info ndmpagent(pid=6940) Restore started          
9/25/2013 4:58:17 PM - connected; connect time: 00:00:00
9/25/2013 4:58:17 PM - Info bptm(pid=12744) start           
9/25/2013 4:58:17 PM - started process bptm (12744)
9/25/2013 4:58:18 PM - Info bpdm(pid=12744) reading backup image         
9/25/2013 4:58:22 PM - begin reading
9/25/2013 4:58:23 PM - Info ndmpagent(pid=6940) INF - Restoring NDMP files from /vol/s002_vol15/qtree_tok_data/Global/Temp/??????/ to [See line below] 
9/25/2013 4:58:25 PM - Info ndmpagent(pid=6940) INF - Restoring NDMP files from [See line above] to /vol/s705_ndmptest/qtree_ndmptest/?????? 
9/25/2013 4:58:29 PM - Info ndmpagent(pid=6940) DAR enabled          
9/25/2013 5:03:31 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: RESTORE: Wed Sep 25 17:03:32 2013 : We have read 276114 KB from the backup.
9/25/2013 5:06:25 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: RESTORE: /vol/s002_vol15/qtree_tok_data/Global/Temp/ボランティア/ - cannot create directory: No such file or directory
9/25/2013 5:06:27 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: RESTORE: No files were created.      
9/25/2013 5:06:29 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: RESTORE: RESTORE IS ABORTED       
9/25/2013 5:06:31 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: Dump aborted.         
9/25/2013 5:06:33 PM - Error ndmpagent(pid=6940) NDMP restore failed from path /vol/s002_vol15      
9/25/2013 5:06:35 PM - end reading; read time: 00:08:13
9/25/2013 5:06:35 PM - Info ndmpagent(pid=6940) done. status: 5         
9/25/2013 5:06:37 PM - Info bptm(pid=12744) completed reading backup image        
9/25/2013 5:06:37 PM - Info bptm(pid=12744) EXITING with status 0 <----------       
9/25/2013 5:06:37 PM - Info ndmpagent(pid=6940) done. status: 5: the restore failed to recover the requested files 
9/25/2013 5:06:37 PM - Error bpbrm(pid=12156) client restore EXIT STATUS 5: the restore failed to recover the requested files
9/25/2013 5:06:39 PM - restored image XXXXXXXXXXX-b_1378465235 - (the restore failed to recover the requested files(5)); restore time 00:08:25
9/25/2013 5:06:42 PM - end Restore; elapsed time: 00:08:32
NDMP policy restore error(2813)
 

thanks

RamNagalla's picture

this time its a differnt error

/vol/s002_vol15/qtree_tok_data/Global/Temp/ボランティア/ - cannot create directory: No such file or directory
9/25/2013 5:06:27 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: RESTORE: No files were created.      
9/25/2013 5:06:29 PM - Info ndmpagent(pid=6940) XXXXXXXXXXXX-b: RESTORE: RESTORE IS ABORTED

are you able to browe the file that is provided as destinationation path?  

owsumkid's picture

The file I am trying to restore has Japanese Characters on it.

I have tried restoring other files from /vol/s002_vol15/qtree_tok_data/ (also with japanese characters) to F2 and got no issues.

The strange thing is that some directories on F1 (/vol/s002),  cant be restored to destination F2 (vol/s705)

which is what you highlighted:

/vol/s002_vol15/qtree_tok_data/Global/Temp/ボランティア/ - cannot create directory: No such file or directory

It seems that eventhough i have manually pointed the path of restore to F2, from the logs shown, it is tryng to still restore the file on the source location w/c is  F1..

I have logged this case with symantec and all they say is that it is a filer rights issue that i should address on our netapp admin.

I just want to seek other opinion from some experts here in this forum.