09-20-2018 09:15 AM
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)
09-20-2018 11:07 PM
09-21-2018 04:56 AM
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.