SQLBackup Restore: increasing duration and ReadFile failed attempts

BTreeToBTree

Hi,

We have a weekly process to restore a prod database to CHECKDB, over the past few weeks we have noticed an increase in duration and notifications regarding - Warning 204: ReadFile failed for file:

The redacted command being used -

RESTORE DATABASE [****_****]

FROM

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-49A4-ABE2-70A320C49589',

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-49A4-ABE2-70A320C4958901',

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-49A4-ABE2-70A320C4958902',

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-49A4-ABE2-70A320C4958903',

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-49A4-ABE2-70A320C4958904',

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-49A4-ABE2-70A320C4958905',

VIRTUAL_DEVICE = 'SQLBACKUP_E14EDDCF-A1AE-4 9A4-ABE2-70A320C4958906'

WITH BUFFERCOUNT = 28, BLOCKSIZE = 65536, MAXTRANSFERSIZE = 524288 , RECOVERY,

MOVE '****_****_Data' TO 'S:\Tech\Databases\****_****.mdf',

MOVE '****_****_****1' TO 'S:\Tech\Databases\****_****_****1_****.ndf',

MOVE '****_****_****2' TO 'S:\Tech\Databases\****_****_****2_****.ndf',

MOVE '****_****_****3' TO 'S:\Tech\Databases\****_****_****3_****.ndf',

MOVE '****_****_****4' TO 'S:\Tech\Databases\****_****_****4_****.ndf',

MOVE '****_****_****' TO 'S:\Tech\Databases\****_****_****_****.ndf',

MOVE '****_****_Log' TO 'S:\Tech\Databases\****_****_log.ldf',

REPLACE

The redacted log lines -

1/7/2025 10:02:35 AM: Processed 259337336 pages for database '****_****', file '****_****_Data' on file 1.

1/7/2025 10:02:35 AM: Processed 280 pages for database '****_****', file '****_****_****1' on file 1.

1/7/2025 10:02:35 AM: Processed 280 pages for database '****_****', file '****_****_****2' on file 1.

1/7/2025 10:02:35 AM: Processed 264 pages for database '****_****', file '****_****_****3' on file 1.

1/7/2025 10:02:35 AM: Processed 272 pages for database '****_****', file '****_****_****4' on file 1.

1/7/2025 10:02:35 AM: Processed 375619592 pages for database '****_****', file '****_****_Consumption' on file 1.

1/7/2025 10:02:35 AM: Processed 28544 pages for database '****_****', file '****_****_Log' on file 1.

1/7/2025 10:02:35 AM: RESTORE DATABASE successfully processed 634986568 pages in 201160.683 seconds (24.661 MB/sec).

1/7/2025 10:02:35 AM: Warning 204: ReadFile failed for file: \\****\restores$\****\Fulls\****_****\FULL_(local)_****_****_20250103_230049.sqb at position: 68251289600

1/5/2025 11:00:15 AM: Re-attempt: 1

1/5/2025 11:00:16 AM: File (\\****-dat

a\restores$\****\Fulls\****_****\FULL_(local)_****_****_20250103_230049.sqb) reopened and read succeeded (524288 bytes) at position (68251289600).

ReadFile failed for file: \\****\restores$\****\Fulls\****_****\FULL_(local)_****_****_

20250103_230049.sqb at position: 108206753792

1/5/2025 12:47:56 PM: Re-attempt: 2

1/5/2025 12:48:18 PM: File (\\****\restores$\****\Fulls\****_****\FULL_(local)_****_****_20250103_230049.sqb) reopened and read succeeded (524288 bytes) at pos ition (108206753792).

ReadFile failed for file: \\****\restores$\****\Fulls\****_****\FULL_(local)_****_****_20250103_230049.sqb at position: 464009037824

1/6/2025 9:52:03 AM: Re-attempt: 3

1/6/2025 9:52:03 AM: File (\\****\restores$\S

QLCLUSTER01\Fulls\****_****\FULL_(local)_****_****_20250103_230049.sqb) reopened and read succeeded (524288 bytes) at position (464009037824).

ReadFile failed for file: \\****\restores$\****\Fulls\****_****\FULL_(local)_****_****_20250103_230

049.sqb at position: 1308494399488

1/7/2025 4:24:45 AM: Re-attempt: 4

1/7/2025 4:24:46 AM: File (\\****\restores$\****\Fulls\****_****\FULL_(local)_****_****_20250103_230049.sqb) reopened and read succeeded (524288 bytes) at position (130849 4399488).

1/7/2025 10:02:35 AM: Warning 445: OLEDB error running parameterised command: attempt to write a readonly database

What could be the cause of the Re-attempt entries and could this be impacting the duration?

Many Thanks

Stephen

squigley

Hello Stephen,
Thanks for your inquiry with Redgate SQL Backup.
This is an informational warning message as detailed here:
https://documentation.red-gate.com/sbu/errors-and-warnings/sql-backup-warnings-1-499


Potential causes include the involved file being large and there being a lot of restores happening and it just times out a couple times before succeeding, but it's hard to determine an exact answer from the provided log entries.

BTreeToBTree

Hi Sean,

Thanks for responding, we tick both boxes for large files and a few restores happening.

As these are timeouts I'll get someone to review server  / network / storage.