Windows backup - Socket Write Failed

[MG11]

Windows server (WAS) backup is failing with error code 13. On checking bpbkar logs "socket write failed" error is observed.
We found that timezone on server and client were different, but even after correcting them issue was there. 
Then we tried a manual backup by unticking the BMR option in policy and the backup succeeded, as the backup was earlier trying to create a "tir" file.

Since Symantec BMR is required for Windows Client, so i need help to get this issue resolved.

GUI LOGS

03/29/2016 18:08:21 - Info nbjm (pid=9186) starting backup job (jobid=22008) for client vneswas1bkp, policy vneswas1bkp_windows, schedule Weekly_Full
03/29/2016 18:08:21 - Info nbjm (pid=9186) requesting STANDARD_RESOURCE resources from RB for backup job (jobid=22008, request id:{1F3B4BCC-F5AB-11E5-BD8A-ECB1D78B68E0})
03/29/2016 18:08:21 - requesting resource vnesombs1bkp-hcart3-robot-tld-0
03/29/2016 18:08:21 - requesting resource vnesombs1bkp.NBU_CLIENT.MAXJOBS.vneswas1bkp
03/29/2016 18:08:21 - requesting resource vnesombs1bkp.NBU_POLICY.MAXJOBS.vneswas1bkp_windows
03/29/2016 18:08:21 - awaiting resource vnesombs1bkp-hcart3-robot-tld-0. Waiting for resources. 
          Reason: Media is in use, Media server: vnesombs1bkp, 
          Robot Type(Number): TLD(0), Media ID: N/A, Drive Name: N/A, 
          Volume Pool: COMInf_Pool, Storage Unit: vnesombs1bkp-hcart3-robot-tld-0, Drive Scan Host: N/A, 
          Disk Pool: N/A, Disk Volume: N/A 
03/29/2016 18:36:23 - granted resource  vnesombs1bkp.NBU_CLIENT.MAXJOBS.vneswas1bkp
03/29/2016 18:36:23 - granted resource  vnesombs1bkp.NBU_POLICY.MAXJOBS.vneswas1bkp_windows
03/29/2016 18:36:23 - granted resource  4086L6
03/29/2016 18:36:23 - granted resource  HP.ULTRIUM6-SCSI.003
03/29/2016 18:36:23 - granted resource  vnesombs1bkp-hcart3-robot-tld-0
03/29/2016 18:36:23 - estimated 17851814 kbytes needed
03/29/2016 18:36:23 - Info nbjm (pid=9186) started backup (backupid=vneswas1bkp_1459256783) job for client vneswas1bkp, policy vneswas1bkp_windows, schedule Weekly_Full on storage unit vnesombs1bkp-hcart3-robot-tld-0
03/29/2016 18:36:24 - started process bpbrm (pid=27138)
03/29/2016 18:36:25 - Info bpbrm (pid=27138) vneswas1bkp is the host to backup data from
03/29/2016 18:36:25 - Info bpbrm (pid=27138) reading file list for client
03/29/2016 18:36:26 - connecting
03/29/2016 18:36:27 - Info bpbrm (pid=27138) starting bpbkar on client
03/29/2016 18:36:27 - connected; connect time: 0:00:00
03/29/2016 18:36:28 - Info bpbkar (pid=5628) Backup started
03/29/2016 18:36:28 - Info bpbrm (pid=27138) bptm pid: 27182
03/29/2016 18:36:28 - Info bpbkar (pid=5628) change time comparison:<disabled>
03/29/2016 18:36:28 - Info bpbkar (pid=5628) archive bit processing:<enabled>
03/29/2016 18:36:28 - Info bpbkar (pid=5628) not using change journal data for <C:\>: not enabled
03/29/2016 18:36:28 - Info bptm (pid=27182) start
03/29/2016 18:36:28 - Info bptm (pid=27182) using 65536 data buffer size
03/29/2016 18:36:28 - Info bptm (pid=27182) setting receive network buffer to 262144 bytes
03/29/2016 18:36:28 - Info bptm (pid=27182) using 30 data buffers
03/29/2016 18:36:28 - Info bptm (pid=27182) start backup
03/29/2016 18:36:28 - Info bptm (pid=27182) backup child process is pid 27184
03/29/2016 18:36:28 - Info bptm (pid=27182) media id 4086L6 mounted on drive index 3, drivepath /dev/rmt/6cbn, drivename HP.ULTRIUM6-SCSI.003, copy 1
03/29/2016 18:36:28 - mounted 4086L6
03/29/2016 18:36:28 - positioning 4086L6 to file 253
03/29/2016 18:36:30 - positioned 4086L6; position time: 0:00:02
03/29/2016 18:36:30 - begin writing
03/29/2016 18:37:05 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files\Common Files\Symantec Shared\SEVINST64x86.EXE (WIN32 5: Access is denied. )
03/29/2016 18:37:54 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Common Files\Symantec Shared\EENGINE\EPERSIST.DAT (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\AutoExcl.exe (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\regid.1992-12.com.symantec_SEP.swidtag (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\regid.1992-12.com.symantec_SEP_Windows_Client_x64.swidtag (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\12.1.6318.6100.105\License.rtf (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\12.1.6318.6100.105\isolate.ini (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\12.1.6318.6100.105\Bin\AVHostPlugin.dll (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\12.1.6318.6100.105\Bin\AVMan.plg (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - error clearing archive bit: C:\Program Files (x86)\Symantec\Symantec Endpoint Protection\12.1.6318.6100.105\Bin\AVProxy.dll (WIN32 5: Access is denied. )
03/29/2016 18:41:06 - Info bpbrm (pid=27138) from client vneswas1bkp: TRV - last message being suppressed after 10 occurrences
03/29/2016 19:05:17 - Error bpbrm (pid=27138) socket read failed: errno = 62 - Timer expired
03/29/2016 19:05:19 - Error bptm (pid=27182) media manager terminated by parent process
03/29/2016 19:06:18 - Info bpbkar (pid=5628) done. status: 13: file read failed
03/29/2016 19:06:18 - end writing; write time: 0:29:48
file read failed  (13)

bpbrm logs
-----------------
13:00:07.710 [12915] <16> bpbrm readline: socket read failed: errno = 62 - Timer expired
13:00:07.711 [12915] <2> bpbrm kill_child_process: start
13:00:07.711 [12915] <2> bpbrm wait_for_child: start
13:00:49.105 [12915] <2> bpbrm wait_for_child: child exit_status = 82 signal_status = 0
13:00:49.105 [12915] <2> inform_client_of_status: INF - Server status = 13

[backup_master1]

Have a look here:

https://www.veritas.com/support/en_US/article.TECH141299

https://www.veritas.com/support/en_US/article.TECH71821

Ron

[MG11]

Hi Ron,

Thanks for the reply i already tried increasing client timeout value to 1400 still same issue.

Marty