cancel
Showing results for 
Search instead for 
Did you mean: 

File Read Failed (13)

wapeters
Level 2

Hi All

I am in need of some assitance, a Backup has been giving me problem for about a week now, and I've tried all i could.

Here are the log. anybody had the same issue? please assist.

09/20/2018 17:49:54 - Info nbjm (pid=5044) starting backup job (jobid=278245) for client AFPSQV01, policy ATLANTIS_AFPSQV01_FS, schedule DAILY
09/20/2018 17:49:54 - Info nbjm (pid=5044) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=278245, request id:{7349A2B2-5891-408A-92BF-4630339131D1})
09/20/2018 17:49:54 - requesting resource DEDUP_DISK-stu
09/20/2018 17:49:54 - requesting resource s175a089rclaf.atlantisfoundries.com.NBU_CLIENT.MAXJOBS.AFPSQV01
09/20/2018 17:49:54 - requesting resource s175a089rclaf.atlantisfoundries.com.NBU_POLICY.MAXJOBS.ATLANTIS_AFPSQV01_FS
09/20/2018 17:49:54 - granted resource s175a089rclaf.atlantisfoundries.com.NBU_CLIENT.MAXJOBS.AFPSQV01
09/20/2018 17:49:54 - granted resource s175a089rclaf.atlantisfoundries.com.NBU_POLICY.MAXJOBS.ATLANTIS_AFPSQV01_FS
09/20/2018 17:49:54 - granted resource MediaID=@aaaai;DiskVolume=PureDiskVolume;DiskPool=DEDUP_DISK;Path=PureDiskVolume;StorageServer=s175a089rclaf.atlantisfoundries.com;MediaServer=s175a089rclaf.atlantisfoundries.com
09/20/2018 17:49:54 - granted resource DEDUP_DISK-stu
09/20/2018 17:49:55 - estimated 131421328 kbytes needed
09/20/2018 17:49:55 - Info nbjm (pid=5044) resumed backup (backupid=AFPSQV01_1537442703) job for client AFPSQV01, policy ATLANTIS_AFPSQV01_FS, schedule DAILY on storage unit DEDUP_DISK-stu
09/20/2018 17:49:56 - started process bpbrm (pid=11272)
09/20/2018 17:49:56 - Info bpbrm (pid=11272) AFPSQV01 is the host to backup data from
09/20/2018 17:49:56 - Info bpbrm (pid=11272) reading file list for client
09/20/2018 17:49:57 - Info bpbrm (pid=11272) accelerator enabled
09/20/2018 17:50:04 - connecting
09/20/2018 17:50:05 - Info bpbrm (pid=11272) starting bpbkar32 on client
09/20/2018 17:50:05 - connected; connect time: 0:00:00
09/20/2018 17:50:07 - Info bpbkar32 (pid=8232) Backup started
09/20/2018 17:50:07 - Info bpbkar32 (pid=8232) change time comparison:<enabled>
09/20/2018 17:50:07 - Info bpbkar32 (pid=8232) accelerator enabled backup, archive bit processing:<disabled>
09/20/2018 17:50:07 - Info bptm (pid=10296) start
09/20/2018 17:50:07 - Info bpbkar32 (pid=8232) will attempt to use change journal data for <T:\>
09/20/2018 17:50:08 - Info bptm (pid=10296) using 262144 data buffer size
09/20/2018 17:50:08 - Info bptm (pid=10296) setting receive network buffer to 1049600 bytes
09/20/2018 17:50:08 - Info bptm (pid=10296) using 30 data buffers
09/20/2018 17:50:09 - Info bptm (pid=10296) resuming backup-id AFPSQV01_1537442703 copy 1 at fragment 13, 512 blocks: 173704910
09/20/2018 17:50:09 - Info bptm (pid=10296) start backup
09/20/2018 17:50:12 - Info bptm (pid=10296) backup child process is pid 11508.7180
09/20/2018 17:50:12 - Info bptm (pid=11508) start
09/20/2018 17:50:12 - begin writing
09/20/2018 17:50:32 - Info bpbkar32 (pid=8232) not using change journal data for <T:\>: no previous track log
09/20/2018 17:51:22 - Error bpbrm (pid=11272) socket read failed, An existing connection was forcibly closed by the remote host. (10054)
09/20/2018 17:51:22 - Error bptm (pid=11508) socket operation failed - 10054 (at ../child.c.1276)
09/20/2018 17:51:22 - Error bptm (pid=11508) unable to perform read from client socket, connection may have been broken
09/20/2018 17:51:26 - Info s175a089rclaf.atlantisfoundries.com (pid=10296) StorageServer=PureDisk:s175a089rclaf.atlantisfoundries.com; Report=PDDO Stats for (s175a089rclaf.atlantisfoundries.com): scanned: 3622971 KB, CR sent: 1375 KB, CR sent over FC: 0 KB, dedup: 100.0%, cache disabled
09/20/2018 17:51:27 - Error bpbrm (pid=11272) could not send server status message
09/20/2018 17:51:27 - Info bpbkar32 (pid=8232) done. status: 13: file read failed
09/20/2018 17:51:27 - end writing; write time: 0:01:15
file read failed (13)

 

 

2 REPLIES 2

Amol_Nair
Level 6
Employee
The job details show this message which could point to a network side issue

An existing connection was forcibly closed by the remote host. (10054)

But I do see that you have accelerated enabled in the policy which puts extra overhead on the client to check file checksum against the existing accelerator track log.. If there is any corruption in the tracklog itself you may see similar symptoms..

My first suggestion would be to test a backup without accelerator option enabled in it to rule this scenario out..

You can simply uncheck the use accelerator option in the policy to do this.

Btw I hope you are aware that nbu 7.6 is out of support long back..

Marianne
Moderator
Moderator
Partner    VIP    Accredited Certified

Strange that connection is broken within about 1 minute after backup start:

09/20/2018 17:50:12 - begin writing
09/20/2018 17:50:32 - Info bpbkar32 (pid=8232) not using change journal data for <T:\>: no previous track log
09/20/2018 17:51:22 - Error bpbrm (pid=11272) socket read failed, An existing connection was forcibly closed by the remote host. (10054)

This reminded me of a similar issue that I saw some time ago (although status code is different):
backup or restore fails with status 41 a few seconds after bpbrm starts
https://www.veritas.com/support/en_US/article.000086408

Please check if settings such as SERVER_PORT_WINDOW or CLIENT_PORT_WINDOW has been changed.

Ensure that bpbrm log folder exists on the media server and bpbkar and bpcd folders on the client for further troubleshooting.