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

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
  • 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
Sign In or Register to comment.