I have an rare issue that I can´t understand, I have a virtual server with Ubuntu 20.04 on a Hyper-V infrastucture. We installed recently veeam agent for linux 5.0 on this server and we installed the integration tools for the VSS Snapshot, but when I created the backup and executed it, the backup job failed and show me the error with the failed to create volume snapshot and failed to execute IOCTL_TRACKING_ADD
This backup job his destination is to a repository on VSCP.
My caseID is #04802392 — I can´t generate backup
I share the logs so that someone can help us in this case, please
Code: Select all
[10.05.2021 21:57:25.585] <139835194615552> lpbcore| Creating job objects filter.
[10.05.2021 21:57:25.585] <139835194615552> lpbcore| Record type: [Include], object type: [AllSystem], value: []
[10.05.2021 21:57:25.585] <139835194615552> lpbcore| Creating job objects filter. ok.
[10.05.2021 21:57:25.585] <139835194615552> lpbcore| Detecting whether we are running under recovery ISO.
[10.05.2021 21:57:25.585] <139835194615552> lpbcore| Distribution ID: ubuntu
[10.05.2021 21:57:25.585] <139835194615552> lpbcore| Recovery ISO: [0].
[10.05.2021 21:57:25.585] <139835194615552> lpbdeve| Enumerating all block devices...
[10.05.2021 21:57:25.585] <139835194615552> lpbdeve| Ignored devices mask: [].
[10.05.2021 21:57:25.585] <139835194615552> lpbdeve| Skip filtering: [false].
[10.05.2021 21:57:25.585] <139835194615552> lpbdeve| Collect empty disks: [false].
[10.05.2021 21:57:25.585] <139835194615552> lpbdeve| Verbose logging: [false].
[10.05.2021 21:57:25.604] <139835194615552> lpbdeve| Skipp device(/dev/fd0) with type: floppy
[10.05.2021 21:57:25.726] <139835194615552> lpbdeve| ERR |Failed to create DeviceInfoEx for device [/dev/sr0].
[10.05.2021 21:57:25.726] <139835194615552> lpbdeve| >> |No medium found
[10.05.2021 21:57:25.726] <139835194615552> lpbdeve| >> |Failed to probe device [/dev/sr0].
[10.05.2021 21:57:25.726] <139835194615552> lpbdeve| >> |--tr:CRealBlkidAccessor: Failed to probe device [/dev/sr0].
[10.05.2021 21:57:25.726] <139835194615552> lpbdeve| >> |--tr:in virtual lpbdevenumlib::blkid::IBlkidProbe::Ptr lpbdevenumlib::accessor::CRealBlkidAccessor::ProbeDevice(const astr&) const at /mnt/Sources/LpbDevEnumLib/lpbdevenumlib/accessor/impl/RealBlkidAccessor.cpp:38
[10.05.2021 21:57:25.726] <139835194615552> lpbdeve| >> |An exception was thrown from thread [139835194615552].
[10.05.2021 21:57:25.732] <139835194615552> dsk | I/O statistics for '/dev/sda': 1024 bytes read unaligned (2 requests)
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop0] (7:0) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop1] (7:1) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop2] (7:2) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop3] (7:3) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop4] (7:4) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop5] (7:5) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop6] (7:6) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop7] (7:7) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| WARN|Device [/dev/loop8] (7:8) with type [loop] is not supported for backup and WILL SKIPPED.
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Found device: [/dev/sda]. Device number: [8:0]; Type: [scsi].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Size: [214748364800] bytes (200.0G).
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/sda].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/block/8:0].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-path/acpi-VMBUS:01-vmbus-00000000000088990000000000000000-lun-0].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/wwn-0x60022480c4ca53be2179925dea46d44a].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/scsi-360022480c4ca53be2179925dea46d44a].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Partition table type: [dos].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Partitions count: 3
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Found device: [/dev/sda1]. Device number: [8:1]; Type: [scsi].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Index: [1].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Offset: [1048576].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Size: [536870912] bytes (512.0M).
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/sda1].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/block/8:1].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-uuid/6334-A04F].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-partuuid/dc6273fc-01].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-path/acpi-VMBUS:01-vmbus-00000000000088990000000000000000-lun-0-part1].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/scsi-360022480c4ca53be2179925dea46d44a-part1].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/wwn-0x60022480c4ca53be2179925dea46d44a-part1].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Filesystem UUID: [6334-A04F]; Type: [vfat]; Mount points: [/boot/efi].
[10.05.2021 21:57:25.734] <139835194615552> lpbdeve| Found device: [/dev/sda2]. Device number: [8:2]; Type: [scsi].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Index: [2].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Offset: [538967040].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Size: [214208349184] bytes (199.4G).
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/sda2].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/block/8:2].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-partuuid/dc6273fc-02].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-path/acpi-VMBUS:01-vmbus-00000000000088990000000000000000-lun-0-part2].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/scsi-360022480c4ca53be2179925dea46d44a-part2].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/wwn-0x60022480c4ca53be2179925dea46d44a-part2].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Found device: [/dev/sda5]. Device number: [8:5]; Type: [scsi].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Index: [5].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Offset: [538968064].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Size: [214208348160] bytes (199.4G).
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/sda5].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/block/8:5].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-uuid/8d0f7b12-e991-4f48-a6c2-2d39e4594057].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-partuuid/dc6273fc-05].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-path/acpi-VMBUS:01-vmbus-00000000000088990000000000000000-lun-0-part5].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/scsi-360022480c4ca53be2179925dea46d44a-part5].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Link: [/dev/disk/by-id/wwn-0x60022480c4ca53be2179925dea46d44a-part5].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Filesystem UUID: [8d0f7b12-e991-4f48-a6c2-2d39e4594057]; Type: [ext4]; Mount points: [/].
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| [4] block devices were detected.
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Enumerating LVM devices...
[10.05.2021 21:57:25.735] <139835194615552> lpbdeve| Should skip inactive LVs? [true].
[10.05.2021 21:57:25.797] <139835194615552> lpbdeve| [0] LVM volume groups were detected.
[10.05.2021 21:57:25.798] <139835194615552> lpbdeve| Enumerating BTRFS subvolumes...
[10.05.2021 21:57:25.798] <139835194615552> lpbdeve| [0] BTRFS subvolumes were detected.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Building backup objects.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Find root enumerated objects for backup.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Find REO for backup of object [sda] (wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found REO for backup: [sda] (wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Find REO for backup of object [sda1] (wwn-0x60022480c4ca53be2179925dea46d44a-part1).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found REO for backup: [sda] (wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Find REO for backup of object [sda2] (wwn-0x60022480c4ca53be2179925dea46d44a-part2).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found REO for backup: [sda] (wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Find REO for backup of object [sda5] (wwn-0x60022480c4ca53be2179925dea46d44a-part5).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found REO for backup: [sda] (wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Create disk backup object.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Enumerated object [sda] (wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk) have a partition table [wwn-0x60022480c4ca53be2179925dea46d44a-LogicalDisk].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Build MBR disk object for [sda].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found filter for object [sda](dc6273fc). Record type: [Include], value: []
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| No matching filters for object [dc6273fc]
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Number of primary partitions: [2].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Process primary partition [/dev/sda1] (dc6273fc/o1048576l536870912).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Partition index: [1].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found filter for object [sda1](dc6273fc/o1048576l536870912). Record type: [Include], value: []
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| No matching filters for object [dc6273fc/o1048576l536870912]
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| No matching filters for object [\boot\efi]
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Partition [/dev/sda1] should be backed up.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Process primary partition [/dev/sda2] (dc6273fc/o538967040l214208349184).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Partition index: [2].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Is extended MBR partition.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found filter for object [sda2](dc6273fc/o538967040l214208349184). Record type: [Include], value: []
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| No matching filters for object [dc6273fc/o538967040l214208349184]
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Number of logical partitions: [1].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Process logical partition [/dev/sda5] (dc6273fc/o538968064l214208348160).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Partition index: [5].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Process logical partition [/dev/sda5] (dc6273fc/o538968064l214208348160).
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Found filter for object [sda5](dc6273fc/o538968064l214208348160). Record type: [Include], value: []
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| No matching filters for object [dc6273fc/o538968064l214208348160]
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| No matching filters for object [\]
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Partition [/dev/sda5] should be backed up.
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Detecting bootloader on device [/dev/sda].
[10.05.2021 21:57:25.798] <139835194615552> lpbcore| Detected GRUB 2 (v1.97-1.99).
[10.05.2021 21:57:25.825] <139835194615552> lpbcore| Device has MBR partition table.
[10.05.2021 21:57:25.827] <139835194615552> lpbcore| MBR gap area size: [1048064].
[10.05.2021 21:57:25.836] <139835194615552> lpbcore| Detected bootloader type: [Grub2].
[10.05.2021 21:57:25.836] <139835194615552> dsk | I/O statistics for '/dev/sda': 1052600 bytes read unaligned (10 requests)
[10.05.2021 21:57:25.836] <139835194615552> lpbcore| BIOS bootloader on [/dev/sda] will be backed up.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Disk [/dev/sda] will be backed up.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Computing backup used size.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Querying used space on sda.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Partition sda1, size: 536870912, used: 4096.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Partition sda5, size: 214208348160, used: 17122697216.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Used space: 17122701312.
[10.05.2021 21:57:25.837] <139835194615552> lpbcore| Overall data size used: 17122701312.
[10.05.2021 21:57:26.010] <139835194615552> | Default CA certificates will be loaded from: /etc/ssl/certs
[10.05.2021 21:57:26.010] <139835194615552> | CA certificates was loaded from OS specific paths
[10.05.2021 21:57:26.011] <139835194615552> | Default CA certificates will be loaded from: /etc/ssl/certs
[10.05.2021 21:57:26.236] <139835194615552> vmb | CCloudCertificateVerifier: verify certificate: /OU=Domain Control Validated/CN=draas.nephoscloud.mx
[10.05.2021 21:57:26.236] <139835194615552> vmb | Verifying by CA
[10.05.2021 21:57:26.236] <139835194615552> vmb | Verifying certificate(/OU=Domain Control Validated/CN=draas.nephoscloud.mx) by CA
[10.05.2021 21:57:26.236] <139835194615552> vmb | Set server hostname: cgw01.nephoscloud.mx.
[10.05.2021 21:57:26.237] <139835194615552> vmb | Downloading crl for: /OU=Domain Control Validated/CN=draas.nephoscloud.mx(crl count: 1)
[10.05.2021 21:57:26.237] <139835194615552> vmb | Downloading CRL from: http://crl.godaddy.com/gdig2s1-2904.crl
[10.05.2021 21:57:26.237] <139835194615552> vmb | Creating HTTP client. URI: [http://crl.godaddy.com/gdig2s1-2904.crl]
[10.05.2021 21:57:26.464] <139835194615552> vmb | Downloading crl for: /C=US/ST=Arizona/L=Scottsdale/O=GoDaddy.com, Inc./OU=http://certs.godaddy.com/repository//CN=Go Daddy Secure Certificate Authority - G2(crl count: 1)
[10.05.2021 21:57:26.464] <139835194615552> vmb | Downloading CRL from: http://crl.godaddy.com/gdroot-g2.crl
[10.05.2021 21:57:26.465] <139835194615552> vmb | Creating HTTP client. URI: [http://crl.godaddy.com/gdroot-g2.crl]
[10.05.2021 21:57:26.631] <139835194615552> vmb | Downloading crl for: /C=US/ST=Arizona/L=Scottsdale/O=GoDaddy.com, Inc./CN=Go Daddy Root Certificate Authority - G2(crl count: 0)
[10.05.2021 21:57:26.632] <139835194615552> vmb | Verifying certificate(/OU=Domain Control Validated/CN=draas.nephoscloud.mx) by CA ok.
[10.05.2021 21:57:26.632] <139835194615552> vmb | CCloudCertificateVerifier: verify certificate: /OU=Domain Control Validated/CN=draas.nephoscloud.mx ok.
[10.05.2021 21:57:26.692] <139835194615552> vmb | [cloud] Invoke [Service/TestConnection][10/40]: <SIData><CISpec Options="1" TenantName="cadlan" /></SIData>
[10.05.2021 21:57:26.720] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="1" />
[10.05.2021 21:57:26.920] <139835194615552> vmb | Cloud server version is [11.0.0.837]
[10.05.2021 21:57:26.920] <139835194615552> vmb | Creating cloud repository job settings validator.
[10.05.2021 21:57:26.920] <139835194615552> vmb | Validating job settings for Cloud repository limitations.
[10.05.2021 21:57:26.921] <139835194615552> | Default CA certificates will be loaded from: /etc/ssl/certs
[10.05.2021 21:57:26.921] <139835194615552> | CA certificates was loaded from OS specific paths
[10.05.2021 21:57:26.921] <139835194615552> | Default CA certificates will be loaded from: /etc/ssl/certs
[10.05.2021 21:57:27.128] <139835194615552> vmb | CCloudCertificateVerifier: verify certificate: /OU=Domain Control Validated/CN=draas.nephoscloud.mx
[10.05.2021 21:57:27.128] <139835194615552> vmb | Verifying by CA
[10.05.2021 21:57:27.128] <139835194615552> vmb | Verifying certificate(/OU=Domain Control Validated/CN=draas.nephoscloud.mx) by CA
[10.05.2021 21:57:27.128] <139835194615552> vmb | Set server hostname: cgw01.nephoscloud.mx.
[10.05.2021 21:57:27.129] <139835194615552> vmb | Downloading crl for: /OU=Domain Control Validated/CN=draas.nephoscloud.mx(crl count: 1)
[10.05.2021 21:57:27.129] <139835194615552> vmb | Downloading CRL from: http://crl.godaddy.com/gdig2s1-2904.crl
[10.05.2021 21:57:27.130] <139835194615552> vmb | Creating HTTP client. URI: [http://crl.godaddy.com/gdig2s1-2904.crl]
[10.05.2021 21:57:27.350] <139835194615552> vmb | Downloading crl for: /C=US/ST=Arizona/L=Scottsdale/O=GoDaddy.com, Inc./OU=http://certs.godaddy.com/repository//CN=Go Daddy Secure Certificate Authority - G2(crl count: 1)
[10.05.2021 21:57:27.350] <139835194615552> vmb | Downloading CRL from: http://crl.godaddy.com/gdroot-g2.crl
[10.05.2021 21:57:27.350] <139835194615552> vmb | Creating HTTP client. URI: [http://crl.godaddy.com/gdroot-g2.crl]
[10.05.2021 21:57:27.516] <139835194615552> vmb | Downloading crl for: /C=US/ST=Arizona/L=Scottsdale/O=GoDaddy.com, Inc./CN=Go Daddy Root Certificate Authority - G2(crl count: 0)
[10.05.2021 21:57:27.516] <139835194615552> vmb | Verifying certificate(/OU=Domain Control Validated/CN=draas.nephoscloud.mx) by CA ok.
[10.05.2021 21:57:27.517] <139835194615552> vmb | CCloudCertificateVerifier: verify certificate: /OU=Domain Control Validated/CN=draas.nephoscloud.mx ok.
[10.05.2021 21:57:27.577] <139835194615552> vmb | [cloud] Invoke [Service/TestConnection][10/40]: <SIData><CISpec Options="1" TenantName="cadlan" /></SIData>
[10.05.2021 21:57:27.605] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="1" />
[10.05.2021 21:57:27.749] <139835194615552> vmb | [cloud] Invoke [Service/ValidateJobSetting][10/300]: <SIData><CISpec JobSettings='<JobSettings RepositoryId="{d29c60e7-f88c-4381-a950-520ad07cc718}" BackupMode="0" FullBackupEnabled="false" CompactFullEnabled="false" TransformIncrementsToSynthetic="false" TransformFullToSynthetic="false" ChainLength="1" />' UserIdentity="<UserIdentity><Name>cadlan</Name><Password>7AA714FE1426179AB7F34C228BEFF5AB72B29450EDC1BD70D1C9AA1FBCB5225F</Password></UserIdentity>" /></SIData>
[10.05.2021 21:57:27.785] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="<JobSettingsValidationResult IsValid="True" ErrorMessage="" />" />
[10.05.2021 21:57:27.960] <139835194615552> vmb | Acquiring cloud provider resources
[10.05.2021 21:57:28.104] <139835194615552> vmb | [cloud] Invoke [Service/CanAcquireResource][10/238]: <SIData><CISpec><Bundle CloudType="3" JobSessionId="{5eba2cbd-7013-40ac-b94a-84aeef62d931}" Name="Cloud task"><ResourceRequest><CCloudTaskResourceRequest p1:Version="1" Id="{5f837f0b-77ae-4398-b0e0-9d2c9295316a}" ProviderId="{00000000-0000-0000-0000-000000000000}" xmlns:p1="vxs" /></ResourceRequest></Bundle></CISpec></SIData>
[10.05.2021 21:57:28.134] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="True" />
[10.05.2021 21:57:28.279] <139835194615552> vmb | [cloud] Invoke [Service/CanAcquireResource][10/238]: <SIData><CISpec><Bundle CloudType="0" JobSessionId="{5eba2cbd-7013-40ac-b94a-84aeef62d931}" Name="Cloud repository"><ResourceRequest><CRepositoryResourceRequest p1:Version="1" Id="{50ec7ce6-c245-42d5-bdbe-c2bd23aabee4}" JobId="{00000000-0000-0000-0000-000000000000}" _repositoryName="[CGW01.NEPHOSCLOUD.MX][cadlan]CadLan_Cloud_Backup" RepositoryId="{d29c60e7-f88c-4381-a950-520ad07cc718}" IsJobShared="False" IsTargetRepository="True" UsedSize="17122701312" ShouldCorrectSize="True" xmlns:p1="vxs"><AllVmsInJobUsedSize p1:Count="1"><p1:Included p1:Count="1"><Item Value="17122701312"><Key HostUniqueId="dummy" ObjectUniqueId="dummy" /></Item></p1:Included></AllVmsInJobUsedSize><_storageRequests p1:Count="1"><Item Id="{aa3875f8-0210-432e-930e-04249fb854de}" ResourceName="Dependant repository request [{aa3875f8-0210-432e-930e-04249fb854de}]" Access="2" AccessType="2" StorageId="{43682897-6b71-46fe-9d2b-eeb59768fa15}" RequestState="1" StorageType="0" IsSlotNeeded="True" ObjectId="{00000000-0000-0000-0000-000000000000}" BackupId="{23f2b90e-5e51-4df9-8a4d-f420b3636b91}" KeepWithPrevFull="False" /></_storageRequests></CRepositoryResourceRequest></ResourceRequest></Bundle></CISpec></SIData>
[10.05.2021 21:57:28.312] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="True" />
[10.05.2021 21:57:28.458] <139835194615552> vmb | [cloud] Invoke [Service/AcquireResource][10/239]: <SIData><CISpec><Bundle CloudType="3" JobSessionId="{5eba2cbd-7013-40ac-b94a-84aeef62d931}" Name="Cloud task"><ResourceRequest><CCloudTaskResourceRequest p1:Version="1" Id="{5f837f0b-77ae-4398-b0e0-9d2c9295316a}" ProviderId="{00000000-0000-0000-0000-000000000000}" xmlns:p1="vxs" /></ResourceRequest></Bundle></CISpec></SIData>
[10.05.2021 21:57:28.487] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="True" />
[10.05.2021 21:57:28.635] <139835194615552> vmb | [cloud] Invoke [Service/AcquireResource][10/239]: <SIData><CISpec><Bundle CloudType="0" JobSessionId="{5eba2cbd-7013-40ac-b94a-84aeef62d931}" Name="Cloud repository"><ResourceRequest><CRepositoryResourceRequest p1:Version="1" Id="{50ec7ce6-c245-42d5-bdbe-c2bd23aabee4}" JobId="{00000000-0000-0000-0000-000000000000}" _repositoryName="[CGW01.NEPHOSCLOUD.MX][cadlan]CadLan_Cloud_Backup" RepositoryId="{d29c60e7-f88c-4381-a950-520ad07cc718}" IsJobShared="False" IsTargetRepository="True" UsedSize="17122701312" ShouldCorrectSize="True" xmlns:p1="vxs"><AllVmsInJobUsedSize p1:Count="1"><p1:Included p1:Count="1"><Item Value="17122701312"><Key HostUniqueId="dummy" ObjectUniqueId="dummy" /></Item></p1:Included></AllVmsInJobUsedSize><_storageRequests p1:Count="1"><Item Id="{aa3875f8-0210-432e-930e-04249fb854de}" ResourceName="Dependant repository request [{aa3875f8-0210-432e-930e-04249fb854de}]" Access="2" AccessType="2" StorageId="{43682897-6b71-46fe-9d2b-eeb59768fa15}" RequestState="1" StorageType="0" IsSlotNeeded="True" ObjectId="{00000000-0000-0000-0000-000000000000}" BackupId="{23f2b90e-5e51-4df9-8a4d-f420b3636b91}" KeepWithPrevFull="False" /></_storageRequests></CRepositoryResourceRequest></ResourceRequest></Bundle></CISpec></SIData>
[10.05.2021 21:57:28.678] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="True" />
[10.05.2021 21:57:28.678] <139835194615552> vmb | Acquiring cloud provider resources ok.
[10.05.2021 21:57:28.678] <139835194615552> lpbcore| Enumerating snapshots requests.
[10.05.2021 21:57:28.679] <139835194615552> lpbcore| Requesting snapshot for device sda1 [8:1].
[10.05.2021 21:57:28.679] <139835194615552> lpbcore| Requesting snapshot for device sda5 [8:5].
[10.05.2021 21:57:28.679] <139835194615552> lpbcore| [2] snapshots requests found.
[10.05.2021 21:57:28.679] <139835194615552> lpbcore| Taking snapshots.
[10.05.2021 21:57:28.681] <139835194615552> vmb | [SessionLog][processing] Creating volume snapshot.
[10.05.2021 21:57:29.416] <139835194615552> prtcl | WARN|Method invocation was not finalized. Method id [1]. Class: [lpbcorelib::interaction::ISnapshotOperation]
[10.05.2021 21:57:29.416] <139835194615552> prtcl | ERR |Argumento inválido
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |Failed to execute IOCTL_TRACKING_ADD.
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:Failed to create machine snapshot
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:in void lpbcorelib::snapshot::CVeeamSnapSnapshot::CreateSnapshot(const SVeeamSnapDeviceRequestList&, lpbcorelib::snapshot::SCbtDataMap_t&) at /mnt/Sources/LPBCoreLib/lpbcorelib/snapshot/VeeamSnapSnapshot.cpp:135
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:Failed to finish snapshot creation process.
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:in virtual lpbcorelib::interaction::ISnapshotResourceLockPtr lpbcorelib::service::CSnapshotOperation::EndCreateSnapshot(lpbcorelib::service::CSnapshotTask::Ptr&) at /mnt/Sources/LPBCoreLib/lpbcorelib/service/SnapshotOperation.cpp:33
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:Failed to execute method [1] for class [lpbcorelib::interaction::ISnapshotOperation].
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:in void remoting::CStubBase<TStub, TObject>::ExecuteMethod(remoting::method_id_t, remoting::IStub::SDispatchContext&) const [with TStub = lpbcorelib::interaction::proxystub::CSnapshotOperationStub; TObject = lpbcorelib::interaction::ISnapshotOperation; remoting::method_id_t = unsigned int] at /mnt/Sources/LPBCoreLib/lpbcorelib/interaction/proxystub/../../../../ProtocolLib/remoting/StubBase.h:78
[10.05.2021 21:57:29.416] <139835194615552> prtcl | >> |--tr:Failed to invoke method [1] in class [lpbcorelib::interaction::ISnapshotOperation].
[10.05.2021 21:57:29.417] <139835194615552> prtcl | >> |--tr:in void remoting::CProxyBase::CMethodInvocationStream<TObject>::FinalizeExc() [with TObject = lpbcorelib::interaction::ISnapshotOperation] at /mnt/Sources/LPBCoreLib/lpbcorelib/interaction/proxystub/../../../../ProtocolLib/remoting/ProxyBase.h:92
[10.05.2021 21:57:29.417] <139835194615552> prtcl | >> |An exception was thrown from thread [139835194615552].
[10.05.2021 21:57:29.417] <139835194615552> vmb | [SessionLog][error] Failed to create volume snapshot.
[10.05.2021 21:57:29.422] <139835194615552> vmb | [SessionLog][error] Failed to take volume snapshot.
[10.05.2021 21:57:29.427] <139835194615552> lpbcore| Taking snapshots. Failed.
[10.05.2021 21:57:29.568] <139835194615552> vmb | [cloud] Invoke [Service/ReleaseResource][10/240]: <SIData><CISpec><Bundle CloudType="0" JobSessionId="{5eba2cbd-7013-40ac-b94a-84aeef62d931}" Name="Cloud repository"><ResourceRequest><CRepositoryResourceRequest p1:Version="1" Id="{50ec7ce6-c245-42d5-bdbe-c2bd23aabee4}" JobId="{00000000-0000-0000-0000-000000000000}" _repositoryName="[CGW01.NEPHOSCLOUD.MX][cadlan]CadLan_Cloud_Backup" RepositoryId="{d29c60e7-f88c-4381-a950-520ad07cc718}" IsJobShared="False" IsTargetRepository="True" UsedSize="17122701312" ShouldCorrectSize="True" xmlns:p1="vxs"><AllVmsInJobUsedSize p1:Count="1"><p1:Included p1:Count="1"><Item Value="17122701312"><Key HostUniqueId="dummy" ObjectUniqueId="dummy" /></Item></p1:Included></AllVmsInJobUsedSize><_storageRequests p1:Count="1"><Item Id="{aa3875f8-0210-432e-930e-04249fb854de}" ResourceName="Dependant repository request [{aa3875f8-0210-432e-930e-04249fb854de}]" Access="2" AccessType="2" StorageId="{43682897-6b71-46fe-9d2b-eeb59768fa15}" RequestState="1" StorageType="0" IsSlotNeeded="True" ObjectId="{00000000-0000-0000-0000-000000000000}" BackupId="{23f2b90e-5e51-4df9-8a4d-f420b3636b91}" KeepWithPrevFull="False" /></_storageRequests></CRepositoryResourceRequest></ResourceRequest></Bundle></CISpec></SIData>
[10.05.2021 21:57:29.603] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="True" />
[10.05.2021 21:57:29.746] <139835194615552> vmb | [cloud] Invoke [Service/ReleaseResource][10/240]: <SIData><CISpec><Bundle CloudType="3" JobSessionId="{5eba2cbd-7013-40ac-b94a-84aeef62d931}" Name="Cloud task"><ResourceRequest><CCloudTaskResourceRequest p1:Version="1" Id="{5f837f0b-77ae-4398-b0e0-9d2c9295316a}" ProviderId="{00000000-0000-0000-0000-000000000000}" xmlns:p1="vxs" /></ResourceRequest></Bundle></CISpec></SIData>
[10.05.2021 21:57:29.775] <139835194615552> vmb | [cloud] Result: <SIResponse CIResult="True" />
[10.05.2021 21:57:29.916] <139835194615552> vmb | [cloud] Invoke [Service/FinishBackupVm][10/130]: <SIData><CISpec VmSpec='<RepositoryVmSpec VmUuid="{30448908-7c92-4e6a-a611-4830f18e6b44}" />' LicenseMode="1" IsRentalLicense="True" /></SIData>
[10.05.2021 21:57:29.944] <139835194615552> vmb | [cloud] Result: <SIResponse />
[10.05.2021 21:57:29.944] <139835194615552> lpbcore| BackupJobPerformer: Creating backup. Failed.
[10.05.2021 21:57:29.944] <139835194615552> vmb | [SessionLog][error] Failed to perform backup.
[10.05.2021 21:57:29.947] <139835194615552> vmb | [SessionLog][error] Argumento inválido.
[10.05.2021 21:57:29.951] <139835194615552> vmb | [SessionLog][error] Failed to execute IOCTL_TRACKING_ADD.
[10.05.2021 21:57:29.953] <139835194615552> vmb | [SessionLog][error] Processing finished with errors at 2021-05-11 02:57:29 UTC.
[10.05.2021 21:57:29.955] <139835194615552> lpbcore| ERR |Argumento inválido
[10.05.2021 21:57:29.955] <139835194615552> lpbcore| >> |Failed to execute IOCTL_TRACKING_ADD.
[10.05.2021 21:57:29.955] <139835194615552> lpbcore| >> |--tr:Failed to create machine snapshot