cancel
Showing results for 
Search instead for 
Did you mean: 

the restore failed to recover the requested files(5)... While alternate restore of SQL

Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited
Activity Monitor

7/27/2010 5:16:43 PM - begin Restore
7/27/2010 5:17:16 PM - 1 images required
7/27/2010 5:17:16 PM - media 0017L4 required
7/27/2010 5:17:20 PM - restoring image sql-cluster_1280160605
7/27/2010 5:17:28 PM - requesting resource 0017L4
7/27/2010 5:17:28 PM - awaiting resource 0017L4 Reason: Media is in use, Media Server: N/A,
     Robot Number: NONE, Robot Type: NONE, Media ID: 0017L4, Drive Name: N/A,
     Volume Pool: N/A, Storage Unit: N/A, Drive Scan Host: N/A
    
7/27/2010 5:17:35 PM - connected
7/27/2010 5:18:38 PM - granted resource 0017L4
7/27/2010 5:18:38 PM - granted resource IBM.ULT3580-TD4.002
7/27/2010 5:18:39 PM - started process bptm (61848)
7/27/2010 5:18:39 PM - mounting 0017L4
7/27/2010 5:19:21 PM - mounted; mount time: 00:00:42
7/27/2010 5:19:23 PM - positioning 0017L4 to file 79
7/27/2010 5:21:09 PM - positioned 0017L4; position time: 00:01:46
7/27/2010 5:21:09 PM - begin reading
7/27/2010 7:17:36 PM - Error bptm(pid=61988) The following files/folders were not restored:       
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) UTF - /dbserver-sec.MSSQL7.SQL-CLUSTER.db.xxxx.~.7.001of001.20100726210954..C          
7/27/2010 7:17:36 PM - Error bptm(pid=61988) more than 10 files were not restored, remaining ones are shown in the progress log.
7/27/2010 7:17:37 PM - restored image sql-cluster_1280160605 - (file read failed(13)); restore time 02:00:17
7/27/2010 7:17:43 PM - end Restore; elapsed time: 02:01:00
the restore failed to recover the requested files(5)


I also attached temporarly the dpclient logs for reference


14 REPLIES 14

varunprakash
Level 5
Hi,

I cant able to open the zip file. Can u please paste it?

Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited
please rename it to rar

varunprakash
Level 5

Marianne
Level 6
Partner    VIP    Accredited Certified

I think the problem is with log file itself - I see the uncompressed size is 4 282 222 132 bytes!!!
What on earth is your logging level set to? For everyday troubleshooting a logging level of 0 (zero) is sufficient to solve most problems. ONLY increase logging levels when Symantec Support asks you for a level 5 log (Most of the time I bargain with them to settle for a level 3 due to the problems CAUSED by high logging levels). Support engineers have tools to extract relevant info.

My 4 Gig memory 64-bit laptop is battling to open your log file - been sitting with hourglass for couple of minutes now. No wonder you're getting File Read error.........

Please move/rename this log file, drop logging level to 0 and retry the restore.

varunprakash
Level 5
Check the following TN which may help u.

http://seer.entsupport.symantec.com/docs/275115.htm

Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited
Hi Marianne

Finally i got successful to read this log file in linux. I am pasting few things from the logs below




17:07:08.284 [318360.328408] <16> DBthreads::dbclient: ERR - Error in VxBSAGetDa
ta: 3.
17:07:08.284 [318360.328408] <1> DBthreads::dbclient:     CONTINUATION: - System
 detected error, operation aborted.

................................................................................................................................................................
................................................................................................................................................................

17:07:08.284 [318360.328408] <16> CDBbackrec::ProcessVxBSAerror: ERR - Error in DBthreads::dbclient: 6.
17:07:08.284 [318360.328408] <1> CDBbackrec::ProcessVxBSAerror:     CONTINUATION: - The system cannot find the file specified.
17:07:08.284 [318360.328408] <16> DBthreads::dbclient: ERR - Error in VxBSAEndData: 6.

................................................................................................................................................................
................................................................................................................................................................

17:07:11.284 [318360.327856] <4> dbc_RemoteWriteFile: VERITAS-NETBKUP root 37 logs/mssql_backup_failures/072710.rpt a 585 TIMESTAMP 20100727170711
MASTERSERVER VERITAS-NETBKUP
CLIENT EBI
INSTANCE EBI
DATABASE SonawareDotNetSAS
OPERATION RESTORE
OBJECTTYPE DATABASE
STATUS -1
INFO Server Status:  Communication with the server has not been iniatated or the server status has not been retrieved from the server.
INFO Error in VxBSAGetData: 3.
INFO System detected error, operation aborted.
INFO Error in DBthreads::dbclient: 6.
INFO The system cannot find the file specified.
INFO Error in VxBSAEndData: 6.
................................................................................................................................................................
................................................................................................................................................................

17:07:12.003 [318360.327856] <16> CDBbackrec::FreeDeviceSet(): ERR - Error in VDS->Close: 0x80770004.

................................................................................................................................................................
................................................................................................................................................................

17:07:12.003 [318360.327856] <16> CDBbackrec::FreeDeviceSet(): ERR - Error in VDS->Close: 0x80770004.
17:07:12.003 [318360.327856] <1> CDBbackrec::FreeDeviceSet():     CONTINUATION: - An abort request is preventing anything except termination actions.
17:07:12.003 [318360.327856] <4> Dbbackrec::Perform: INF - OPERATION #1 of batch C:\Program Files\Veritas\NetBackup\DbExt\MsSql\Sona_SAS_27-07-2010.bch FAILED with STATUS 1 (0 is normal). Elapsed time = 5736(0) seconds.
17:07:14.002 [318360.328088] <4> CDBbackmain::dbbackup: INF - Results of executing <C:\Program Files\Veritas\NetBackup\DbExt\MsSql\Sona_SAS_27-07-2010.bch>: ^M
<0> operations succeeded. <1> operations failed.
17:07:14.002 [318360.328088] <4> VxBSATerminate: INF - entering VxBSATerminate.
17:07:14.002 [318360.328088] <4> VxBSAGetEnv: INF - entering GetEnv - NBBSA_DEBUGFD
17:07:14.002 [318360.328088] <4> VxBSAGetEnv: INF - returning -
17:15:04.118 [325820.327720] <8> CDBbackupView::ClassDropDownList: WARN - Error: bppllist not found in C:\Program Files\Veritas\NetBackup\bin\admincmd\bppllist.exe.


==========================================================================

17:07:07.393 [318360.328408] <16> dbc_get: ERR - failed reading data from server, bytes read = -1
17:07:07.393 [318360.328408] <4> closeApi: entering closeApi.
17:07:07.393 [318360.328408] <4> closeApi: INF - EXIT STATUS 5: the restore failed to recover the requested files




   

Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited

What you can say regarding this SYM article

http://seer.entsupport.symantec.com/docs/336398.htm

In the SOLUTION / WORKAROUND: see point two. As per the point 2 do i replace the default value of maxtransfersize to 10?

varunprakash
Level 5
Yes, change both TcpMaxDataRetransmissions and TcpMaxConnectRetransmissions to value 10 & 3 respectively.

Marianne
Level 6
Partner    VIP    Accredited Certified
dbc_RemoteWriteFile: VERITAS-NETBKUP root 37 logs/mssql_backup_failures/072710.rpt

Status 37:
http://seer.entsupport.symantec.com/docs/288230.htm


Please confirm this in bprd log on master.

Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited

Hi varun

Thanks for your kind reply.

I am not able to see both TcpMaxDataRetransmissions and TcpMaxConnectRetransmissions. I can see  maxtransfersize means TcpMaxDataRetransmissions. But i dont know where is TcpMaxConnectRetransmissions in the restore script of SQL.


Hi Marianne.

I am not able to open the document which you suggested me


varunprakash
Level 5
Hi,

TcpMaxConnectRetransmissions is not available in SQL script. Its a windows registry.
It is used to determines how many times TCP retransmits requests for new connections. When sending data on existing connections, the maximum number of retransmissions is determined by the value of the TcpMaxDataRetransmissions entry.

FYI..

http://technet.microsoft.com/en-us/library/cc758896(WS.10).aspx

Marianne
Level 6
Partner    VIP    Accredited Certified
Zahid - I think the TechNote that you found is more appropriate that the one I found. Have you tried the solution that is listed at the bottom of the doc?
SOLUTION / WORKAROUND:
1.  Verify if the NetBackup client is 6.5
2. Check the backup script and find the maxtransfersize value. (Incase if the script was created using NetBackup version 5.x or 6.0 the maxtransfersize is by default 0)
3. Match the maxtransfersize value in the restore script.
4. Run the restore and it should be successful.

About the TN I found:
I just clicked on the link and it opened....

http://seer.entsupport.symantec.com/docs/288230.htm


STATUS CODE 37: SQL restore attempts fail with NetBackup Status Code 37 (operation requested by an invalid server).

Exact Error Message
operation requested by an invalid server
 
Details:
Overview:
When attempting to restore a Microsoft SQL server database, the restore fails with a NetBackup Status Code 37 (operation requested by an invalid server).

Troubleshooting:
On the Windows master server, look to see if there is a directory with the same name as the SQL server in the <install_path>\veritas\netbackup\db\client directory (check in the /usr/openv/netbackup/db/client directory on a UNIX/Linux master server).  

Log Files:  N/A

Resolution:
All clients have the ability to browse for and restore files by default.  If, however, this option is or changed, the restore attempts fail with a NetBackup Status Code 37 (operation requested by an invalid server).

The ability to browse for and restore images is set on the master sever in the NetBackup Administration Console.  Open the Administration Console, and under NetBackup Management expand Host Properties.  Select the master server, and in the right hand window pane, right click on the master server, and select Properties.  

Figure 1
 

Under Properties, click on Client Attributes.  If the following is displayed, no changes have been made to the client attributes, and there should be no problems with the restore.

Figure 2
 

If, however, the NetBackup interface is as shown in Figure 3, the ability to browse for and restore images is disabled for the specified client (in the image, client_01)

Figure 3
 

To re-enable the ability to browse for and restore images, simply highlight the client, and delete it from the list.  

It is also possible to change this setting from the command line using the bpclient command (see the NetBackup Command Guides in related documents section below for assistance with this command)


Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited
Thanks all for your kind replies


Hi marianne

I have checked and there is no client in the Clients attributes

Hi Varun

As per your suggestion from the below article


http://seer.entsupport.symantec.com/docs/336398.htm

In the SOLUTION / WORKAROUND: As per the point 2 i replaced the default value of maxtransfersize to 10 , But the restore is not being triggered even i restarted NBU Server


Zahid_Haseeb
Moderator
Moderator
Partner    VIP    Accredited
Any help will be appriciated