Failed XCP-NG Restores

[czopper]

Tried to restore 2 VMs and both failed to restore. logs... first job errored out 2nd job ran more than 2 hours...

[22.03.2026 09:10:37.581] <217> Info (3) General Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:37.698] <210> Info (3) General Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:37.699] <210> Info (3) VMB Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:38.253] <237> Info (3) [LinuxBackupServerWorker] Command: lease.createLease
[22.03.2026 09:10:38.253] <237> Info (3) {(EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EInt32) TtlSecs=1800; (EDateTime) _currentTime=2026-03-22 09:10:38; (EDateTime) currentTimeUtc=2026-03-22 13:10:38; }
[22.03.2026 09:10:38.253] <237> Info (3) [API] Creating new lease. TTL: [00:30:00], SessionId: [d00bd517-42e3-4ee8-8237-7f3a5ff36989]
[22.03.2026 09:10:38.256] <237> Info (3) Session [d00bd517-42e3-4ee8-8237-7f3a5ff36989] was not exists when lease [79a5a09b-8f9f-49bf-bd08-24056c13dbf7] is creating
[22.03.2026 09:10:38.256] <237> Info (3) [API] New lease was created. Id: [79a5a09b-8f9f-49bf-bd08-24056c13dbf7], TTL: [00:30:00]
[22.03.2026 09:10:38.256] <237> Info (3) [LinuxBackupServerWorker] Result: {(EGuid) LeaseId={79a5a09b-8f9f-49bf-bd08-24056c13dbf7}; }
[22.03.2026 09:10:38.293] <339> Info (3) [LinuxBackupServerWorker] Command: job.StartRestoreSession
[22.03.2026 09:10:38.293] <339> Info (3) {(EGuid) JobId={fbbf5f14-c12f-422e-a6e6-d8852da7e300}; (EString) JobName=Restore LSORDS30-NGD; (EGuid) OibId={325c9186-40ac-4626-a87c-98e1d55ff3e3}; (EString) Reason=<empty>; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EDateTime) _currentTime=2026-03-22 09:10:38; (EDateTime) currentTimeUtc=2026-03-22 13:10:38; }
[22.03.2026 09:10:38.300] <339> Info (3) Creating job process lock. ShortName is 'fbbf5f14-c12f-422e-a6e6-d8852da7e300'
[22.03.2026 09:10:38.300] <339> Info (3) [ForeignJobs] Started new restore session 'd00bd517-42e3-4ee8-8237-7f3a5ff36989:EndpointRestore' for job 'fbbf5f14-c12f-422e-a6e6-d8852da7e300:Restore LSORDS30-NGD'
[22.03.2026 09:10:38.310] <339> Info (3) [LinuxBackupServerWorker] Result:
[22.03.2026 09:10:38.348] <150> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:10:38.348] <150> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:38; (EDateTime) currentTimeUtc=2026-03-22 13:10:38; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:10:38.349] <150> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:10:38.612] <252> Info (3) General Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:38.614] <252> Info (3) VMB Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:38.651] <242> Info (3) [LinuxBackupServerWorker] Command: agent.start
[22.03.2026 09:10:38.651] <242> Info (3) {(EString) Determinant=<LinuxStorageDeterminant UniqueId="{e86cc4d6-4690-4d99-a72b-f796700e79e5}" MainStorageId="{68d1576d-f3c1-44d9-82b4-72536c9b3914}" Description="Restore" BackupId="{935cc30e-0f24-49a5-8c9a-f7f4151fbf63}"><StorageRequestItem Id="{7e6a0b27-4ef5-4067-ae6f-e1cd4eb87b9f}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{efbff675-433f-4da1-aba9-b7e14bcab53a}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{4ebe8c9e-34a1-4a48-ac5b-bf518b42428d}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{4a68ca50-d40d-4949-afd7-51764b576ef5}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{68d1576d-f3c1-44d9-82b4-72536c9b3914}" Type="0" State="2" IsSlotNeeded="false" Access="1" /></LinuxStorageDeterminant>; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EDateTime) _currentTime=2026-03-22 09:10:38; (EDateTime) currentTimeUtc=2026-03-22 13:10:38; (EGuid) jobSessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) leaseId={79a5a09b-8f9f-49bf-bd08-24056c13dbf7}; (EString) logPath=Agent.Repository.log; }
[22.03.2026 09:10:38.651] <242> Info (3) [sharedAgentIdentifier] Creating identifier from jobId or backupId [00000000-0000-0000-0000-000000000000]
[22.03.2026 09:10:38.659] <242> Info (3) General Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:38.664] <242> Info (3) [CProxyAgentStarter] Create Proxy transport agent manager.
[22.03.2026 09:10:38.670] <242> Info (3) Product version is 12.3.2.36028, Transport version installed on server Veeam.HVdns.com is 12.3.2.36028.
[22.03.2026 09:10:38.720] <242> Info (3) [TransportService] Connecting to transport service, host 'Veeam.HVdns.com', addresses ['10.0.0.200'], port '6162'.
[22.03.2026 09:10:38.720] <242> Info (3) [SNetworkAddressResolver] Resolved ['Veeam.HVdns.com', '10.0.0.200'] by NTLM strategy IP addresses and host names. IPAddressKind: [All]. Result: ['10.0.0.200'].
[22.03.2026 09:10:38.722] <242> Info (3) [CProxyRpcInvoker] RpcInvoker [25256935] has been created. Host: [10.0.0.200:6162]
[22.03.2026 09:10:38.723] <242> Info (3) [ProxyAgent] Preparing to start CProxyAgent. Agent starter 'CProxyAgentStarter', agent manager 'CTransportSvcAgentManager'.
[22.03.2026 09:10:38.724] <242> Info (3) [CTransportSvcAgentManager] Agent connection addresses before filtering: ['10.0.0.200'].
[22.03.2026 09:10:38.724] <242> Info (3) [CTransportSvcAgentManager] Starting agent with normal priority, Host 'Veeam.HVdns.com', logName: 'Endpoint\Restore\Proxy251ee2cb-b47b-45d1-bb89-04a7b4a7b4ba\Veeam\Restore\Agent.Repository.log'. IPs: '10.0.0.200', is x64 agent preferred: 'True'.
[22.03.2026 09:10:38.725] <242> Info (3) Generating certificate. FriendlyName: [Manager to Agent channel encryption], Subject: [CN=Veeam Data Mover certificate], ValidFrom: [3/21/2026 9:10:38 AM], ValidTo: [4/21/2026 9:10:38 AM]
[22.03.2026 09:10:38.783] <242> Info (3) Generated new agent group id: 1cc936e6-1bf1-4f4e-b739-813b77f4920e
[22.03.2026 09:10:38.980] <242> Info (3) [CTransportSvcAgentManager] Attaching windows FLR agent to restore session's lease. AgentId: [8f62a064-df6e-465c-ac2e-7f467be09190], SessionId: [d00bd517-42e3-4ee8-8237-7f3a5ff36989], LeaseId: [00000000-0000-0000-0000-000000000000]
[22.03.2026 09:10:38.982] <242> Info (3) [CTransportSvcAgentManager] Agent has been started, ID '8f62a064-df6e-465c-ac2e-7f467be09190', addresses '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:38.982] <242> Info (3) [ProxyAgent] Starting CProxyAgent. Agent ID '8f62a064-df6e-465c-ac2e-7f467be09190', HostId: 6745a759-2205-4cd2-b172-8ec8f7e60ef8, agent endpoint 'Veeam.HVdns.com' (IPs: ['10.0.0.200:2501'])
[22.03.2026 09:10:38.983] <242> Info (3) [ProxyAgent] Starting client agent session, id '8284b9bb-1373-4480-a145-f688607be13e', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:38.983] <242> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:38.983] <242> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:38.999] <242> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.007] <296> Info (4) [AP] (8284b9bb) output: >
[22.03.2026 09:10:39.007] <242> Info (4) [AP] (8284b9bb) state: ready
[22.03.2026 09:10:39.007] <242> Info (3) [AgentReconnectsManager] Starting remote agent control session.
[22.03.2026 09:10:39.007] <242> Info (3) [AP] (8284b9bb) command: 'Invoke: Generic.SetNetworkReconnectOptions { (EBoolean) Enabled = true; (EUInt64) AttemptIntervalMs = 15000; (EUInt64) OverallTimeoutMs = 1800000; }'
[22.03.2026 09:10:39.008] <142> Info (4) [AP] (8284b9bb) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.008] <217> Info (4) [AP] (8284b9bb) output: <VCPCommandArgs />
[22.03.2026 09:10:39.008] <210> Info (4) [AP] (8284b9bb) output: >
[22.03.2026 09:10:39.009] <242> Info (3) [AsyncReconnectPolicy] Enable remote agent session reconnects..., agentId: 8f62a064-df6e-465c-ac2e-7f467be09190
[22.03.2026 09:10:39.009] <242> Info (3) [AP] (8284b9bb) command: 'Invoke: Generic.EnableAgentReconnectPolicy { (EBoolean) IsRemoteAgent = true; }'
[22.03.2026 09:10:39.009] <339> Info (4) [AP] (8284b9bb) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.009] <339> Info (4) [AP] (8284b9bb) output: <VCPCommandArgs />
[22.03.2026 09:10:39.010] <339> Info (4) [AP] (8284b9bb) output: >
[22.03.2026 09:10:39.010] <242> Info (3) [ProxyAgent] Starting client agent session, id '3f8d409e-ce56-41d9-a3fe-f0efaab09171', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:39.010] <242> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:39.010] <242> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.011] <242> Info (3) [ReconnectableSessionStarter] Creating reconnectable socket with options: [enabled: timeout 1800000, interval 15000]. Agent on [10.0.0.200:2501]. Connection id: a06b924b-fff6-408f-b12c-c35c635b4620.
[22.03.2026 09:10:39.027] <242> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.039] <242> Info (4) [AP] (3f8d409e) state: ready
[22.03.2026 09:10:39.039] <217> Info (4) [AP] (3f8d409e) output: >
[22.03.2026 09:10:39.039] <242> Info (3) [ProxyAgent] Starting client agent session, id '96d4efa9-c235-4838-8e3a-494f049b0c86', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:39.040] <242> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:39.040] <242> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.040] <242> Info (3) [ReconnectableSessionStarter] Creating reconnectable socket with options: [enabled: timeout 1800000, interval 15000]. Agent on [10.0.0.200:2501]. Connection id: c1ba0f50-6082-437c-ae23-208a6b5dd39e.
[22.03.2026 09:10:39.059] <242> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.070] <264> Info (4) [AP] (96d4efa9) output: >
[22.03.2026 09:10:39.070] <242> Info (4) [AP] (96d4efa9) state: ready
[22.03.2026 09:10:39.070] <242> Info (3) [StorageRequestItem] Id: 7e6a0b27-4ef5-4067-ae6f-e1cd4eb87b9f
[22.03.2026 09:10:39.070] <242> Info (3) [StorageRequestItem] Id: efbff675-433f-4da1-aba9-b7e14bcab53a
[22.03.2026 09:10:39.070] <242> Info (3) [StorageRequestItem] Id: 4ebe8c9e-34a1-4a48-ac5b-bf518b42428d
[22.03.2026 09:10:39.070] <242> Info (3) [StorageRequestItem] Id: 4a68ca50-d40d-4949-afd7-51764b576ef5
[22.03.2026 09:10:39.070] <242> Info (3) [StorageRequestItem] Id: 68d1576d-f3c1-44d9-82b4-72536c9b3914
[22.03.2026 09:10:39.074] <242> Info (3) [ProxyAgent] Starting client agent session, id '08a38f1b-f638-45fe-adf4-4f450eae5fd5', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:39.078] <242> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:39.078] <242> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.079] <242> Info (3) [ReconnectableSessionStarter] Creating reconnectable socket with options: [enabled: timeout 1800000, interval 15000]. Agent on [10.0.0.200:2501]. Connection id: 1840dcf7-c4c7-4337-bead-1ba3f70d33af.
[22.03.2026 09:10:39.096] <242> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.105] <252> Info (4) [AP] (08a38f1b) output: >
[22.03.2026 09:10:39.105] <242> Info (4) [AP] (08a38f1b) state: ready
[22.03.2026 09:10:39.106] <242> Info (3) [AP] (08a38f1b) command: 'Invoke: Permissions.Elevate2PrivilegedMode { }'
[22.03.2026 09:10:39.106] <150> Info (4) [AP] (08a38f1b) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.106] <150> Info (4) [AP] (08a38f1b) output: <VCPCommandArgs />
[22.03.2026 09:10:39.106] <339> Info (4) [AP] (08a38f1b) output: >
[22.03.2026 09:10:39.106] <242> Info (3) Creating file commander for the host 'Veeam.HVdns.com', protocol 'RemoteWin'
[22.03.2026 09:10:39.157] <242> Info (3) [CProxyRpcInvoker] RpcInvoker [63979514] has been created. Host: [10.0.0.200:6160]
[22.03.2026 09:10:39.158] <242> Info (3) Create storage commander for repository 88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec.
[22.03.2026 09:10:39.159] <242> Info (3) [CStorageCommanderFactoryImpl] Create storage commander for repository [id=88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec:name=Default Backup Repository E:host_id=6745a759-2205-4cd2-b172-8ec8f7e60ef8:mount_host_id=6745a759-2205-4cd2-b172-8ec8f7e60ef8:path=E:|Backup:useNfsOnMountHost=True:typeWinLocal]
[22.03.2026 09:10:39.159] <242> Info (3) Use backup base access path
[22.03.2026 09:10:39.159] <242> Info (3) Initialize root path: E:\Backup
[22.03.2026 09:10:39.159] <242> Info (3) [AP] (08a38f1b) command: 'Invoke: Stg.SetRoot { (EString) StgRootPath = E:\Backup; }'
[22.03.2026 09:10:39.159] <142> Info (4) [AP] (08a38f1b) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.159] <142> Info (4) [AP] (08a38f1b) output: <VCPCommandArgs />
[22.03.2026 09:10:39.159] <150> Info (4) [AP] (08a38f1b) output: >
[22.03.2026 09:10:39.160] <242> Info (3) [AP] (08a38f1b) command: 'Invoke: FileSystem.SetRelativePathRoot { (EString) PathRoot = E:\Backup; }'
[22.03.2026 09:10:39.160] <339> Info (4) [AP] (08a38f1b) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.160] <237> Info (4) [AP] (08a38f1b) output: <VCPCommandArgs />
[22.03.2026 09:10:39.160] <237> Info (4) [AP] (08a38f1b) output: >
[22.03.2026 09:10:39.160] <242> Info (3) Initialize access path: E:\Backup
[22.03.2026 09:10:39.161] <242> Info (3) [AP] (08a38f1b) command: 'Invoke: Permissions.GrantAccess2Path { (EString) Path = E:\Backup\LSORDS30-NGD Backup; (EBoolean) Recursive = true; }'
[22.03.2026 09:10:39.161] <217> Info (4) [AP] (08a38f1b) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.161] <150> Info (4) [AP] (08a38f1b) output: <VCPCommandArgs />
[22.03.2026 09:10:39.161] <150> Info (4) [AP] (08a38f1b) output: >
[22.03.2026 09:10:39.161] <242> Info (3) [AP] (08a38f1b) command: 'Invoke: Permissions.IncreaseStgSizeLimit { (EUInt64) AdditionalSize = 18446744073709551615; }'
[22.03.2026 09:10:39.162] <237> Info (4) [AP] (08a38f1b) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.162] <217> Info (4) [AP] (08a38f1b) output: <VCPCommandArgs />
[22.03.2026 09:10:39.162] <217> Info (4) [AP] (08a38f1b) output: >
[22.03.2026 09:10:39.163] <242> Info (3) [AP] Disposing client from thread 242
[22.03.2026 09:10:39.163] <242> Info (3) Disposing BaseAgentProtocol [0x33d3189]
[22.03.2026 09:10:39.163] <242> Info (3) Disposing CSocketAgentService [0x395462e], sessionId [08a38f1b]
[22.03.2026 09:10:39.163] <242> Info (3) [SocketAgentService] Closing connection to agent 'Veeam.HVdns.com', id 08a38f1b
[22.03.2026 09:10:39.163] <242> Info (3) [ReconnectableSocket] Stop request was sent on [1840dcf7-c4c7-4337-bead-1ba3f70d33af].
[22.03.2026 09:10:39.163] <113> Info (3) [ReconnectableSocket][StopCondition] Stop confirmation was received on [1840dcf7-c4c7-4337-bead-1ba3f70d33af].
[22.03.2026 09:10:39.163] <286> Info (3) [ReconnectableSocket] Stop confirmation was sent on [1840dcf7-c4c7-4337-bead-1ba3f70d33af].
[22.03.2026 09:10:39.163] <286> Info (3) [ReconnectableSocket][StopCondition] Stop request was received on [1840dcf7-c4c7-4337-bead-1ba3f70d33af].
[22.03.2026 09:10:39.165] <242> Info (3) [ProxyAgent] Starting client agent session, id '1a61ecfe-e395-4f92-a38f-301b0e2d4778', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:39.165] <242> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:39.165] <242> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.166] <242> Info (3) [ReconnectableSessionStarter] Creating reconnectable socket with options: [enabled: timeout 1800000, interval 15000]. Agent on [10.0.0.200:2501]. Connection id: ed2f5ed2-74ea-4cff-8367-135af252044a.
[22.03.2026 09:10:39.183] <242> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:39.193] <142> Info (4) [AP] (1a61ecfe) output: >
[22.03.2026 09:10:39.193] <242> Info (4) [AP] (1a61ecfe) state: ready
[22.03.2026 09:10:39.194] <242> Info (3) [AP] (1a61ecfe) command: 'Invoke: Permissions.Elevate2PrivilegedMode { }'
[22.03.2026 09:10:39.194] <339> Info (4) [AP] (1a61ecfe) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:39.194] <252> Info (4) [AP] (1a61ecfe) output: <VCPCommandArgs />
[22.03.2026 09:10:39.194] <264> Info (4) [AP] (1a61ecfe) output: >
[22.03.2026 09:10:39.194] <242> Info (3) Setting repository 'Default Backup Repository E' ('88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec') credentials for backup client.
[22.03.2026 09:10:39.196] <242> Info (3) [AP] Disposing client from thread 242
[22.03.2026 09:10:39.196] <242> Info (3) Disposing BaseAgentProtocol [0x1f10f16]
[22.03.2026 09:10:39.196] <242> Info (3) Disposing CSocketAgentService [0x136581a], sessionId [1a61ecfe]
[22.03.2026 09:10:39.196] <242> Info (3) [SocketAgentService] Closing connection to agent 'Veeam.HVdns.com', id 1a61ecfe
[22.03.2026 09:10:39.196] <242> Info (3) [ReconnectableSocket] Stop request was sent on [ed2f5ed2-74ea-4cff-8367-135af252044a].
[22.03.2026 09:10:39.196] <113> Info (3) [ReconnectableSocket][StopCondition] Stop confirmation was received on [ed2f5ed2-74ea-4cff-8367-135af252044a].
[22.03.2026 09:10:39.196] <123> Info (3) [ReconnectableSocket] Stop confirmation was sent on [ed2f5ed2-74ea-4cff-8367-135af252044a].
[22.03.2026 09:10:39.196] <123> Info (3) [ReconnectableSocket][StopCondition] Stop request was received on [ed2f5ed2-74ea-4cff-8367-135af252044a].
[22.03.2026 09:10:39.198] <242> Info (3) [LinuxBackupServerWorker] Result: {(EGuid) agentUid={8f62a064-df6e-465c-ac2e-7f467be09190}; (EString) agentEndpoints=10.0.0.200:2501; }
[22.03.2026 09:10:41.512] <150> Info (3) [LinuxBackupServerWorker] Command: network.isTrafficEncrypted
[22.03.2026 09:10:41.512] <150> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:41; (EDateTime) currentTimeUtc=2026-03-22 13:10:41; (EStringArray) sourceIps=[127.0.0.1,10.0.0.207,::1](EStringArray) targetIps=[10.0.0.200]}
[22.03.2026 09:10:41.513] <150> Info (3) [LinuxBackupServerWorker] Result: {(EBoolean) isEncrypted=false; }
[22.03.2026 09:10:41.550] <237> Info (3) [LinuxBackupServerWorker] Command: network.getConnectionsCount
[22.03.2026 09:10:41.550] <237> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:41; (EDateTime) currentTimeUtc=2026-03-22 13:10:41; }
[22.03.2026 09:10:41.550] <237> Info (3) [LinuxBackupServerWorker] Result: {(EInt32) connectionsCount=5; }
[22.03.2026 09:10:41.607] <252> Info (3) [LinuxBackupServerWorker] Command: agent.startServer
[22.03.2026 09:10:41.607] <252> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:41; (EGuid) agentUid={8f62a064-df6e-465c-ac2e-7f467be09190}; (EDateTime) currentTimeUtc=2026-03-22 13:10:41; }
[22.03.2026 09:10:41.607] <252> Info (3) [ProxyAgent] Starting client agent session, id 'b741adc2-c673-4a5f-9279-56cee992c8bb', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:41.608] <252> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:41.608] <252> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:41.609] <252> Info (3) [ReconnectableSessionStarter] Creating reconnectable socket with options: [enabled: timeout 1800000, interval 15000]. Agent on [10.0.0.200:2501]. Connection id: 9bcd2e26-61fa-42a8-a0c7-0521ff48db49.
[22.03.2026 09:10:41.626] <252> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:41.635] <210> Info (4) [AP] (b741adc2) output: >
[22.03.2026 09:10:41.635] <252> Info (4) [AP] (b741adc2) state: ready
[22.03.2026 09:10:41.635] <252> Info (3) [AP] (b741adc2) command: 'Invoke: Permissions.Elevate2PrivilegedMode { }'
[22.03.2026 09:10:41.636] <142> Info (4) [AP] (b741adc2) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:41.636] <242> Info (4) [AP] (b741adc2) output: <VCPCommandArgs />
[22.03.2026 09:10:41.636] <242> Info (4) [AP] (b741adc2) output: >
[22.03.2026 09:10:41.636] <252> Info (3) [AP] (b741adc2) command: 'startServer\nd7069daa-a9f8-4f40-aa7c-0480cf8df7fb\n4ce1b01c-d6f2-430b-bf6c-332dd83be721\n'
[22.03.2026 09:10:41.637] <210> Info (4) [AP] (b741adc2) output: 2501
[22.03.2026 09:10:41.637] <210> Info (4) [AP] (b741adc2) output: >
[22.03.2026 09:10:41.637] <252> Info (3) [AP] Disposing client from thread 252
[22.03.2026 09:10:41.637] <252> Info (3) Disposing BaseAgentProtocol [0x3402b6c]
[22.03.2026 09:10:41.637] <252> Info (3) Disposing CSocketAgentService [0xebd5c2], sessionId [b741adc2]
[22.03.2026 09:10:41.637] <252> Info (3) [SocketAgentService] Closing connection to agent 'Veeam.HVdns.com', id b741adc2
[22.03.2026 09:10:41.637] <252> Info (3) [ReconnectableSocket] Stop request was sent on [9bcd2e26-61fa-42a8-a0c7-0521ff48db49].
[22.03.2026 09:10:41.637] <375> Info (3) [ReconnectableSocket][StopCondition] Stop confirmation was received on [9bcd2e26-61fa-42a8-a0c7-0521ff48db49].
[22.03.2026 09:10:41.637] <124> Info (3) [ReconnectableSocket] Stop confirmation was sent on [9bcd2e26-61fa-42a8-a0c7-0521ff48db49].
[22.03.2026 09:10:41.638] <124> Info (3) [ReconnectableSocket][StopCondition] Stop request was received on [9bcd2e26-61fa-42a8-a0c7-0521ff48db49].
[22.03.2026 09:10:41.645] <252> Info (3) [LinuxBackupServerWorker] Result: {(EString) login=d7069daa-a9f8-4f40-aa7c-0480cf8df7fb; (EString) pwd=4ce1b01c-d6f2-430b-bf6c-332dd83be721; (EBoolean) crcEnabled=false; (EGuid) gateConnId={00000000-0000-0000-0000-000000000000}; (EString) endpoints=10.0.0.200:2501;Veeam.HVdns.com:2501; }
[22.03.2026 09:10:43.574] <210> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:10:43.574] <210> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:44; (EDateTime) currentTimeUtc=2026-03-22 13:10:44; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:10:43.617] <210> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:10:44.451] <210> Info (3) General Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:44.457] <210> Info (3) VMB Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:44.533] <142> Info (3) General Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:44.535] <142> Info (3) VMB Repository Default Backup Repository E(88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec) support check result: True(OK)
[22.03.2026 09:10:44.572] <296> Info (3) [LinuxBackupServerWorker] Command: resourceScheduler.notifyJobStarted
[22.03.2026 09:10:44.572] <296> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:44; (EDateTime) currentTimeUtc=2026-03-22 13:10:44; (EGuid) jobSessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:10:44.583] <296> Info (3) [LinuxBackupServerWorker] Result:
[22.03.2026 09:10:44.619] <252> Info (3) [LinuxBackupServerWorker] Command: resourceScheduler.pushRepositoryRequest
[22.03.2026 09:10:44.619] <252> Info (3) {(EString) Determinant=<LinuxStorageDeterminant UniqueId="{b3a140c3-8994-4842-b055-2f52324cfac2}" MainStorageId="{68d1576d-f3c1-44d9-82b4-72536c9b3914}" Description="Restore" BackupId="{935cc30e-0f24-49a5-8c9a-f7f4151fbf63}"><StorageRequestItem Id="{7e6a0b27-4ef5-4067-ae6f-e1cd4eb87b9f}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{efbff675-433f-4da1-aba9-b7e14bcab53a}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{4ebe8c9e-34a1-4a48-ac5b-bf518b42428d}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{4a68ca50-d40d-4949-afd7-51764b576ef5}" Type="2" State="2" IsSlotNeeded="false" Access="1" /><StorageRequestItem Id="{68d1576d-f3c1-44d9-82b4-72536c9b3914}" Type="0" State="2" IsSlotNeeded="false" Access="1" /></LinuxStorageDeterminant>; (EDateTime) _currentTime=2026-03-22 09:10:44; (EDateTime) currentTimeUtc=2026-03-22 13:10:44; (EString) intendedOperation=restore; (EString) jobName=LSORDS30-NGD Backup; (EGuid) jobSessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EString) priority=100; (EGuid) repositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EDateTime) sessionStartTimeUtc=2026-03-22 13:10:37; (EBoolean) shouldCorrectSize=true; (EString) taskGroupType=Restore; (EString) vmId=4b777370-aef4-f7cb-88b1-2a63c4cf7c8d; (EInt64) vmUsedSize=0; }
[22.03.2026 09:10:44.626] <252> Info (3) Use crutch for resource task id 76d45d02-b9d7-42c9-b57f-29f5beba3ab7
[22.03.2026 09:10:44.626] <252> Info (3) [LinuxBackupServerWorker] Result: {(EGuid) resourceRequestId={76d45d02-b9d7-42c9-b57f-29f5beba3ab7}; }
[22.03.2026 09:10:44.662] <339> Info (3) [LinuxBackupServerWorker] Command: resourceScheduler.tryGetResponse
[22.03.2026 09:10:44.662] <339> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:45; (EDateTime) currentTimeUtc=2026-03-22 13:10:45; (EGuid) jobSessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) resourceRequestId={76d45d02-b9d7-42c9-b57f-29f5beba3ab7}; }
[22.03.2026 09:10:44.662] <339> Info (3) [LinuxBackupServerWorker] Result: {(EString) response=PleaseWait; }
[22.03.2026 09:10:46.699] <264> Info (3) [LinuxBackupServerWorker] Command: resourceScheduler.tryGetResponse
[22.03.2026 09:10:46.699] <264> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:47; (EDateTime) currentTimeUtc=2026-03-22 13:10:47; (EGuid) jobSessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) resourceRequestId={76d45d02-b9d7-42c9-b57f-29f5beba3ab7}; }
[22.03.2026 09:10:46.699] <264> Info (3) [LinuxBackupServerWorker] Result: {(EString) response=Ready; }
[22.03.2026 09:10:46.736] <217> Info (3) [LinuxBackupServerWorker] Command: resourceScheduler.notifyResourceInUse
[22.03.2026 09:10:46.736] <217> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:47; (EDateTime) currentTimeUtc=2026-03-22 13:10:47; (EGuid) jobSessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) resourceRequestId={76d45d02-b9d7-42c9-b57f-29f5beba3ab7}; }
[22.03.2026 09:10:46.736] <217> Info (3) [LinuxBackupServerWorker] Result:
[22.03.2026 09:10:47.174] <339> Info (3) [LinuxBackupServerWorker] Command: repository.prepareLockResolver
[22.03.2026 09:10:47.174] <339> Info (3) {(EString) LockType=Read; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuidArray) StorageIds=[{7e6a0b27-4ef5-4067-ae6f-e1cd4eb87b9f},{efbff675-433f-4da1-aba9-b7e14bcab53a},{4ebe8c9e-34a1-4a48-ac5b-bf518b42428d},{4a68ca50-d40d-4949-afd7-51764b576ef5},{68d1576d-f3c1-44d9-82b4-72536c9b3914}](EDateTime) _currentTime=2026-03-22 09:10:47; (EDateTime) currentTimeUtc=2026-03-22 13:10:47; }
[22.03.2026 09:10:47.174] <339> Info (3) [LinuxBackupServerWorker] Result: {(EGuid) ResolverId={3b395624-2aed-4750-a5f2-da32f1327c5e}; }
[22.03.2026 09:10:47.463] <296> Info (3) [LinuxBackupServerWorker] Command: repository.resolveAndLockStorages
[22.03.2026 09:10:47.463] <296> Info (3) {(EGuid) LeaseId={79a5a09b-8f9f-49bf-bd08-24056c13dbf7}; (EGuid) ResolverId={3b395624-2aed-4750-a5f2-da32f1327c5e}; (EDateTime) _currentTime=2026-03-22 09:10:47; (EDateTime) currentTimeUtc=2026-03-22 13:10:47; }
[22.03.2026 09:10:47.463] <296> Info (3) [LockResolver] Getting conflicting sessions
[22.03.2026 09:10:47.477] <296> Info (3) [LockResolver] No conflicting sessions found
[22.03.2026 09:10:47.544] <296> Info (3) [LinuxBackupServerWorker] Result: {(EGuid) LockId={54a7e777-03a8-466b-a733-7e66cdb6f87f}; }
[22.03.2026 09:10:47.838] <237> Info (3) [LinuxBackupServerWorker] Command: repository.findOibChainCryptoMetaKeys
[22.03.2026 09:10:47.838] <237> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:48; (EDateTime) currentTimeUtc=2026-03-22 13:10:48; (EGuid) oibId={325c9186-40ac-4626-a87c-98e1d55ff3e3}; }
[22.03.2026 09:10:47.885] <237> Info (3) [LinuxBackupServerWorker] Result: {(EBinaryBlob) keySetsBlob={EBinaryBlob: 8 bytes}; }
[22.03.2026 09:10:47.921] <150> Info (3) [LinuxBackupServerWorker] Command: repository.findOibChainCryptoStorageKeys
[22.03.2026 09:10:47.921] <150> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:48; (EDateTime) currentTimeUtc=2026-03-22 13:10:48; (EGuid) oibId={325c9186-40ac-4626-a87c-98e1d55ff3e3}; }
[22.03.2026 09:10:47.963] <150> Info (3) [LinuxBackupServerWorker] Result: {(EBinaryBlob) keySetsBlob={EBinaryBlob: 8 bytes}; }
[22.03.2026 09:10:48.085] <142> Info (3) [LinuxBackupServerWorker] Command: repository.getStorageAgentLink
[22.03.2026 09:10:48.085] <142> Info (3) {(EGuid) AgentId={8f62a064-df6e-465c-ac2e-7f467be09190}; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) StorageId={7e6a0b27-4ef5-4067-ae6f-e1cd4eb87b9f}; (EDateTime) _currentTime=2026-03-22 09:10:48; (EDateTime) currentTimeUtc=2026-03-22 13:10:48; }
[22.03.2026 09:10:48.098] <142> Info (3) Create storage commander for backup 935cc30e-0f24-49a5-8c9a-f7f4151fbf63.
[22.03.2026 09:10:48.102] <142> Info (3) [LinuxBackupServerWorker] Result: {(EString) StorageAgentLink=RelativePath://LSORDS30-NGD Backup\LSORDS30-NGD Backup_2026-03-15T230415.vib; }
[22.03.2026 09:10:50.651] <339> Info (3) [LinuxBackupServerWorker] Command: network.isTrafficEncrypted
[22.03.2026 09:10:50.651] <339> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:50; (EDateTime) currentTimeUtc=2026-03-22 13:10:50; (EStringArray) sourceIps=[127.0.0.1,10.0.0.207,::1](EStringArray) targetIps=[10.0.0.200]}
[22.03.2026 09:10:50.652] <339> Info (3) [LinuxBackupServerWorker] Result: {(EBoolean) isEncrypted=false; }
[22.03.2026 09:10:50.688] <252> Info (3) [LinuxBackupServerWorker] Command: agent.startServer
[22.03.2026 09:10:50.688] <252> Info (3) {(EDateTime) _currentTime=2026-03-22 09:10:51; (EGuid) agentUid={8f62a064-df6e-465c-ac2e-7f467be09190}; (EDateTime) currentTimeUtc=2026-03-22 13:10:51; }
[22.03.2026 09:10:50.688] <252> Info (3) [ProxyAgent] Starting client agent session, id '2de31307-1da2-46f0-bdba-f14fb6177ffe', host 'Veeam.HVdns.com', agent id '8f62a064-df6e-465c-ac2e-7f467be09190', IPs '10.0.0.200:2501', PID '1456'
[22.03.2026 09:10:50.689] <252> Info (3) [SocketAgentService] Connecting to agent 'Veeam.HVdns.com' ('10.0.0.200:2501')
[22.03.2026 09:10:50.689] <252> Info (3) [NetSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:50.690] <252> Info (3) [ReconnectableSessionStarter] Creating reconnectable socket with options: [enabled: timeout 1800000, interval 15000]. Agent on [10.0.0.200:2501]. Connection id: 82efb441-c211-4136-a44d-fe2c41ec8553.
[22.03.2026 09:10:50.705] <252> Info (3) [TlsSocket] Connecting to '10.0.0.200:2501'.
[22.03.2026 09:10:50.714] <252> Info (4) [AP] (2de31307) state: ready
[22.03.2026 09:10:50.714] <296> Info (4) [AP] (2de31307) output: >
[22.03.2026 09:10:50.714] <252> Info (3) [AP] (2de31307) command: 'Invoke: Permissions.Elevate2PrivilegedMode { }'
[22.03.2026 09:10:50.714] <150> Info (4) [AP] (2de31307) output: <VCPCommandResult result="true" exception="" />
[22.03.2026 09:10:50.714] <150> Info (4) [AP] (2de31307) output: <VCPCommandArgs />
[22.03.2026 09:10:50.715] <142> Info (4) [AP] (2de31307) output: >
[22.03.2026 09:10:50.715] <252> Info (3) [AP] (2de31307) command: 'startServer\n83090b45-8571-472f-b675-427e844b0ad4\n42788698-c966-480b-b857-1563f6405cb4\n'
[22.03.2026 09:10:50.715] <237> Info (4) [AP] (2de31307) output: 2501
[22.03.2026 09:10:50.715] <237> Info (4) [AP] (2de31307) output: >
[22.03.2026 09:10:50.715] <252> Info (3) [AP] Disposing client from thread 252
[22.03.2026 09:10:50.716] <252> Info (3) Disposing BaseAgentProtocol [0x1563370]
[22.03.2026 09:10:50.716] <252> Info (3) Disposing CSocketAgentService [0x2198b6c], sessionId [2de31307]
[22.03.2026 09:10:50.716] <252> Info (3) [SocketAgentService] Closing connection to agent 'Veeam.HVdns.com', id 2de31307
[22.03.2026 09:10:50.716] <252> Info (3) [ReconnectableSocket] Stop request was sent on [82efb441-c211-4136-a44d-fe2c41ec8553].
[22.03.2026 09:10:50.716] <124> Info (3) [ReconnectableSocket][StopCondition] Stop confirmation was received on [82efb441-c211-4136-a44d-fe2c41ec8553].
[22.03.2026 09:10:50.716] <375> Info (3) [ReconnectableSocket] Stop confirmation was sent on [82efb441-c211-4136-a44d-fe2c41ec8553].
[22.03.2026 09:10:50.716] <375> Info (3) [ReconnectableSocket][StopCondition] Stop request was received on [82efb441-c211-4136-a44d-fe2c41ec8553].
[22.03.2026 09:10:50.757] <252> Info (3) [LinuxBackupServerWorker] Result: {(EString) login=83090b45-8571-472f-b675-427e844b0ad4; (EString) pwd=42788698-c966-480b-b857-1563f6405cb4; (EBoolean) crcEnabled=false; (EGuid) gateConnId={00000000-0000-0000-0000-000000000000}; (EString) endpoints=10.0.0.200:2501;Veeam.HVdns.com:2501; }
[22.03.2026 09:10:51.932] <142> Info (3) [LinuxBackupServerWorker] Command: repository.getStorageAgentLink
[22.03.2026 09:10:51.932] <142> Info (3) {(EGuid) AgentId={8f62a064-df6e-465c-ac2e-7f467be09190}; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) StorageId={7e6a0b27-4ef5-4067-ae6f-e1cd4eb87b9f}; (EDateTime) _currentTime=2026-03-22 09:10:52; (EDateTime) currentTimeUtc=2026-03-22 13:10:52; }
[22.03.2026 09:10:51.939] <142> Info (3) [LinuxBackupServerWorker] Result: {(EString) StorageAgentLink=RelativePath://LSORDS30-NGD Backup\LSORDS30-NGD Backup_2026-03-15T230415.vib; }
[22.03.2026 09:10:52.014] <210> Info (3) [LinuxBackupServerWorker] Command: repository.getStorageAgentLink
[22.03.2026 09:10:52.014] <210> Info (3) {(EGuid) AgentId={8f62a064-df6e-465c-ac2e-7f467be09190}; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) StorageId={efbff675-433f-4da1-aba9-b7e14bcab53a}; (EDateTime) _currentTime=2026-03-22 09:10:52; (EDateTime) currentTimeUtc=2026-03-22 13:10:52; }
[22.03.2026 09:10:52.022] <210> Info (3) [LinuxBackupServerWorker] Result: {(EString) StorageAgentLink=RelativePath://LSORDS30-NGD Backup\LSORDS30-NGD Backup_2026-03-15T153319.vib; }
[22.03.2026 09:10:52.100] <339> Info (3) [LinuxBackupServerWorker] Command: repository.getStorageAgentLink
[22.03.2026 09:10:52.100] <339> Info (3) {(EGuid) AgentId={8f62a064-df6e-465c-ac2e-7f467be09190}; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) StorageId={4ebe8c9e-34a1-4a48-ac5b-bf518b42428d}; (EDateTime) _currentTime=2026-03-22 09:10:52; (EDateTime) currentTimeUtc=2026-03-22 13:10:52; }
[22.03.2026 09:10:52.106] <339> Info (3) [LinuxBackupServerWorker] Result: {(EString) StorageAgentLink=RelativePath://LSORDS30-NGD Backup\LSORDS30-NGD Backup_2026-03-14T230333.vib; }
[22.03.2026 09:10:52.181] <264> Info (3) [LinuxBackupServerWorker] Command: repository.getStorageAgentLink
[22.03.2026 09:10:52.181] <264> Info (3) {(EGuid) AgentId={8f62a064-df6e-465c-ac2e-7f467be09190}; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) StorageId={4a68ca50-d40d-4949-afd7-51764b576ef5}; (EDateTime) _currentTime=2026-03-22 09:10:52; (EDateTime) currentTimeUtc=2026-03-22 13:10:52; }
[22.03.2026 09:10:52.189] <264> Info (3) [LinuxBackupServerWorker] Result: {(EString) StorageAgentLink=RelativePath://LSORDS30-NGD Backup\LSORDS30-NGD Backup_2026-03-08T230331.vib; }
[22.03.2026 09:10:52.267] <237> Info (3) [LinuxBackupServerWorker] Command: repository.getStorageAgentLink
[22.03.2026 09:10:52.267] <237> Info (3) {(EGuid) AgentId={8f62a064-df6e-465c-ac2e-7f467be09190}; (EGuid) RepositoryId={88788f9e-d8f5-4eb4-bc4f-9b3f5403bcec}; (EGuid) SessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; (EGuid) StorageId={68d1576d-f3c1-44d9-82b4-72536c9b3914}; (EDateTime) _currentTime=2026-03-22 09:10:52; (EDateTime) currentTimeUtc=2026-03-22 13:10:52; }
[22.03.2026 09:10:52.275] <237> Info (3) [LinuxBackupServerWorker] Result: {(EString) StorageAgentLink=RelativePath://LSORDS30-NGD Backup\LSORDS30-NGD Backup2026-03-07T230839_B1AF.vbk; }
[22.03.2026 09:11:08.386] <339> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:11:08.386] <339> Info (3) {(EDateTime) _currentTime=2026-03-22 09:11:08; (EDateTime) currentTimeUtc=2026-03-22 13:11:08; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:11:08.388] <339> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:11:08.440] <252> Info (3) [LinuxBackupServerWorker] Command: lease.keepAliveLease
[22.03.2026 09:11:08.440] <252> Info (3) {(EGuid) LeaseId={de65f295-aeeb-4768-99f7-583c6e41f666}; (EDateTime) _currentTime=2026-03-22 09:11:09; (EDateTime) currentTimeUtc=2026-03-22 13:11:09; }
[22.03.2026 09:11:08.441] <252> Info (3) [LinuxBackupServerWorker] Result:
[22.03.2026 09:11:13.655] <339> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:11:13.655] <339> Info (3) {(EDateTime) _currentTime=2026-03-22 09:11:14; (EDateTime) currentTimeUtc=2026-03-22 13:11:14; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:11:13.697] <339> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:11:38.425] <296> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:11:38.425] <296> Info (3) {(EDateTime) _currentTime=2026-03-22 09:11:38; (EDateTime) currentTimeUtc=2026-03-22 13:11:38; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:11:38.426] <296> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:11:43.733] <237> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:11:43.733] <237> Info (3) {(EDateTime) _currentTime=2026-03-22 09:11:44; (EDateTime) currentTimeUtc=2026-03-22 13:11:44; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:11:43.782] <237> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:12:08.463] <296> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:12:08.463] <296> Info (3) {(EDateTime) _currentTime=2026-03-22 09:12:08; (EDateTime) currentTimeUtc=2026-03-22 13:12:08; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:12:08.465] <296> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:12:13.819] <352> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:12:13.819] <352> Info (3) {(EDateTime) _currentTime=2026-03-22 09:12:14; (EDateTime) currentTimeUtc=2026-03-22 13:12:14; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:12:13.867] <352> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:12:38.502] <185> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:12:38.502] <185> Info (3) {(EDateTime) _currentTime=2026-03-22 09:12:38; (EDateTime) currentTimeUtc=2026-03-22 13:12:38; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:12:38.504] <185> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:12:43.904] <141> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:12:43.904] <141> Info (3) {(EDateTime) _currentTime=2026-03-22 09:12:44; (EDateTime) currentTimeUtc=2026-03-22 13:12:44; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:12:43.948] <141> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:13:08.541] <296> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:13:08.541] <296> Info (3) {(EDateTime) _currentTime=2026-03-22 09:13:08; (EDateTime) currentTimeUtc=2026-03-22 13:13:08; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:13:08.542] <296> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:13:13.984] <296> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:13:13.984] <296> Info (3) {(EDateTime) _currentTime=2026-03-22 09:13:14; (EDateTime) currentTimeUtc=2026-03-22 13:13:14; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:13:13.986] <296> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:13:38.580] <296> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:13:38.580] <296> Info (3) {(EDateTime) _currentTime=2026-03-22 09:13:38; (EDateTime) currentTimeUtc=2026-03-22 13:13:38; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:13:38.581] <296> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:13:44.023] <431> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:13:44.023] <431> Info (3) {(EDateTime) _currentTime=2026-03-22 09:13:44; (EDateTime) currentTimeUtc=2026-03-22 13:13:44; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:13:44.069] <431> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:14:08.619] <350> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:14:08.619] <350> Info (3) {(EDateTime) _currentTime=2026-03-22 09:14:08; (EDateTime) currentTimeUtc=2026-03-22 13:14:08; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:14:08.620] <350> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:14:14.106] <340> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:14:14.106] <340> Info (3) {(EDateTime) _currentTime=2026-03-22 09:14:14; (EDateTime) currentTimeUtc=2026-03-22 13:14:14; (EGuid) sessionId={7ab15ad2-ddc2-483e-ace5-50b244682e20}; }
[22.03.2026 09:14:14.148] <340> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:14:38.657] <352> Info (3) [LinuxBackupServerWorker] Command: job.GetSessionState
[22.03.2026 09:14:38.657] <352> Info (3) {(EDateTime) _currentTime=2026-03-22 09:14:38; (EDateTime) currentTimeUtc=2026-03-22 13:14:38; (EGuid) sessionId={d00bd517-42e3-4ee8-8237-7f3a5ff36989}; }
[22.03.2026 09:14:38.664] <352> Info (3) [LinuxBackupServerWorker] Result: {(EInt64) state=5; }
[22.03.2026 09:14:44.18