Summary
Block backup failed on a large size volume on Windows client node,
Symptoms
Block backup of a Windows node always failed on a volume, with the error in the job log:
----------
155.250.158.55 sssvh Mon Dec 04 01:40:09 2017 SNBNCJ_103E Task 8 NDMP_LOG: id(2), type(NDMP_LOG_ERROR), text(process was aborted)
155.250.158.55 sssvh Mon Dec 04 01:40:09 2017 SNBNCJ_100N Task 8 NDMP_LOG: id(0), type(), text(NDMP_SVS_NOTIFY_CREATE_RELATIONSHIP operation failed. Error(542113793, NDMP_SV_RPL_GENERIC); Rseq(11))
155.250.158.55 sssvh Mon Dec 04 01:40:09 2017 SNBSVH_968E Create relationship(/vol/SYNC_VOL_RUMO1W0004_1/[1510224621]1444308672@{4C046E24}) failed with exception: NDMPSessionException(0, createRelationship exception: <EXCEPTION CATEGORY="BEXEXCEPTION"><CODE>501</CODE></EXCEPTION>)
Restarting DPX APM on the proxy node and resetting CJ do not help.
From the client nibbler.log, it shows the error:
------------------------------
Dec 04 02:10:14 2017:5312:WARNING:CUtil::get_fs_type():Failed to get info for filesystem [\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy19].
Dec 04 02:10:14 2017:5312:WARNING:SV backup file history will not be created for this backup. FH Generation disabled.
Dec 04 02:10:14 2017:5312:INFO:Loading allocated blocks from file [\\?\Volume{fd221719-dc57-11e4-aee0-d89d672c7ed3}\SS Jobs
\RUMO1W0004_NEW_1\1512334843\BitMap\IBMAP.1512076885.1512337099.4096.BEX]...
Dec 04 02:10:14 2017:5312:INFO:Allocated block file [\\?\Volume{fd221719-dc57-11e4-aee0-d89d672c7ed3}\SS Jobs\RUMO1W0004_NEW_1\1512334843\BitMap\IBMAP.1512076885.1512337099.4096.BEX] block size:[4096],count:[16686592] volume[\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy19] block count: [16686591],volume size[68348276736]
Dec 04 02:10:15 2017:5312:INFO:SVP created FH file copy for DMA file history connection:[C:/PROGRA~1/DPX/logs \D2675D61RUMO1W0004NEW11512334843.ctl]:
Dec 04 02:10:15 2017:5312:INFO:Processing []:[BEXIMAGE.TOC]...:
Dec 04 02:10:15 2017:5312:ERROR:Failed opening []:[GetLastError(3):The system cannot find the path specified. ].:
Dec 04 02:10:15 2017:5312:INFO:Processing [\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy19]:[BEXIMAGE.RAW]...:
From the output of 'vssadmin list providers', it shows a third party VSS provider is resented in addition to the Microsoft Shadow Copy provider, from Acronis,
----------
Acronis Backup and Recovery 11.5
Provider name: 'Acronis VSS SW Provider'
Provider type: Software
Provider Id: {f782463b-33bb-4043-ad8d-60b728d26a6c}
Version: 1.0
Resolution
1. uninstalled the Acronis VSS Provider
2. disable all services by Acronis
Afterwards, the backup completes fine without issues.