AltaVault Virtual AVA-v with CIFS not able to work with Veeam v9

[TonyWu]

Hi All,

Is there anyone working on AltaValt virtual with Veeam Backup and Replication v9.0?

It never works in my demo environment 

There is a discussion from Veeam Forum

https://forums.veeam.com/veeam-backup-replication-f2/case-id-01755522-backup-copy-can-not-generate-vbm-file-t34273.html

Please advise.  

Here is the log error generated by Veeam software:

I wondering is there any blocking  by AVA for generating the file

===================================================================
Starting new log
Log has been started by 'VEEAM02\SYSTEM' user (Non-interactive)
Logging level: [4 (AboveNormal)]
MachineName: [VEEAM02], OS: [Microsoft Windows Server 2012 R2 Standard (6.3.9600)], CPU: [2]
Process: [64 bit], PID: [3852], SessionId: [0]
UTC Time: [30/8/16 2:56:56 PM], DaylightSavingTime: [False]
Culture: [en-HK], UI culture: [en-US]
Module: [C:\Program Files\Veeam\Backup and Replication\Backup\Veeam.Backup.Manager.exe]. File version: [9.0.0.1715], Assembly version: [9.0.0.0]
CmdLineParams: [startbackupjob owner=[vbsvc] Normal 7f35e2ea-36b6-4a58-94fe-96dba024bda4 7866e46b-76a8-449a-ba9d-e68b6a12025e]
Network Interface, Name: Ethernet, Description: Microsoft Hyper-V Network Adapter, Interface Type: Ethernet, Operational Status: Up;
Unicast IPAddresses: fe80::a907:f8a:53fc:f738%12; 10.20.7.203;
Gateway IPAddresses: 10.20.7.254;
Network Interface, Name: Loopback Pseudo-Interface 1, Description: Software Loopback Interface 1, Interface Type: Loopback, Operational Status: Up;
Unicast IPAddresses: ::1; 127.0.0.1;
Network Interface, Name: isatap.{C2DF1013-285B-4248-902D-4F32479E564D}, Description: Microsoft ISATAP Adapter, Interface Type: Tunnel, Operational Status: Down;
Unicast IPAddresses: fe80::5efe:10.20.7.203%13;
UTC offset: 8.00 hours

[30.08.2016 22:56:56] <01> Info -------------------------------------------------------------------------------------
[30.08.2016 22:56:56] <01> Info STARTBACKUPJOB
[30.08.2016 22:56:56] <01> Info owner=[vbsvc]
[30.08.2016 22:56:56] <01> Info Normal
[30.08.2016 22:56:56] <01> Info 7f35e2ea-36b6-4a58-94fe-96dba024bda4
[30.08.2016 22:56:56] <01> Info 7866e46b-76a8-449a-ba9d-e68b6a12025e
[30.08.2016 22:56:56] <01> Info -------------------------------------------------------------------------------------
[30.08.2016 22:56:56] <01> Info Starting job mode: 'Normal'
[30.08.2016 22:56:56] <01> Info [Session] Id '7866e46b-76a8-449a-ba9d-e68b6a12025e', State 'Working'
[30.08.2016 22:56:56] <01> Info Custom command execution (pre script) disabled
[30.08.2016 22:56:58] <01> Info Client session [d3a0f814-d345-4a79-964e-def60d001163] was opened with info
[30.08.2016 22:56:58] <01> Info -------------------------------------------------------------------------------------
[30.08.2016 22:56:58] <01> Info Job ID: [7f35e2ea-36b6-4a58-94fe-96dba024bda4]
[30.08.2016 22:56:58] <01> Info Job Name: [Backup to AVA02]
[30.08.2016 22:56:58] <01> Info Job Mode: [Normal]
[30.08.2016 22:56:58] <01> Info Job Type: [HyperV Backup]
[30.08.2016 22:56:58] <01> Info Job Options: [<JobOptionsRoot><RunManually>True</RunManually><GroupSnapshotProcessing>True</GroupSnapshotProcessing><SourceProxyAutoDetect>True</SourceProxyAutoDetect><TargetProxyAutoDetect>True</TargetProxyAutoDetect><OffHostBackup>True</OffHostBackup><FailoverToOnHostBackup>True</FailoverToOnHostBackup><CheckRetention>True</CheckRetention><RetainCycles>14</RetainCycles><UseChangeTracking>True</UseChangeTracking><EmailNotification>False</EmailNotification><EmailNotificationAddresses /><UseCustomEmailNotificationOptions>False</UseCustomEmailNotificationOptions><EmailNotificationSubject>[%JobResult%] %JobName% (%VmCount% VMs) %Issues%</EmailNotificationSubject><EmailNotifyOnSuccess>True</EmailNotifyOnSuccess><EmailNotifyOnWarning>True</EmailNotifyOnWarning><EmailNotifyOnError>True</EmailNotifyOnError><EmailNotifyOnLastRetryOnly>True</EmailNotifyOnLastRetryOnly><SnmpNotification>False</SnmpNotification><PostJobCommand><Periodicity>Cycles</Periodicity><PreScriptEnabled>False</PreScriptEnabled><PreScriptCommandLine /><Frequency>1</Frequency><Enabled>False</Enabled><CommandLine /><Days><Day>Saturday</Day></Days></PostJobCommand><StgBlockSize>KbBlockSize1024</StgBlockSize><EnableDeduplication>True</EnableDeduplication><CompressionLevel>0</CompressionLevel><EnableHvQuiescence>False</EnableHvQuiescence><CanDoCrashConsistent>False</CanDoCrashConsistent><EnableFullBackup>False</EnableFullBackup><FullBackupScheduleKind>Daily</FullBackupScheduleKind><FullBackupDays><DayOfWeek>Saturday</DayOfWeek></FullBackupDays><FullBackupMonthlyScheduleOptions><DayNumberInMonth>First</DayNumberInMonth><DayOfWeek>Monday</DayOfWeek><Months><EMonth>January</EMonth><EMonth>February</EMonth><EMonth>March</EMonth><EMonth>April</EMonth><EMonth>May</EMonth><EMonth>June</EMonth><EMonth>July</EMonth><EMonth>August</EMonth><EMonth>September</EMonth><EMonth>October</EMonth><EMonth>November</EMonth><EMonth>December</EMonth></Months></FullBackupMonthlyScheduleOptions><Algorithm>Increment</Algorithm><TransformFullToSyntethic>True</TransformFullToSyntethic><TransformToSyntethicDays><DayOfWeek>Saturday</DayOfWeek></TransformToSyntethicDays><TransformIncrementsToSyntethic>False</TransformIncrementsToSyntethic><ExcludeSwapFile>True</ExcludeSwapFile><DirtyBlocksNullingEnabled>True</DirtyBlocksNullingEnabled><StorageEncryptionEnabled>False</StorageEncryptionEnabled><GenerationPolicy><EnableRecheck>False</EnableRecheck><RecheckScheduleKind>Monthly</RecheckScheduleKind><RecheckDays><DayOfWeek>Friday</DayOfWeek></RecheckDays><RecheckMonthlyScheduleOptions><DayNumberInMonth>Last</DayNumberInMonth><DayOfWeek>Friday</DayOfWeek><Months><EMonth>January</EMonth><EMonth>February</EMonth><EMonth>March</EMonth><EMonth>April</EMonth><EMonth>May</EMonth><EMonth>June</EMonth><EMonth>July</EMonth><EMonth>August</EMonth><EMonth>September</EMonth><EMonth>October</EMonth><EMonth>November</EMonth><EMonth>December</EMonth></Months></RecheckMonthlyScheduleOptions><EnableCompactFull>False</EnableCompactFull><CompactFullBackupScheduleKind>Monthly</CompactFullBackupScheduleKind><CompactFullBackupDays><DayOfWeek>Saturday</DayOfWeek></CompactFullBackupDays><CompactMonthlyScheduleOptions><DayNumberInMonth>Last</DayNumberInMonth><DayOfWeek>Saturday</DayOfWeek><Months><EMonth>January</EMonth><EMonth>February</EMonth><EMonth>March</EMonth><EMonth>April</EMonth><EMonth>May</EMonth><EMonth>June</EMonth><EMonth>July</EMonth><EMonth>August</EMonth><EMonth>September</EMonth><EMonth>October</EMonth><EMonth>November</EMonth><EMonth>December</EMonth></Months></CompactMonthlyScheduleOptions></GenerationPolicy><EnableDeletedVmDataRetention>False</EnableDeletedVmDataRetention><RetainDays>14</RetainDays></JobOptionsRoot>]
[30.08.2016 22:56:58] <01> Info Job VSS Settings: [VssSnapshotOptions: [Enabled: [False], IgnoreErrors: [False], IsCopyOnly: [False]], WinGuestFSIndexingOptions: [Type: [None]], LinGuestFSIndexingOptions: [Type: [None]], SqlBackupOptions: [TransactionLogsProcessing: [TruncateOnlyOnSuccessJob], BackupLogsFrequencyMin: [15], UseDbBackupRetention: [True], RetainDays: [15], ProxyAutoSelect: [True]], ExchangeBackupOptions: [TransactionLogsProcessing: [TruncateOnlyOnSuccessJob]], WinCredsId: [00000000-0000-0000-0000-000000000000], LinCredsId: [93aacdc7-8025-4f94-a0fc-e374f8526de5], Old Properties: [Enabled: [False], IgnoreErrors: [False], GuestFSIndexingType: [None], IncludedIndexingFolders: [], ExcludedIndexingFolders: [], LinGuestFSIndexingType: [None], LinIncludedIndexingFolders: [], LinExcludedIndexingFolders: [], IsFirstUsage: [True]]]
[30.08.2016 22:56:58] <01> Info Job ScheduleOptions: [Start time: [31/8/16 10:00:00 PM], Latest run time: [1/1/01 12:00:00 AM], Next run time: [], Retry times on failure: [3], Retry timeout: [10 min], Daily options: [Enabled: True, DayNumberInMonth: Everyday, Days: Sunday, Monday, Tuesday, Wednesday, Thursday, Friday, Saturday]Monthly options: [Enabled: False, Time: 30/8/16 10:00:00 PM, Day Number In Month: Fourth, Day Of Week: Saturday, Months: January, February, March, April, May, June, July, August, September, October, November, December]Periodically options: [Enabled: False, Period: 1 hour(s), ScheduleString: <scheduler><Sunday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Sunday><Monday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Monday><Tuesday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Tuesday><Wednesday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Wednesday><Thursday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Thursday><Friday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Friday><Saturday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Saturday></scheduler>, HourlyOffset: 0]Continuous options: [Enabled: False, ScheduleString: <scheduler><Sunday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Sunday><Monday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Monday><Tuesday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Tuesday><Wednesday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Wednesday><Thursday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Thursday><Friday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Friday><Saturday>0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0</Saturday></scheduler>]]
[30.08.2016 22:56:58] <01> Info Job AutoScheduleOptions: [<AutoScheduleOptions PerformActiveFullBackup="False" ActiveFullBackupKind="0" ActiveFullBackupDays="Saturday;" PerformTransformToSyntethic="True" TransformToSyntethicDays="Saturday;"><ActiveFullBackupMonthlyOption><FullBackupMonthlyScheduleOptions><DayNumberInMonth>First</DayNumberInMonth><DayOfWeek>Monday</DayOfWeek><Months><EMonth>January</EMonth><EMonth>February</EMonth><EMonth>March</EMonth><EMonth>April</EMonth><EMonth>May</EMonth><EMonth>June</EMonth><EMonth>July</EMonth><EMonth>August</EMonth><EMonth>September</EMonth><EMonth>October</EMonth><EMonth>November</EMonth><EMonth>December</EMonth></Months></FullBackupMonthlyScheduleOptions></ActiveFullBackupMonthlyOption></AutoScheduleOptions>]
[30.08.2016 22:56:58] <01> Info Enable parallel processing checkbox is on
[30.08.2016 22:56:58] <01> Info Session parameters: NzAwNjdkID8gNGUyYzAgMTUvMDQvMjAxNyAxOWE4Nw==
[30.08.2016 22:56:58] <01> Info Proxy mode: autodetect.
[30.08.2016 22:56:58] <01> Info The following off-host proxies can be used
[30.08.2016 22:56:58] <01> Info proxy '10.20.7.5', detection mode 'auto', physical host id 'cd67f75d-09e4-4d9f-a610-a5193d3fb876'
[30.08.2016 22:56:58] <01> Info volume 'C:\', type 'Microsoft Software Shadow Copy provider 1.0', provider 'Automatic', physVolumeId 'e8353cc3-dad7-4057-b5da-31760f3d3d37',scsi id 'Bus 2 Lun 0 Target 0 Port 1'
[30.08.2016 22:56:58] <01> Info volume 'D:\', type 'Microsoft Software Shadow Copy provider 1.0', provider 'Automatic', physVolumeId '9782bd1f-d91d-4d9f-b47c-25ea4ffbc655',scsi id 'Bus 1 Lun 0 Target 0 Port 0'
[30.08.2016 22:56:58] <01> Info Object in job: [Id: [21aef9ef-2844-4541-8132-9462857ced2e], ObjectId: [9dd39cba-7fbe-4f4f-93d4-61c7396073a7], JobId: [7f35e2ea-36b6-4a58-94fe-96dba024bda4]FolderId: [00000000-0000-0000-0000-000000000000]
[30.08.2016 22:56:58] <01> Info Location: [10.20.7.5\UbuntuOS], Type: [Include], ApproxSize: [3795845120]
[30.08.2016 22:56:58] <01> Info VssOptions: [VssSnapshotOptions: [Enabled: [True], IgnoreErrors: [False], IsCopyOnly: [False]], WinGuestFSIndexingOptions: [Type: [ExceptSpecifiedFolders], IncludedFolders: [], ExcludedFolders: [%windir%; %ProgramFiles%; %ProgramFiles(x86)%; %ProgramW6432%; %TEMP%]], LinGuestFSIndexingOptions: [Type: [ExceptSpecifiedFolders], IncludedFolders: [], ExcludedFolders: [/cdrom; /dev; /media; /mnt; /proc; /tmp; /lost+found]], SqlBackupOptions: [TransactionLogsProcessing: [TruncateOnlyOnSuccessJob], BackupLogsFrequencyMin: [15], UseDbBackupRetention: [True], RetainDays: [15], ProxyAutoSelect: [True]], ExchangeBackupOptions: [TransactionLogsProcessing: [TruncateOnlyOnSuccessJob]], WinCredsId: [00000000-0000-0000-0000-000000000000], LinCredsId: [00000000-0000-0000-0000-000000000000], Old Properties: [Enabled: [True], IgnoreErrors: [False], GuestFSIndexingType: [ExceptSpecifiedFolders], IncludedIndexingFolders: [], ExcludedIndexingFolders: [%windir%; %ProgramFiles%; %ProgramFiles(x86)%; %ProgramW6432%; %TEMP%], LinGuestFSIndexingType: [ExceptSpecifiedFolders], LinIncludedIndexingFolders: [], LinExcludedIndexingFolders: [/cdrom; /dev; /media; /mnt; /proc; /tmp; /lost+found], IsFirstUsage: [True]]],
[30.08.2016 22:56:58] <01> Info Platform: [EHyperV], UpdateVmx: [True]
[30.08.2016 22:56:58] <01> Info DiskFilter: [Ver8|3000;3001;3002;3003;2000;2001;2002;2003;2004;2005;2006;2007;2008;2009;2010;2011;2012;2013;2014;2015;2016;2017;2018;2019;2020;2021;2022;2023;2024;2025;2026;2027;2028;2029;2030;2031;2032;2033;2034;2035;2036;2037;2038;2039;2040;2041;2042;2043;2044;2045;2046;2047;2048;2049;2050;2051;2052;2053;2054;2055;2056;2057;2058;2059;2060;2061;2062;2063;2016;2017;2018;2019;2020;2021;2022;2023;2024;2025;2026;2027;2028;2029;2030;2031;2032;2033;2034;2035;2036;2037;2038;2039;2040;2041;2042;2043;2044;2045;2046;2047;2048;2049;2050;2051;2052;2053;2054;2055;2056;2057;2058;2059;2060;2061;2062;2063;2064;2065;2066;2067;2068;2069;2070;2071;2072;2073;2074;2075;2076;2077;2078;2079;2032;2033;2034;2035;2036;2037;2038;2039;2040;2041;2042;2043;2044;2045;2046;2047;2048;2049;2050;2051;2052;2053;2054;2055;2056;2057;2058;2059;2060;2061;2062;2063;2064;2065;2066;2067;2068;2069;2070;2071;2072;2073;2074;2075;2076;2077;2078;2079;2080;2081;2082;2083;2084;2085;2086;2087;2088;2089;2090;2091;2092;2093;2094;2095;2048;2049;2050;2051;2052;2053;2054;2055;2056;2057;2058;2059;2060;2061;2062;2063;2064;2065;2066;2067;2068;2069;2070;2071;2072;2073;2074;2075;2076;2077;2078;2079;2080;2081;2082;2083;2084;2085;2086;2087;2088;2089;2090;2091;2092;2093;2094;2095;2096;2097;2098;2099;2100;2101;2102;2103;2104;2105;2106;2107;2108;2109;2110;2111], ]
[30.08.2016 22:56:58] <01> Info Last retry point was not found.
[30.08.2016 22:56:58] <01> Info Building VM tasks.
[30.08.2016 22:56:58] <01> Info Expanding include list ('1' objects)
[30.08.2016 22:56:58] <10> Info [wmi(10.20.7.5)] Searching for VM with ID '3a01a357-591a-4db9-b638-daca405a5f7e'.
[30.08.2016 22:56:58] <10> Info [wmi(10.20.7.5)] Invoking WMI query with enum timeout '3600 sec': 'SELECT * FROM Msvm_ComputerSystem WHERE Name = "3A01A357-591A-4DB9-B638-DACA405A5F7E"'
[30.08.2016 22:56:59] <08> Info [BackupMetaUpdater] Working proc started
[30.08.2016 22:56:59] <01> Info VM: 'UbuntuOS', ref: '3a01a357-591a-4db9-b638-daca405a5f7e', host: '10.20.7.5'.
[30.08.2016 22:56:59] <01> Info Expanding exclude list ('0' objects)
[30.08.2016 22:56:59] <01> Info Expanding policies ('0' objects)
[30.08.2016 22:56:59] <01> Info Removing excluded VMs
[30.08.2016 22:56:59] <01> Info Creating plain list of tasks:
[30.08.2016 22:56:59] <12> Info [wmi(10.20.7.5)] Listing virtual machines on host.
[30.08.2016 22:56:59] <12> Info [wmi(10.20.7.5)] Invoking WMI query with enum timeout '3600 sec': 'SELECT ElementName, Name, EnabledState FROM Msvm_ComputerSystem'
[30.08.2016 22:57:08] <01> Info [SharedSnapshotHolder] Removing context 'b320604d-d631-46ba-b4e4-e3f6d0930eb3' because it contains one vm
[30.08.2016 22:57:08] <01> Info [SharedSnapshotHolder] Printing shared snapshot contexts
[30.08.2016 22:57:08] <01> Info Created task order:
[30.08.2016 22:57:08] <01> Info '2267d78d-3387-4e9e-b476-476fee9fc582' - 'UbuntuOS'. Initial index = '0', shared snapshot context = '00000000-0000-0000-0000-000000000000'
[30.08.2016 22:57:08] <01> Info Licensing hosts from all backup tasks
[30.08.2016 22:57:08] <01> Info The following hosts should be licensed:
[30.08.2016 22:57:08] <01> Info '10.20.7.5' '2' sockets
[30.08.2016 22:57:08] <01> Info Checking the license for the source host '10.20.7.5'
[30.08.2016 22:57:08] <01> Info Number of sockets: '2', BIOS uuid: '944D737A-0D35-E211-A46C-001E674B6AA8'.
[30.08.2016 22:57:08] <01> Info Valid vm tasks ('1'):
[30.08.2016 22:57:08] <01> Info =================================================================================================================
[30.08.2016 22:57:08] <01> Info VM | Clust. | Host | Size | Provisioned | Snapshot Mode| Off-host proxies
[30.08.2016 22:57:08] <01> Info =================================================================================================================
[30.08.2016 22:57:08] <01> Info UbuntuOS | | 10.20.7.5 | 36.0 GB | 3.5 GB | enCrash|
[30.08.2016 22:57:08] <01> Info [CProxyRpcInvoker] RpcInvoker HashCode:9119245 was disposed
[30.08.2016 22:57:08] <01> Info [CProxyRpcInvoker] RpcInvoker HashCode:14964341 was disposed
[30.08.2016 22:57:08] <01> Info Job size: 36.0 GB (3.5 GB provisioned).
[30.08.2016 22:57:08] <01> Info [JobSession] totalObjects '1', totalSize '36.0 GB'
[30.08.2016 22:57:08] <01> Info Skipping host 'Veeam04.im.demo.intra', Type 'Windows' because another host with the same PhysHostId already in list
[30.08.2016 22:57:08] <01> Info Skipping host '10.20.7.204', Type 'Windows' because another host with the same PhysHostId already in list
[30.08.2016 22:57:08] <01> Info Skipping host '10.20.7.5', Type 'HvServer' because another host with the same PhysHostId already in list
[30.08.2016 22:57:08] <01> Info [BackupJobTarget] Initializing backup target, mode: 'Normal', agent sharing mode: 'SharedAgent', repository: 'ava03'.
[30.08.2016 22:57:08] <01> Info [ProxyAgentTracker] Stopping agents on Job: '7f35e2ea-36b6-4a58-94fe-96dba024bda4'
[30.08.2016 22:57:09] <01> Info [Backup] SetMetaUpdateTimeAndVersion, old value: [1/1/00 12:00:00 AM,-1] new value: [1/1/00 12:00:00 AM,-1]
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] Identity for impersonation is not specified.
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] Creating File Commander.
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Setting share credentials: Share \\ava03\cifs, User .\ava03
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\] contains 5 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\WindowsImageBackup', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\WindowsImageBackup\] contains 1 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\WindowsImageBackup\HyperV02', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\WindowsImageBackup\HyperV02\] contains 4 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\MSSQL', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\MSSQL\] contains 0 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\] contains 25 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K12R2-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K12R2-x64\] contains 15 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K8R2-ia64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K8R2-ia64\] contains 25 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K12-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K12-x64\] contains 18 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K8-ia64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K8-ia64\] contains 25 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\VISTA-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\VISTA-x64\] contains 14 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\Installer', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\Installer\] contains 3 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W7-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W7-x64\] contains 14 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K8-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K8-x64\] contains 28 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K8-x86', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K8-x86\] contains 28 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K3-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K3-x64\] contains 33 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\VISTA-x86', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\VISTA-x86\] contains 14 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W7-x86', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W7-x86\] contains 14 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K3-x86', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K3-x86\] contains 34 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W8_1-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W8_1-x64\] contains 12 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W8-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W8-x64\] contains 12 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K8R2-x64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K8R2-x64\] contains 28 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\XP-x86', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\XP-x86\] contains 15 items
[30.08.2016 22:57:09] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\New folder\W2K3-ia64', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:10] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\New folder\W2K3-ia64\] contains 34 items
[30.08.2016 22:57:10] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\VeeamBackup&Replication_9.0.0.1715_Update2', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:10] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\VeeamBackup&Replication_9.0.0.1715_Update2\] contains 1 items
[30.08.2016 22:57:10] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Enumerating items in directory '\\ava03\cifs\Backup Job 2_1', searchMask '*.*', recursive 'False'
[30.08.2016 22:57:10] <01> Info [Veeam02.IM.DEMO.INTRA] [FC] Directory [\\ava03\cifs\Backup Job 2_1\] contains 3 items
[30.08.2016 22:57:10] <01> Info [CStorageLinksHelper] Asserting storage links...
[30.08.2016 22:57:10] <01> Info [CStorageLinksHelper] Storage links checked successfully
[30.08.2016 22:57:10] <01> Info [BackupJobTarget] Need to create basic point. Last point not found in backup
[30.08.2016 22:57:10] <01> Info [BackupJobTarget] Forcing full mode (need to create basic point or encryption enabled changed)
[30.08.2016 22:57:10] <01> Info [JobSession] Setting full mode (activeFull: False)
[30.08.2016 22:57:10] <01> Info Initializing shared repository client for repository 'ava03'.
[30.08.2016 22:57:10] <01> Info [LocalBackupTargetRepairAlg] Repair started
[30.08.2016 22:57:10] <01> Info [BackupJobTarget] Job session is running in full mode
[30.08.2016 22:57:10] <01> Info [BackupMeta] Generate backup meta for 'Backup Job 2:1c4243c2-e578-44c4-a681-08ed14fa70b1'
[30.08.2016 22:57:10] <01> Info [BackupMetaUpdater] Waiting for backup meta to be generated in another thread <8>
[30.08.2016 22:57:10] <08> Info [BackupMetaUpdater] Generating backup meta because it was requested by the following threads:
[30.08.2016 22:57:10] <08> Info [BackupMetaUpdater] Thread <1>
[30.08.2016 22:57:11] <08> Info [BackupMeta] Serializing meta for backup Backup Job 2
[30.08.2016 22:57:11] <08> Info [BackupMeta] Serialization finished
[30.08.2016 22:57:11] <08> Info [BackupMeta] Saving backup meta to '\\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm'
[30.08.2016 22:57:11] <08> Info [Veeam02.IM.DEMO.INTRA] [FC] Saving file content [<?xml version="1.0" encoding="UTF-8"?><BackupMeta Version="1" OriginalBackupId="1c4243c2-e578-44c4-a681-08ed14fa70b1"><VmObjects><VmId /></VmObjects><KeySets /><Vbm KeySetId="">&lt;?xml version="1.0" encoding="UTF-8"?&gt;&lt;Vbm Id="5b26dd0c-392a-4dda-a65c-4ea634b5650e" JobId="7f35e2ea-36b6-4a58-94fe-96dba024bda4" JobName="Backup Job 2" JobType="Backup" JobSourceType="HyperV" Platform="EHyperV" CreationTimeUtc="08/30/2016 14:57:10" FormatVersion="1" BackupCreationTime="08/30/2016 17:56:00"&gt;&l...] to [\\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm_1_tmp].
[30.08.2016 22:58:16] <08> Info [Veeam02.IM.DEMO.INTRA] [FC] Renaming remote file from [\\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm_1_tmp] to [\\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm]
[30.08.2016 23:00:57] <08> Error Exception at meta save process
[30.08.2016 23:00:57] <08> Error [Veeam02.IM.DEMO.INTRA] Failed to get item info for \\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm (System.Exception)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.ProxyProvider.CProxyFileCommander.GetFsItemInfo(String itemPath)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.Core.CRemoteWinFileCommander.GetItem(String link)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.Core.CBackupMetaEx.<>c__DisplayClass9.<GenerateAndSaveLocally>b__8(Tuple`2 tuple)
[30.08.2016 23:00:57] <08> Error File not found. File name: \\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm (Veeam.Backup.Common.CAppException)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.ProxyProvider.CProxyFileCommander.GetFsItemInfo(String itemPath)
[30.08.2016 23:00:57] <08> Error [BackupMetaUpdater] Failed to generate backup meta
[30.08.2016 23:00:57] <08> Error Unable to save meta. See log for details. (System.Exception)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.Core.CBackupMetaEx.GenerateAndSaveLocally(IStorageCommander storageCommander, IMetaCommander metaCommander, IEnumerable`1 fcAndPathes, CBackupInfo backupInfo)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.Core.CLocalStorageCommander.GenerateAndSaveMetaFile(CBackupInfo backupInfo)
[30.08.2016 23:00:57] <08> Error at Veeam.Backup.Core.CThreadedBackupMetaUpdater.GenerateMetaSafe(CBackupMetaUpdateRequest[] requests)
[30.08.2016 23:00:58] <01> Info [Session] Id '7866e46b-76a8-449a-ba9d-e68b6a12025e', State 'Postprocessing'
[30.08.2016 23:00:58] <01> Info Deleting empty storages from backup '1c4243c2-e578-44c4-a681-08ed14fa70b1'
[30.08.2016 23:00:58] <01> Info [BackupMeta] Generate backup meta for 'Backup Job 2:1c4243c2-e578-44c4-a681-08ed14fa70b1'
[30.08.2016 23:00:58] <01> Info [BackupMetaUpdater] Waiting for backup meta to be generated in another thread <8>
[30.08.2016 23:00:58] <08> Info [BackupMetaUpdater] Generating backup meta because it was requested by the following threads:
[30.08.2016 23:00:58] <08> Info [BackupMetaUpdater] Thread <1>
[30.08.2016 23:00:58] <08> Info [BackupMeta] Serializing meta for backup Backup Job 2
[30.08.2016 23:00:58] <08> Info [BackupMeta] Serialization finished
[30.08.2016 23:00:58] <08> Info [BackupMeta] Saving backup meta to '\\ava03\cifs\Backup Job 2_1\Backup Job 2.vbm'

[chriswong · Accepted solution]

Hi Tony,

In order to support Veeam 9 you need to be at minimum versions of AltaVault, as described in the interoperability matrix (IMT). The supported versions of AltaVault are 4.1.1P1, and 4.2 and higher. I understand that you're using 4.1.1 because of the AltaVault Hyper-V download issue discussed per our email.

To access 4.1.1P1, you'll need to select the “Access a Specific Release” section on the bottom of the Software Download page (http://mysupport.netapp.com/NOW/cgi-bin/software/). Select “AltaVault ...” & input the version 4.1.1P1, and hit “Go”. 

Regards,

Christopher