Restore Error with SQL Backup 5.3

phowlettphowlett Posts: 5
edited December 16, 2008 4:50AM in SQL Backup Previous Versions
I have received the following error when attempting to restore a full backup using SQL backup 5.3:

MSSQL2K5-MIR01: Restore - Failed
================================

Restoring full backup - Failed

This operation failed with errors.

Restoring ispi_live (database) from:
E:\MSSQL\Backup\FULL_(local)_live_20081106_141420.sqb
Thread 0 error:
Process terminated unexpectedly. Error code: -2139684853
Thread 1 error:
Failed to service command. Error code: -2139684860
Process terminated unexpectedly. Error code: -2139684860
Thread 2 error:
Failed to service command. Error code: -2139684860
Process terminated unexpectedly. Error code: -2139684860
SQL error 3013: SQL error 3013: RESTORE DATABASE is terminating abnormally.
SQL error 3203: SQL error 3203: Read on "SQLBACKUP_43FF1451-84E8-4E4B-BCE7-2674E9CB69E002" failed: 995(The I/O operation has been aborted because of either a thread exit or an application request.)
SQL error 3271: SQL error 3271: A nonrecoverable I/O error occurred on file "SQLBACKUP_43FF1451-84E8-4E4B-BCE7-2674E9CB69E001:" 995(The I/O operation has been aborted because of either a thread exit or an application request.).
SQL Backup exit code: 790
SQL error code: 3271



Updating SQL Server information - Pending

Operation pending.



I was able to repeat this same problem with 3 different full backups of the same database instance. Those 3 failed restores were all backed up using multiple threads (3) and full compression. When I took a backup without using the multiple threads option (and lower compression) the restore operation succeeded.

Are there any known issues with use of multiple threads (a new feature in 5.3?) during a backup?

Thanks,
Paul
· Share on Twitter

Comments

  • peteypetey Posts: 2,358 New member
    Could you please post the contents of the SQL Backup log for the backup that failed? By default, the log files are stored in C:\Documents and Settings\All Users\Application Data\Red Gate\SQL Backup\Log\<instance name>.

    Also, if you were to convert the SQL Backup file to regular SQL Server backup files, using the CONVERT command or the sqb2mtf utility, are you able to restore the database using the converted files?
    Peter Yeoh
    SQL Backup Consultant Developer
    Associate, Yohz Software
    Beyond compression - SQL Backup goodies under the hood, updated for version 8
    · Share on Twitter
  • phowlettphowlett Posts: 5
    Logs from one of the failed restore attempts as follows: 
    06/11/2008 13:32:23: Reading SQB file header of "E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_124235.sqb"

06/11/2008 13:32:23: Backup group ID     : 50599725
06/11/2008 13:32:23: File number         : 1 of 1
06/11/2008 13:32:23: Threads             : 3 &#40;multi-threaded file&#41;

06/11/2008 13:32:23: Backup type         : 1 &#40;Database&#41;
06/11/2008 13:32:23: Native backup size  : 122.977 GB
06/11/2008 13:32:23: Database size       : 313.148 GB
06/11/2008 13:32:23: Backup start        : 06 November 2008 12:42:35
06/11/2008 13:32:23: Backup end          : 06 November 2008 13:00:58

06/11/2008 13:32:23: Server name         : MSDB03
06/11/2008 13:32:23: Database name       : ispi_live

06/11/2008 13:32:23: First LSN           : 1667769000007261300202
06/11/2008 13:32:23: Last LSN            : 1667770000001120300001
06/11/2008 13:32:23: Checkpoint LSN      : 1667769000007261300202
06/11/2008 13:32:23: Database backup LSN : 1667738000005405500116

SQL Backup log file 5.3.0.178

-SQL "RESTORE FILELISTONLY FROM DISK='E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_124235.sqb' "

-----------------------  PROCESSES COMPLETED SUCCESSFULLY   --------------------

06/11/2008 13:32:23: Reading filelist of "E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_124235.sqb"

06/11/2008 13:32:23: RESTORE FILELISTONLY FROM VIRTUAL_DEVICE = 'SQLBACKUP_3D691755-004C-4AC5-958C-224BA27D7ADE' WITH BUFFERCOUNT = 6, BLOCKSIZE = 65536, MAXTRANSFERSIZE = 1048576

06/11/2008 13:32:24: Logical name  : ispi_live_Data
Type          : D &#40;Data file&#41;
Physical name : E:\MSSQL\Data\ispi_live_Data.mdf
Size          : 304.369 GB

Logical name  : ispi_live_Log
Type          : L &#40;Log file&#41;
Physical name : E:\MSSQL\Data\ispi_live_Log.ldf
Size          : 8.779 GB


06/11/2008 13:32:24: SQL Backup process ended.


SQL Backup log file 5.3.0.178

-SQL "RESTORE DATABASE &#91;ispi_live&#93;  FROM DISK = 'E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_124235.sqb' WITH NORECOVERY "

-----------------------------  ERRORS AND WARNINGS -----------------------------


06/11/2008 13:33:44: Restoring ispi_live &#40;database&#41; from: 
06/11/2008 13:33:44:   E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_124235.sqb

06/11/2008 13:33:44: RESTORE DATABASE &#91;ispi_live&#93;  FROM VIRTUAL_DEVICE = 'SQLBACKUP_7B9121DA-57C0-44C9-A25F-09A86FF46A16', VIRTUAL_DEVICE = 'SQLBACKUP_7B9121DA-57C0-44C9-A25F-09A86FF46A1601', VIRTUAL_DEVICE = 'SQLBACKUP_7B9121DA-57C0-44C9-A25F-09A86FF46A1602' WITH BUFFERCOUNT = 18, BLOCKSIZE = 65536, MAXTRANSFERSIZE = 1048576 , NORECOVERY

06/11/2008 13:49:56: Thread 0 error: 
Process terminated unexpectedly. Error code: -2139684853
06/11/2008 13:49:56: 
06/11/2008 13:49:56: Thread 1 error: 
Failed to service command.  Error code: -2139684860
Process terminated unexpectedly. Error code: -2139684860
06/11/2008 13:49:56: 
06/11/2008 13:49:56: Thread 2 error: 
Failed to service command.  Error code: -2139684860
Process terminated unexpectedly. Error code: -2139684860
06/11/2008 13:49:56: 
SQL error 3013: SQL error 3013: RESTORE DATABASE is terminating abnormally.
SQL error 3271: SQL error 3271: A nonrecoverable I/O error occurred on file "SQLBACKUP_7B9121DA-57C0-44C9-A25F-09A86FF46A1601:" 995&#40;The I/O operation has been aborted because of either a thread exit or an application request.&#41;.

    Logs from successful restore (after backup using single thread) 
    06/11/2008 17:28:04: Reading SQB file header of "E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_154451.sqb"

06/11/2008 17:28:04: Backup group ID     : 243946205
06/11/2008 17:28:04: File number         : 1 of 1

06/11/2008 17:28:04: Backup type         : 1 &#40;Database&#41;
06/11/2008 17:28:04: Native backup size  : 123.032 GB
06/11/2008 17:28:04: Database size       : 313.148 GB
06/11/2008 17:28:04: Backup start        : 06 November 2008 15:44:51
06/11/2008 17:28:04: Backup end          : 06 November 2008 16:12:48

06/11/2008 17:28:04: Server name         : MSDB03
06/11/2008 17:28:04: Database name       : ispi_live

06/11/2008 17:28:04: First LSN           : 1667773000007651500151
06/11/2008 17:28:04: Last LSN            : 1667774000003283400001
06/11/2008 17:28:04: Checkpoint LSN      : 1667773000007651500151
06/11/2008 17:28:04: Database backup LSN : 1667771000006950500213

SQL Backup log file 5.3.0.178

-SQL "RESTORE FILELISTONLY FROM DISK='E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_154451.sqb' "

-----------------------  PROCESSES COMPLETED SUCCESSFULLY   --------------------

06/11/2008 17:28:04: Reading filelist of "E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_154451.sqb"

06/11/2008 17:28:04: RESTORE FILELISTONLY FROM VIRTUAL_DEVICE = 'SQLBACKUP_A4E3EFF3-EB55-4EC0-9106-AEF5E91B285D' WITH BUFFERCOUNT = 6, BLOCKSIZE = 65536, MAXTRANSFERSIZE = 1048576

06/11/2008 17:28:05: Logical name  : ispi_live_Data
Type          : D &#40;Data file&#41;
Physical name : E:\MSSQL\Data\ispi_live_Data.mdf
Size          : 304.369 GB

Logical name  : ispi_live_Log
Type          : L &#40;Log file&#41;
Physical name : E:\MSSQL\Data\ispi_live_Log.ldf
Size          : 8.779 GB


06/11/2008 17:28:05: SQL Backup process ended.

SQL Backup log file 5.3.0.178

-SQL "RESTORE DATABASE &#91;ispi_live&#93;  FROM DISK = 'E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_154451.sqb' WITH NORECOVERY "

-----------------------  PROCESSES COMPLETED SUCCESSFULLY   --------------------

06/11/2008 17:28:28: Restoring ispi_live &#40;database&#41; from: 
06/11/2008 17:28:28:   E:\MSSQL\Backup\FULL_&#40;local&#41;_ispi_live_20081106_154451.sqb

06/11/2008 17:28:28: RESTORE DATABASE &#91;ispi_live&#93;  FROM VIRTUAL_DEVICE = 'SQLBACKUP_D635C696-244E-491F-8789-F2D842C87388' WITH BUFFERCOUNT = 6, BLOCKSIZE = 65536, MAXTRANSFERSIZE = 1048576 , NORECOVERY

Processed 16122488 pages for database 'ispi_live', file 'ispi_live_Data' on file 1.
Processed 3365 pages for database 'ispi_live', file 'ispi_live_Log' on file 1.
RESTORE DATABASE successfully processed 16125853 pages in 3002.425 seconds &#40;43.998 MB/sec&#41;.
06/11/2008 18:18:38: SQL Backup process ended.

    Sorry about the delay in getting these logs to you. Hope they help.

    Paul
    · Share on Twitter
Sign In or Register to comment.