Skip to main content

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

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.


This is the Job failure

Did not find any error

Recompiled Backup repository 


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.


[27.12.2022 21:04:59] <06> Info                   AP] (6c42) output: >
g27.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 tE:\Backups\ - Backup USB\ - Backup Job\ - Backup JobD2022-12-27T210308_59A3.vib]. InsidePath: ]dcb00872-3c7d-4a8b-8cd9-f70c71c53974 (vm-190)].
v27.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;m-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;t-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;g-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             9AP] (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}; }'
E27.12.2022 21:04:59] <06> Info                   6AP] (6c42) output: <VCPCommandResult result="true" exception="" />
r27.12.2022 21:05:00] <28> Info                   0AP] (6c42) output: <VCPCommandArgs />
]27.12.2022 21:05:00] <28> Info                   2AP] (6c42) output: >
27.12.2022 21:05:00] <29> Info         &OIB] Updating oib '45b76d5e-7585-4915-9761-9712cc9714e1',set isConsistent = 'True' 
527.12.2022 21:05:00] <29> Info         BackupMeta] Generate backup meta for ' - Backup USB\ - Backup Job:a7a312ac-7a84-4ba3-9473-227997946cb3'
k27.12.2022 21:05:00] <29> Info     4BackupMetaUpdater] Waiting for backup meta to be generated in another thread <18>
27.12.2022 21:05:03] <29> Info         rBackupMeta] Backup meta for ' - Backup USB\ - Backup Job:a7a312ac-7a84-4ba3-9473-227997946cb3' generated
U27.12.2022 21:05:03] <29> Info     2BackupEncryptedStateUpdater] Committing backup encryption state (BackupId='a7a312ac-7a84-4ba3-9473-227997946cb3', HasCryptedStorages=False, current EncryptionState=Unencrypted)
b27.12.2022 21:05:03] <29> Info         iAgentsSession] Disposing...
227.12.2022 21:05:03] <29> Info         eAgentsSession] Removing agents connection
:27.12.2022 21:05:03] <29> Info             eAP] (6c42) disconnecting
[27.12.2022 21:05:03] <07> Info                   [ap] (6c42) output: >
>27.12.2022 21:05:03] <29> Info         Disposing CBackupRepositoryClient t0xca5038]
[27.12.2022 21:05:03] <29> Info             DAP] Disposing client from thread 29
827.12.2022 21:05:03] <29> Info         Disposing BaseAgentProtocol s0x2960773]
t27.12.2022 21:05:03] <29> Info         Disposing CSocketAgentService a0xe98564], sessionId 6c09c]
[27.12.2022 21:05:03] <29> Info         SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id c09c
327.12.2022 21:05:04] <29> Info             ]AP] Disposing client from thread 29
t27.12.2022 21:05:04] <29> Info         Disposing BaseAgentProtocol 0x365e6f4]
o27.12.2022 21:05:04] <29> Info         Disposing CSocketAgentService 0x32a29bd], sessionId ea90a]
[27.12.2022 21:05:04] <29> Info         gSocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id a90a
227.12.2022 21:05:04] <29> Info         Disposing CRepositoryAgent s0x2edbac0], agent id 14b8cad5-0c1c-4939-84cd-d12b078083b2
227.12.2022 21:05:04] <29> Info             iAP] Disposing client from thread 29
n27.12.2022 21:05:04] <29> Info         Disposing BaseAgentProtocol 20x215ab70]
27.12.2022 21:05:04] <29> Info         Disposing CSocketAgentService 0x3e0806], sessionId 62e9]
s27.12.2022 21:05:04] <29> Info         .SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 62e9
e27.12.2022 21:05:05] <29> Info         Disposing CProxyAgent 0x151cb2b], agent id 14b8cad5-0c1c-4939-84cd-d12b078083b2
t27.12.2022 21:05:05] <29> Info         ]AgentMngr] Stopping agent, id '14b8cad5-0c1c-4939-84cd-d12b078083b2'
427.12.2022 21:05:05] <29> Info         2AgentMngr] Sending signal to stop agent, id '14b8cad5-0c1c-4939-84cd-d12b078083b2'. Host: 'bak01.cts.ctscorp.com'.
227.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 94372246] was disposed
t27.12.2022 21:05:05] <29> Info             9AP] Disposing client from thread 29
:27.12.2022 21:05:05] <29> Info         Disposing BaseAgentProtocol d0x2e47b99]
.27.12.2022 21:05:05] <29> Info         Disposing CSocketAgentService r0x1fe6b8d], sessionId 16c42]
527.12.2022 21:05:05] <29> Info         sSocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 6c42
o27.12.2022 21:05:06] <29> Info     sPerBackupSourceAgentMngr] Shared repository client for backup b42e238d-4878-40cc-bc45-4800528de31e released. New reference count is: 0
,27.12.2022 21:05:06] <29> Info     9PerBackupSourceAgentMngr] Disposing referenced client
y27.12.2022 21:05:06] <29> Info         Disposing CBackupRepositoryClient 0x140d5e3]
>27.12.2022 21:05:06] <29> Info             aAP] Disposing client from thread 29
27.12.2022 21:05:06] <29> Info         Disposing BaseAgentProtocol B0x10892b0]
l27.12.2022 21:05:06] <29> Info         Disposing CSocketAgentService 0x10d5592], sessionId tc58d]
d27.12.2022 21:05:06] <29> Info         oSocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id c58d
&27.12.2022 21:05:06] <29> Info             eAP] Disposing client from thread 29
227.12.2022 21:05:06] <29> Info         Disposing BaseAgentProtocol c0x39af5b7]
n27.12.2022 21:05:06] <29> Info         Disposing CSocketAgentService f0x3e23f6], sessionId A51d5]
g27.12.2022 21:05:06] <29> Info         :SocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 51d5
227.12.2022 21:05:07] <29> Info         Disposing CRepositoryAgent x0x15eff55], agent id 43b9484f-35e4-4617-a361-1f887405221c
27.12.2022 21:05:07] <29> Info             nAP] Disposing client from thread 29
i27.12.2022 21:05:07] <29> Info         Disposing BaseAgentProtocol R0x208d451]
027.12.2022 21:05:07] <29> Info         Disposing CSocketAgentService 10x22c5aa9], sessionId o7e13]
27.12.2022 21:05:07] <29> Info         rSocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 7e13
827.12.2022 21:05:07] <29> Info         Disposing CProxyAgent i0x2f6da15], agent id 43b9484f-35e4-4617-a361-1f887405221c
227.12.2022 21:05:07] <29> Info         SAgentMngr] Stopping agent, id '43b9484f-35e4-4617-a361-1f887405221c'
27.12.2022 21:05:07] <29> Info         fAgentMngr] Sending signal to stop agent, id '43b9484f-35e4-4617-a361-1f887405221c'. Host: 'bak01.cts.ctscorp.com'.
]27.12.2022 21:05:07] <29> Info         pAgentMngr] Checking whether agent '43b9484f-35e4-4617-a361-1f887405221c' is alive on host 'bak01.cts.ctscorp.com'.
S27.12.2022 21:05:07] <29> Info         4AgentMngr] Agent has been stopped, id '43b9484f-35e4-4617-a361-1f887405221c'
]27.12.2022 21:05:07] <29> Info     CCProxyRpcInvoker] RpcInvoker 440260728] was disposed
c27.12.2022 21:05:07] <29> Info     'CProxyRpcInvoker] RpcInvoker &41447819] was disposed
A27.12.2022 21:05:07] <29> Info         fStorageLock] Unlocking storage, lockId: 'b3abbe2d-97d2-44ac-a1ec-98e768505ebe'
x27.12.2022 21:05:07] <29> Info         eAPI] DestroyLease.Started, leaseId: g2bf1b0ec-e577-4900-b85a-60beb9c74cf5]
27.12.2022 21:05:07] <29> Info         5API] DestroyLease.Success, leaseId: o2bf1b0ec-e577-4900-b85a-60beb9c74cf5]
e27.12.2022 21:05:07] <29> Info     1CProxyRpcInvoker] RpcInvoker n59279729] was disposed
L27.12.2022 21:05:07] <29> Info         5RepositoryThrottler] 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
d27.12.2022 21:05:07] <29> Info             oAP] Disposing client from thread 29
n27.12.2022 21:05:07] <29> Info         Disposing BaseAgentProtocol 20x2b3849b]
a27.12.2022 21:05:07] <29> Info         Disposing CSocketAgentService 10x2d9ad65], sessionId 773c0]
27.12.2022 21:05:07] <29> Info         nSocketAgentService] Closing connection to agent 'bak01.cts.ctscorp.com', id 73c0
l27.12.2022 21:05:07] <29> Info         PReconnectableSocket] Stop request was sent on eb71393d-75c9-4427-89af-d9ff264a4d17].
t27.12.2022 21:05:08] <41> Info         ReconnectableSocket]0StopCondition] Stop confirmation was received on eeb71393d-75c9-4427-89af-d9ff264a4d17].
c27.12.2022 21:05:08] <41> Info         &ReconnectableSocket] Stop confirmation was sent on oeb71393d-75c9-4427-89af-d9ff264a4d17].
a27.12.2022 21:05:08] <41> Info         lReconnectableSocket]StopCondition] Stop request was received on oeb71393d-75c9-4427-89af-d9ff264a4d17].
927.12.2022 21:05:08] <29> Info     2RemoteBackupTaskPerformer] Completing task session MFP01 (moreRetries: False)
s27.12.2022 21:05:08] <29> Info     Busy: Source 41% > Proxy 15% > Network 42% > Target 61%
t27.12.2022 21:05:08] <29> Info     Primary bottleneck: Target
227.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 rd05c684f-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: 
027.12.2022 21:05:08] <29> Info     ,RemoteTaskManager] Task is finished. MFP01
27.12.2022 21:05:08] <29> Info     gTaskExecutor] Task MFP01 execution done
927.12.2022 21:08:13] <46> Info         sReconnectableSocket]2StopCondition] Stop confirmation was received on 10483b923-7f63-49ae-873d-467ac769ea67].
a27.12.2022 21:08:13] <46> Info         IReconnectableSocket] Stop confirmation was sent on 00483b923-7f63-49ae-873d-467ac769ea67].
27.12.2022 21:08:13] <46> Info         eReconnectableSocket]:StopCondition] Stop request was received on t0483b923-7f63-49ae-873d-467ac769ea67].
27.12.2022 21:08:14] <14> Info         cReconnectableSocket]2StopCondition] Stop confirmation was received on n090d6255-4a88-4286-aef5-69183da5c206].
n27.12.2022 21:08:14] <14> Info         >ReconnectableSocket] Stop confirmation was sent on R090d6255-4a88-4286-aef5-69183da5c206].
e27.12.2022 21:08:14] <14> Info         6ReconnectableSocket]7StopCondition] Stop request was received on 090d6255-4a88-4286-aef5-69183da5c206].
c27.12.2022 21:08:14] <31> Info             eAP] (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                   tAP] (3449) output: <VCPCommandArgs />
427.12.2022 21:08:14] <47> Info                   3AP] (3449) output: >


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


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.


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)


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

https://www.veeam.com/kb1999


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

 


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

 

Glad to hear you addressed the issue.


Can I make further configuration changes to prevent warning messages?


Can I make further configuration changes to prevent warning messages?

Not these particular ones no.


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.


Thanks for all advises and help.


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.


Fine that you got your problem sorted out 😎👍🏼


Comment