Solved

Copy backup to USB failed


Userlevel 5
Badge +1

Job site - Backup USB\site - Backup Job cannot be started Job failed unexpectedly Job finished with error. How do I correct this issue? We have plugged and unplugged USB drive but that did not resolve the issue. Using Veeam Back and Replication 10

icon

Best answer by JMeixner 26 January 2023, 21:46

View original

16 comments

Userlevel 7
Badge +21

Do you have a screenshot of the error, and did you check the logs here - C:\ProgramData\Veeam\Backup for errors and clues?

Based on the post it is hard to even start to help.

Userlevel 5
Badge +1

This is the Job failure

Did not find any error

Recompiled Backup repository 

Userlevel 7
Badge +21

The second picture - check the Task and Job text files listed there that are ~4MB each -- those are the log files to check for errors.

Userlevel 5
Badge +1

[27.12.2022 21:04:59] <06> Info                   [AP] (6c42) output: >
[27.12.2022 21:04:59] <29> Info         [OIB] Updating OIB, id '45b76d5e-7585-4915-9761-9712cc9714e1', IsCorrupted 'False':'False'
[27.12.2022 21:04:59] <29> Info     Saving summary to backup file [E:\Backups\ - Backup USB\ - Backup Job\ - Backup JobD2022-12-27T210308_59A3.vib]. InsidePath: [dcb00872-3c7d-4a8b-8cd9-f70c71c53974 (vm-190)].
[27.12.2022 21:04:59] <29> Info             Text to backup: '<OibSummary><Backup Id="a7a312ac-7a84-4ba3-9473-227997946cb3" OriginalId="a7a312ac-7a84-4ba3-9473-227997946cb3" JobId="f300fa68-f8c1-4b73-9b78-20e8608ab462" JobName=" - Backup USB\ - Backup Job" PolicyName=" - Backup USB" JobSourceType="5" JobTargetType="63" TargetType="0" JobTargetHostId="df2849ac-2066-4b97-b09b-53e684c9accc" JobTargetHostProtocol="0" Platform="0" BackupPlatformId="00000000-0000-0000-0000-000000000000" BackupPolicyTag="0cfb5fb4-08f6-4382-8ab6-a8f29e23579f" ParentBackupId="87c84fe8-ead7-487e-95ff-4756079d5b0a" CreationTime="06/10/2021 12:08:32" CreationTimeUtc="06/10/2021 16:08:32" IsJustMigratedToSobr="false" EncryptionState="0" UsedMetaType="0" AttachedJobType="63" /><Point Id="98822bf7-b697-4ea9-a71a-43caf7667770" LinkId="4a1e4a73-35bf-4b55-9fa4-42b3d1cd9fbc" Num="543.0000000000" GroupId="3d587e6f-ed51-4ef3-9487-1bd806e93444" CreationTime="12/27/2022 21:03:08" CreationTimeUtc="12/28/2022 02:03:08" Algoritm="2" Type="1" BackupId="a7a312ac-7a84-4ba3-9473-227997946cb3" /><Storage Id="a4157b94-9435-4e72-ac5a-57826e65df01" BackupId="a7a312ac-7a84-4ba3-9473-227997946cb3" HostId="df2849ac-2066-4b97-b09b-53e684c9accc" FilePath="E:\Backups\ - Backup USB\ - Backup Job\ - Backup JobD2022-12-27T210308_59A3.vib" PartialPath="&lt;Path IsFilePath=&quot;True&quot;&gt;&lt;Elements&gt; - Backup JobD2022-12-27T210308_59A3.vib&lt;/Elements&gt;&lt;/Path&gt;" Version="1" CreationTime="12/27/2022 21:03:08" CreationTimeUtc="12/28/2022 02:03:08" ModificationTime="12/27/2022 21:03:08" BlockSize="KbBlockSize1024" BlockAlignmentSize="512" PartialIncrement="False" ObjectId="00000000-0000-0000-0000-000000000000" ExternalContentMode="Internal" ChangeVersion="0" LinkId="32f09a84-a186-40fc-9f9f-6b23962eb3ae" GfsPeriod="None" CreationMode="Normal" LogMetaTag="NotExist">&lt;CBackupStats&gt;&lt;BackupSize&gt;0&lt;/BackupSize&gt;&lt;DataSize&gt;0&lt;/DataSize&gt;&lt;DedupRatio&gt;0&lt;/DedupRatio&gt;&lt;CompressRatio&gt;0&lt;/CompressRatio&gt;&lt;/CBackupStats&gt;</Storage><OIB Id="45b76d5e-7585-4915-9761-9712cc9714e1" OriginalOibId="7e3a7123-4526-4b3f-ade1-2e1fba28d4da" ObjectId="bbe3724a-57fb-4704-8733-0c2b5a3e315a" PointId="98822bf7-b697-4ea9-a71a-43caf7667770" StorageId="a4157b94-9435-4e72-ac5a-57826e65df01" LinkId="bfbb89a0-01fa-4ff9-92aa-f7b5c90791c9" IsCorrupted="False" IsRecheckCorrupted="False" IsLicensed="True" IsConsistent="True" State="0" Type="2" Algorithm="2" InsideDir="dcb00872-3c7d-4a8b-8cd9-f70c71c53974 (vm-190)" CreationTime="12/27/2022 21:01:24" CreationTimeUtc="12/28/2022 02:01:24" VmName="MFP01" ApproxSize="150728605696" EffectiveMemoryMb="0" HasIndex="False" HasExchange="False" HasSharePoint="False" HasSql="False" HasAd="False" HasOracle="False" HasVeeamArchiver="False" AuxData="&lt;COibAuxData&gt;&lt;CreationUsn value=&quot;544&quot; /&gt;&lt;HasVssMetadata&gt;False&lt;/HasVssMetadata&gt;&lt;CreationTimeUtc&gt;12/28/2022 02:01:24&lt;/CreationTimeUtc&gt;&lt;COibAuxDataVmware&gt;&lt;VmMoRef&gt;vm-190&lt;/VmMoRef&gt;&lt;HostName&gt;dk55vct01.cts.ctscorp.com&lt;/HostName&gt;&lt;EsxName&gt;vmw01.ctscorp.com&lt;/EsxName&gt;&lt;DataStoreRef&gt;datastore-187&lt;/DataStoreRef&gt;&lt;ResourcePoolRef&gt;resgroup-176&lt;/ResourcePoolRef&gt;&lt;FolderRef&gt;group-v171&lt;/FolderRef&gt;&lt;SnapshotRef&gt;&lt;/SnapshotRef&gt;&lt;SnapshotCreationTime&gt;0&lt;/SnapshotCreationTime&gt;&lt;DirPath&gt;[-VM2] MFP01&lt;/DirPath&gt;&lt;Location&gt;dk55vct01.cts.ctscorp.com&lt;/Location&gt;&lt;OijId&gt;00000000-0000-0000-0000-000000000000&lt;/OijId&gt;&lt;RealVmSize&gt;168040806198&lt;/RealVmSize&gt;&lt;VmxFileDatastorePath&gt;MFP01/MFP01.vmx&lt;/VmxFileDatastorePath&gt;&lt;Profiles /&gt;&lt;Tags /&gt;&lt;NicInfo Nic=&quot;0&quot; ConnectAtPowerOn=&quot;True&quot; PciSlotNumber=&quot;192&quot;&gt;&lt;Network&gt;&lt;Name&gt;VLAN12&lt;/Name&gt;&lt;/Network&gt;&lt;/NicInfo&gt;&lt;Disk&gt;&lt;Key&gt;2000&lt;/Key&gt;&lt;UnitNumber&gt;0&lt;/UnitNumber&gt;&lt;ControllerKey&gt;1000&lt;/ControllerKey&gt;&lt;Controller&gt;&lt;CViDeviceController p1:Version=&quot;1&quot; Key=&quot;1000&quot; Number=&quot;0&quot; xmlns:p1=&quot;vxs&quot;&gt;&#xD;&#xA;  &lt;Type _type=&quot;VirtualLsiLogicSASController&quot; /&gt;&#xD;&#xA;&lt;/CViDeviceController&gt;&lt;/Controller&gt;&lt;DescFileName&gt;MFP01.vmdk&lt;/DescFileName&gt;&lt;FlatFileName&gt;MFP01-flat.vmdk&lt;/FlatFileName&gt;&lt;ChangeId&gt;52 d0 e0 50 d7 b4 10 30-9e e1 0c 82 8f 41 34 64/17&lt;/ChangeId&gt;&lt;SnapshotRef /&gt;&lt;DatastoreRef&gt;datastore-187&lt;/DatastoreRef&gt;&lt;Path&gt;[-VM2] MFP01/MFP01.vmdk&lt;/Path&gt;&lt;BusType&gt;2000&lt;/BusType&gt;&lt;Label&gt;Hard disk 1&lt;/Label&gt;&lt;Uuid&gt;6000C29f-29cd-893e-87bb-5c3efa4cebb5&lt;/Uuid&gt;&lt;Capacity&gt;107374182400&lt;/Capacity&gt;&lt;ThinProvisioned&gt;True&lt;/ThinProvisioned&gt;&lt;EagerlyScrub&gt;False&lt;/EagerlyScrub&gt;&lt;ExistsInBackup&gt;True&lt;/ExistsInBackup&gt;&lt;ContentId&gt;d8c446c465507d1911e949631db7f1e6&lt;/ContentId&gt;&lt;UseBlockExclude&gt;False&lt;/UseBlockExclude&gt;&lt;Mode /&gt;&lt;TaskId /&gt;&lt;DiskId&gt;f300fa68-f8c1-4b73-9b78-20e8608ab462:bbe3724a-57fb-4704-8733-0c2b5a3e315a:2000:45b76d5e-7585-4915-9761-9712cc9714e1&lt;/DiskId&gt;&lt;State&gt;Processed&lt;/State&gt;&lt;Type&gt;Default&lt;/Type&gt;&lt;ValidProcessedOffset&gt;107374182400&lt;/ValidProcessedOffset&gt;&lt;/Disk&gt;&lt;Disk&gt;&lt;Key&gt;2001&lt;/Key&gt;&lt;UnitNumber&gt;1&lt;/UnitNumber&gt;&lt;ControllerKey&gt;1000&lt;/ControllerKey&gt;&lt;Controller&gt;&lt;CViDeviceController p1:Version=&quot;1&quot; Key=&quot;1000&quot; Number=&quot;0&quot; xmlns:p1=&quot;vxs&quot;&gt;&#xD;&#xA;  &lt;Type _type=&quot;VirtualLsiLogicSASController&quot; /&gt;&#xD;&#xA;&lt;/CViDeviceController&gt;&lt;/Controller&gt;&lt;DescFileName&gt;MFP01_1.vmdk&lt;/DescFileName&gt;&lt;FlatFileName&gt;MFP01_1-flat.vmdk&lt;/FlatFileName&gt;&lt;ChangeId&gt;52 c1 a8 a9 04 23 4d 17-06 4d b4 e0 f2 d0 29 c8/17&lt;/ChangeId&gt;&lt;SnapshotRef /&gt;&lt;DatastoreRef&gt;datastore-187&lt;/DatastoreRef&gt;&lt;Path&gt;[-VM2] MFP01/MFP01_1.vmdk&lt;/Path&gt;&lt;BusType&gt;2000&lt;/BusType&gt;&lt;Label&gt;Hard disk 2&lt;/Label&gt;&lt;Uuid&gt;6000C298-6374-9eb8-239b-00904b598870&lt;/Uuid&gt;&lt;Capacity&gt;322122547200&lt;/Capacity&gt;&lt;ThinProvisioned&gt;True&lt;/ThinProvisioned&gt;&lt;EagerlyScrub&gt;False&lt;/EagerlyScrub&gt;&lt;ExistsInBackup&gt;True&lt;/ExistsInBackup&gt;&lt;ContentId&gt;338efe4d510b7c278dfca8be95b40c44&lt;/ContentId&gt;&lt;UseBlockExclude&gt;False&lt;/UseBlockExclude&gt;&lt;Mode /&gt;&lt;TaskId /&gt;&lt;DiskId&gt;f300fa68-f8c1-4b73-9b78-20e8608ab462:bbe3724a-57fb-4704-8733-0c2b5a3e315a:2001:45b76d5e-7585-4915-9761-9712cc9714e1&lt;/DiskId&gt;&lt;State&gt;Processed&lt;/State&gt;&lt;Type&gt;Default&lt;/Type&gt;&lt;ValidProcessedOffset&gt;322122547200&lt;/ValidProcessedOffset&gt;&lt;/Disk&gt;&lt;VmStateInStorageSnapshot&gt;0&lt;/VmStateInStorageSnapshot&gt;&lt;VmxDatastoreUuid&gt;600f2d11-e41b2828-737f-3448edf4a38a&lt;/VmxDatastoreUuid&gt;&lt;VmxDiskPaths /&gt;&lt;StorageSnapshotJobName&gt;&lt;/StorageSnapshotJobName&gt;&lt;/COibAuxDataVmware&gt;&lt;/COibAuxData&gt;" ParentId="00000000-0000-0000-0000-000000000000" ParentOriginalOibId="00000000-0000-0000-0000-000000000000" DisplayName="MFP01" Fqdn="" CreationUsn="544" CompletionTimeUtc="12/28/2022 02:04:59" SnapshotId="00000000-0000-0000-0000-000000000000" ProductId="b1e61d9b-8d78-4419-8f63-d21279f71a56" ProductVersion="10.0.1.4854" ProductVersionFlags="0" ProductIsRentalLicense="False">&lt;GuestInfo&gt;&lt;Property Name="GuestOsName"&gt;&lt;Value&gt;Microsoft Windows Server 2016 or later (64-bit)&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="GuestOsType"&gt;&lt;Value&gt;windows9Server64Guest&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="DnsName"&gt;&lt;Value&gt;MFP01.cts.ctscorp.com&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="ToolsStatus"&gt;&lt;Value&gt;Ok&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="ToolsVersionStatus"&gt;&lt;Value&gt;Current&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="Ip"&gt;&lt;Value&gt;10.6.12.12&lt;/Value&gt;&lt;/Property&gt;&lt;/GuestInfo&gt;</OIB><Object Id="bbe3724a-57fb-4704-8733-0c2b5a3e315a" Type="1" HostId="dcb00872-3c7d-4a8b-8cd9-f70c71c53974" Name="MFP01" ObjectId="vm-190" ViType="VirtualMachine" Path="dk55vct01.cts.ctscorp.com\\vmw01.ctscorp.com\Resources\MFP01" Uuid="42185d0e-4218-c87c-c9cd-7e96183d8b20" Platform="0" PlatformId="00000000-0000-0000-0000-000000000000" ParentId="00000000-0000-0000-0000-000000000000" HashV2="2193cff4-a68f-757d-a594-fe8519f9cd26" DisplayName="MFP01">&lt;GuestInfo&gt;&lt;Property Name="GuestOsName"&gt;&lt;Value&gt;Microsoft Windows Server 2016 or later (64-bit)&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="GuestOsType"&gt;&lt;Value&gt;windows9Server64Guest&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="DnsName"&gt;&lt;Value&gt;MFP01.cts.ctscorp.com&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="ToolsStatus"&gt;&lt;Value&gt;Ok&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="ToolsVersionStatus"&gt;&lt;Value&gt;Current&lt;/Value&gt;&lt;/Property&gt;&lt;Property Name="Ip"&gt;&lt;Value&gt;10.6.12.12&lt;/Value&gt;&lt;/Property&gt;&lt;/GuestInfo&gt;</Object><TargetHost Id="df2849ac-2066-4b97-b09b-53e684c9accc" Name="bak01.cts.ctscorp.com" Description="Created by CTS\rwegnerda at 1/29/2021 5:49 PM." Ip="0.0.0.0" Type="5" Reference="" Info="Microsoft Windows 10 Pro 64-bit (10.0.19042 build:19042)" ParentId="00000000-0000-0000-0000-000000000000" Protocol="0" ApiVersion="0" Options="" PhysHostId="57660164-5281-452e-9a7b-ccfb6c4c07f4" CredsId="6aecf1df-7d79-41d2-a35d-80f6d85c51b1" DnsName="bak01.cts.ctscorp.com" HostInstanceId="bak01.cts.ctscorp.com" HostInstanceIdV2="bak01.cts.ctscorp.com" HostUniqueId="" /><PrevFileName>E:\Backups\ - Backup USB\ - Backup Job\ - Backup JobD2022-12-26T210309_7BB7.vib</PrevFileName><BackupVersion>2</BackupVersion><OibFiles><File FileName="MFP01.vmx" Size="3444" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="" ThinProvisioned="False" EagerlyScrub="False" SnapshotMoRef="" Uuid="" /></File><File FileName="MFP01.nvram" Size="8684" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="" ThinProvisioned="False" EagerlyScrub="False" SnapshotMoRef="" Uuid="" /></File><File FileName="FsAwareMeta:bbe3724a-57fb-4704-8733-0c2b5a3e315a:2001" Size="9707" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="" ThinProvisioned="True" EagerlyScrub="False" SnapshotMoRef="" Uuid="" /></File><File FileName="FsAwareMeta:bbe3724a-57fb-4704-8733-0c2b5a3e315a:2000" Size="24942" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="" ThinProvisioned="True" EagerlyScrub="False" SnapshotMoRef="" Uuid="" /></File><File FileName="MFP01.vmdk" Size="632" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="" ThinProvisioned="False" EagerlyScrub="False" SnapshotMoRef="" Uuid="" /></File><File FileName="MFP01-flat.vmdk" Size="107374182400" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="52 d0 e0 50 d7 b4 10 30-9e e1 0c 82 8f 41 34 64/17" ThinProvisioned="True" EagerlyScrub="False" SnapshotMoRef="" Uuid="6000C29f-29cd-893e-87bb-5c3efa4cebb5" /></File><File FileName="MFP01_1.vmdk" Size="582" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="" ThinProvisioned="False" EagerlyScrub="False" SnapshotMoRef="" Uuid="" /></File><File FileName="MFP01_1-flat.vmdk" Size="322122547200" LastModification="01/01/0001 00:00:00" Exist="True" IsPassThroughDisk="False" IsDiskProcessing="False"><PlatformDetails PlatformType="0" ItemType="ViFile" ChangeId="52 c1 a8 a9 04 23 4d 17-06 4d b4 e0 f2 d0 29 c8/17" ThinProvisioned="True" EagerlyScrub="False" SnapshotMoRef="" Uuid="6000C298-6374-9eb8-239b-00904b598870" /></File></OibFiles><LogBackupInfo Id="a7a312ac-7a84-4ba3-9473-227997946cb3" SourceOriginalId="b42e238d-4878-40cc-bc45-4800528de31e" CurrentCreationUsn="1" CreationUsnShift="0"></LogBackupInfo><SourceHost Id="dcb00872-3c7d-4a8b-8cd9-f70c71c53974" Name="dk55vct01.cts.ctscorp.com" Description="Created by CTS\rwegnerda at 9/17/2020 3:15 PM." Ip="0.0.0.0" Type="1" Reference="" Info="VMware vCenter Server 6.7.0 build-19299595" ParentId="00000000-0000-0000-0000-000000000000" Protocol="0" ApiVersion="13" Options="" PhysHostId="adc06188-97cd-44c5-8a76-a4c47c2244a6" CredsId="00000000-0000-0000-0000-000000000000" DnsName="dk55vct01.cts.ctscorp.com" HostInstanceId="869767a3-f420-479b-8b48-021b6f990e18" HostInstanceIdV2="869767a3-f420-479b-8b48-021b6f990e18" HostUniqueId="869767a3-f420-479b-8b48-021b6f990e18" /></OibSummary>'
[27.12.2022 21:04:59] <29> Info             [AP] (6c42) command: 'Invoke: DataTransfer.BackupText { (EString) BackupType = {synth}; (EString) VbkLink = veeamfs:0:dcb00872-3c7d-4a8b-8cd9-f70c71c53974 (vm-190)/summary.xml@E:\Backups\ - Backup USB\ - Backup Job\ - Backup JobD2022-12-27T210308_59A3.vib; (EBoolean) FullCopy = false; (EBoolean) Option.Remote = true; (EBlob) Text = {EBlob: 13388 bytes}; }'
[27.12.2022 21:04:59] <06> Info                   [AP] (6c42) output: <VCPCommandResult result="true" exception="" />
[27.12.2022 21:05:00] <28> Info                   [AP] (6c42) output: <VCPCommandArgs />
[27.12.2022 21:05:00] <28> Info                   [AP] (6c42) output: >
[27.12.2022 21:05:00] <29> Info         [OIB] Updating oib '45b76d5e-7585-4915-9761-9712cc9714e1',set isConsistent = 'True' 
[27.12.2022 21:05:00] <29> Info         [BackupMeta] Generate backup meta for ' - Backup USB\ - Backup Job:a7a312ac-7a84-4ba3-9473-227997946cb3'
[27.12.2022 21:05:00] <29> Info     [BackupMetaUpdater] Waiting for backup meta to be generated in another thread <18>
[27.12.2022 21:05:03] <29> Info         [BackupMeta] Backup meta for ' - Backup USB\ - Backup Job:a7a312ac-7a84-4ba3-9473-227997946cb3' generated
[27.12.2022 21:05:03] <29> Info     [BackupEncryptedStateUpdater] Committing backup encryption state (BackupId='a7a312ac-7a84-4ba3-9473-227997946cb3', HasCryptedStorages=False, current EncryptionState=Unencrypted)
[27.12.2022 21:05:03] <29> Info         [AgentsSession] Disposing...
[27.12.2022 21:05:03] <29> Info         [AgentsSession] Removing agents connection
[27.12.2022 21:05:03] <29> Info             [AP] (6c42) disconnecting
[27.12.2022 21:05:03] <07> Info                   [AP] (6c42) output: >
[27.12.2022 21:05:03] <29> Info         Disposing CBackupRepositoryClient [0xca5038]
[27.12.2022 21:05:03] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:03] <29> Info         Disposing BaseAgentProtocol [0x2960773]
[27.12.2022 21:05:03] <29> Info         Disposing CSocketAgentService [0xe98564], sessionId [c09c]
[27.12.2022 21:05:03] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id c09c
[27.12.2022 21:05:04] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:04] <29> Info         Disposing BaseAgentProtocol [0x365e6f4]
[27.12.2022 21:05:04] <29> Info         Disposing CSocketAgentService [0x32a29bd], sessionId [a90a]
[27.12.2022 21:05:04] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id a90a
[27.12.2022 21:05:04] <29> Info         Disposing CRepositoryAgent [0x2edbac0], agent id 14b8cad5-0c1c-4939-84cd-d12b078083b2
[27.12.2022 21:05:04] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:04] <29> Info         Disposing BaseAgentProtocol [0x215ab70]
[27.12.2022 21:05:04] <29> Info         Disposing CSocketAgentService [0x3e0806], sessionId [62e9]
[27.12.2022 21:05:04] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 62e9
[27.12.2022 21:05:05] <29> Info         Disposing CProxyAgent [0x151cb2b], agent id 14b8cad5-0c1c-4939-84cd-d12b078083b2
[27.12.2022 21:05:05] <29> Info         [AgentMngr] Stopping agent, id '14b8cad5-0c1c-4939-84cd-d12b078083b2'
[27.12.2022 21:05:05] <29> Info         [AgentMngr] Sending signal to stop agent, id '14b8cad5-0c1c-4939-84cd-d12b078083b2'. Host: 'bak01.cts.ctscorp.com'.
[27.12.2022 21:05:05] <29> Info         [AgentMngr] Checking whether agent '14b8cad5-0c1c-4939-84cd-d12b078083b2' is alive on host 'bak01.cts.ctscorp.com'.
[27.12.2022 21:05:05] <29> Info         [AgentMngr] Agent has been stopped, id '14b8cad5-0c1c-4939-84cd-d12b078083b2'
[27.12.2022 21:05:05] <29> Info     [CProxyRpcInvoker] RpcInvoker [4372246] was disposed
[27.12.2022 21:05:05] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:05] <29> Info         Disposing BaseAgentProtocol [0x2e47b99]
[27.12.2022 21:05:05] <29> Info         Disposing CSocketAgentService [0x1fe6b8d], sessionId [6c42]
[27.12.2022 21:05:05] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 6c42
[27.12.2022 21:05:06] <29> Info     [PerBackupSourceAgentMngr] Shared repository client for backup b42e238d-4878-40cc-bc45-4800528de31e released. New reference count is: 0
[27.12.2022 21:05:06] <29> Info     [PerBackupSourceAgentMngr] Disposing referenced client
[27.12.2022 21:05:06] <29> Info         Disposing CBackupRepositoryClient [0x140d5e3]
[27.12.2022 21:05:06] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:06] <29> Info         Disposing BaseAgentProtocol [0x10892b0]
[27.12.2022 21:05:06] <29> Info         Disposing CSocketAgentService [0x10d5592], sessionId [c58d]
[27.12.2022 21:05:06] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id c58d
[27.12.2022 21:05:06] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:06] <29> Info         Disposing BaseAgentProtocol [0x39af5b7]
[27.12.2022 21:05:06] <29> Info         Disposing CSocketAgentService [0x3e23f6], sessionId [51d5]
[27.12.2022 21:05:06] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 51d5
[27.12.2022 21:05:07] <29> Info         Disposing CRepositoryAgent [0x15eff55], agent id 43b9484f-35e4-4617-a361-1f887405221c
[27.12.2022 21:05:07] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:07] <29> Info         Disposing BaseAgentProtocol [0x208d451]
[27.12.2022 21:05:07] <29> Info         Disposing CSocketAgentService [0x22c5aa9], sessionId [7e13]
[27.12.2022 21:05:07] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 7e13
[27.12.2022 21:05:07] <29> Info         Disposing CProxyAgent [0x2f6da15], agent id 43b9484f-35e4-4617-a361-1f887405221c
[27.12.2022 21:05:07] <29> Info         [AgentMngr] Stopping agent, id '43b9484f-35e4-4617-a361-1f887405221c'
[27.12.2022 21:05:07] <29> Info         [AgentMngr] Sending signal to stop agent, id '43b9484f-35e4-4617-a361-1f887405221c'. Host: 'bak01.cts.ctscorp.com'.
[27.12.2022 21:05:07] <29> Info         [AgentMngr] Checking whether agent '43b9484f-35e4-4617-a361-1f887405221c' is alive on host 'bak01.cts.ctscorp.com'.
[27.12.2022 21:05:07] <29> Info         [AgentMngr] Agent has been stopped, id '43b9484f-35e4-4617-a361-1f887405221c'
[27.12.2022 21:05:07] <29> Info     [CProxyRpcInvoker] RpcInvoker [40260728] was disposed
[27.12.2022 21:05:07] <29> Info     [CProxyRpcInvoker] RpcInvoker [41447819] was disposed
[27.12.2022 21:05:07] <29> Info         [StorageLock] Unlocking storage, lockId: 'b3abbe2d-97d2-44ac-a1ec-98e768505ebe'
[27.12.2022 21:05:07] <29> Info         [API] DestroyLease.Started, leaseId: [2bf1b0ec-e577-4900-b85a-60beb9c74cf5]
[27.12.2022 21:05:07] <29> Info         [API] DestroyLease.Success, leaseId: [2bf1b0ec-e577-4900-b85a-60beb9c74cf5]
[27.12.2022 21:05:07] <29> Info     [CProxyRpcInvoker] RpcInvoker [59279729] was disposed
[27.12.2022 21:05:07] <29> Info         [RepositoryThrottler] Stopping, sessionId a49ac372-9280-4743-b3a4-aa24dbbeef94, repositoryId 226215d4-209d-41c1-9706-fcadeb242ed4, agentId ea997a2a-3885-4a39-97b7-3188e2f9027b, instanceId 8bc0d981-d83e-4ef7-b6aa-aa5048767dec, read False
[27.12.2022 21:05:07] <29> Info             [AP] Disposing client from thread 29
[27.12.2022 21:05:07] <29> Info         Disposing BaseAgentProtocol [0x2b3849b]
[27.12.2022 21:05:07] <29> Info         Disposing CSocketAgentService [0x2d9ad65], sessionId [73c0]
[27.12.2022 21:05:07] <29> Info         [SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 73c0
[27.12.2022 21:05:07] <29> Info         [ReconnectableSocket] Stop request was sent on [eb71393d-75c9-4427-89af-d9ff264a4d17].
[27.12.2022 21:05:08] <41> Info         [ReconnectableSocket][StopCondition] Stop confirmation was received on [eb71393d-75c9-4427-89af-d9ff264a4d17].
[27.12.2022 21:05:08] <41> Info         [ReconnectableSocket] Stop confirmation was sent on [eb71393d-75c9-4427-89af-d9ff264a4d17].
[27.12.2022 21:05:08] <41> Info         [ReconnectableSocket][StopCondition] Stop request was received on [eb71393d-75c9-4427-89af-d9ff264a4d17].
[27.12.2022 21:05:08] <29> Info     [RemoteBackupTaskPerformer] Completing task session MFP01 (moreRetries: False)
[27.12.2022 21:05:08] <29> Info     Busy: Source 41% > Proxy 15% > Network 42% > Target 61%
[27.12.2022 21:05:08] <29> Info     Primary bottleneck: Target
[27.12.2022 21:05:08] <29> Info           Job progress: '99%', '429,496,729,600' of '429,496,729,600' bytes, '1,661,992,960' of '150,728,605,696' used bytes, object '1' of '1'
[27.12.2022 21:05:08] <29> Info     Task session [d05c684f-b34b-44c9-abe4-6be8f8c2ebb6] has been completed, status: Success, 429,496,729,600 of 429,496,729,600 bytes, 1,661,992,960 of 1,661,992,960 used bytes, 0 of 0 objects, details: 
[27.12.2022 21:05:08] <29> Info     [RemoteTaskManager] Task is finished. MFP01
[27.12.2022 21:05:08] <29> Info     [TaskExecutor] Task MFP01 execution done
[27.12.2022 21:08:13] <46> Info         [ReconnectableSocket][StopCondition] Stop confirmation was received on [0483b923-7f63-49ae-873d-467ac769ea67].
[27.12.2022 21:08:13] <46> Info         [ReconnectableSocket] Stop confirmation was sent on [0483b923-7f63-49ae-873d-467ac769ea67].
[27.12.2022 21:08:13] <46> Info         [ReconnectableSocket][StopCondition] Stop request was received on [0483b923-7f63-49ae-873d-467ac769ea67].
[27.12.2022 21:08:14] <14> Info         [ReconnectableSocket][StopCondition] Stop confirmation was received on [090d6255-4a88-4286-aef5-69183da5c206].
[27.12.2022 21:08:14] <14> Info         [ReconnectableSocket] Stop confirmation was sent on [090d6255-4a88-4286-aef5-69183da5c206].
[27.12.2022 21:08:14] <14> Info         [ReconnectableSocket][StopCondition] Stop request was received on [090d6255-4a88-4286-aef5-69183da5c206].
[27.12.2022 21:08:14] <31> Info             [AP] (3449) command: 'Invoke: Generic.AbortReconnects '
[27.12.2022 21:08:14] <47> Info                   [AP] (3449) output: <VCPCommandResult result="true" exception="" />
[27.12.2022 21:08:14] <47> Info                   [AP] (3449) output: <VCPCommandArgs />
[27.12.2022 21:08:14] <47> Info                   [AP] (3449) output: >

Userlevel 7
Badge +17

You said, you have unplugged and replugged an USB drive.

Did you create a repository backed with rotated drives? Otherwise there will be problems when writing to removable USB drives….

https://helpcenter.veeam.com/docs/backup/vsphere/backup_repository_rotated.html?ver=110

Userlevel 7
Badge +21

You said, you have unplugged and replugged an USB drive.

Did you create a repository backed with rotated drives? Otherwise there will be problems when writing to removable USB drives….

https://helpcenter.veeam.com/docs/backup/vsphere/backup_repository_rotated.html?ver=110

Yes, I was just about to mention that.  Thanks Joe.  The log output is not helpful.

Userlevel 5
Badge +3

Hey @csaha77,

I strongly recommend open a support case to review the logs. It’s very difficulty to walk through the troubleshooting on forums.

From your redacted screenshot, I can still see part of the message, the job failed to start within 900 seconds after the scheduler process issued the command to start.

https://www.veeam.com/kb1909 <=== Ignore this KB, it is for very specific situations and I doubt that it’s related to your issue.

I strongly recommend check the system requirements and ensure that the Veeam server is sized appropriately for the workload and infrastructure roles it plays; this is by far the most common reason why this might appear (typically this happens with All-in-One installations where one server is VBR, Proxy, Repository, and hosting the Configuration Database with far too few resources)

A reboot likely will clear this temporarily, but if it continues, open a support case and include logs by following https://veeam.com/kb1832. Use the 1st radio option, select the affected job, and wait for the log export to complete (You will see a .zip file in C:\temp or whatever folder you point the log export to; if you don’t see the .zip, it’s not done exporting yet)

Userlevel 7
Badge +6

Do you have anti-virus exclusions in place for the drive?

https://www.veeam.com/kb1999

Userlevel 5
Badge +1

Thanks for all the responses. I recompiled Backup repository yesterday and got warning error:

 

Userlevel 7
Badge +21

Thanks for all the responses. I recompiled Backup repository yesterday and got warning error:

 

Glad to hear you addressed the issue.

Userlevel 5
Badge +1

Can I make further configuration changes to prevent warning messages?

Userlevel 7
Badge +21

Can I make further configuration changes to prevent warning messages?

Not these particular ones no.

Userlevel 7
Badge +17

I would say the job has run the first time after some time when it has not run. So, the set RPO in the job is not met and the warning is given.

Should be gone when the RPO is met.

Userlevel 5
Badge +1

Thanks for all advises and help.

Userlevel 7
Badge +21

Thanks for all advises and help.

Not a problem.  Be sure to mark the answer that was most helpful for this problem so others will see it as well.

Userlevel 7
Badge +17

Fine that you got your problem sorted out 😎👍🏼

Comment