Endpoint Backup stalling

Backup agent for Microsoft Windows servers and workstations (formerly Veeam Endpoint Backup FREE)

Endpoint Backup stalling

Veeam Logoby jayadams » Wed Aug 19, 2015 2:16 pm

I am experiencing backup jobs that get stuck at the first step of "Preparing for backup" with 0% progress. It will stay in this state for hours before being manually cancelled. Clicking the 'cancel' button results in a "Backup is in progress...Canceling" message that makes no progress. I have had successful backups using this same backup job. It is backing up to a B&R server and I am using endpoint 1.1.0.110 and B&R 8.0.0.2030 There doesn't appear to be a permissions problem because I do get successful backups (sometimes) and they only take 7 minutes to run (incrementally)

The log that follows is a backup job that I just ran after a fresh boot of the laptop. Note that the last entry in the log was stagnant for over an hour. Where do I go to debug this?
Code: Select all
===================================================================
Starting new log
Log has been started by 'JPA-LAPTOP\SYSTEM' user (Non-interactive)
Logging level: [4 (AboveNormal)]
MachineName: [JPA-LAPTOP], OS: [Microsoft Windows NT 6.3.9600.0]
Process: [64 bit], PID: [3504], SessionId: [0]
UTC Time: [8/19/2015 12:59:52 PM], DaylightSavingTime: [True]
Culture: [en-US], UI culture: [en-US]
Module: [C:\Program Files\Veeam\Endpoint Backup\Veeam.EndPoint.Manager.exe]. File version: [1.1.0.110], Assembly version: [8.0.0.0]
CmdLineParams: [startbackupjob owner=[vbsvc] Normal 6e77c327-ff03-4547-85b4-1c229a54eb4e 29cc9dc2-457e-4198-92bc-1da1e2819029]
Network Interface, Name: Ethernet 4, Description: TAP Adapter OAS NDIS 6.0, Interface Type: Ethernet, Operational Status: Down;
Unicast IPAddresses: fe80::8bd:5afc:f003:90bf%29; 169.254.144.191;
Network Interface, Name: Ethernet 3, Description: Dell GigabitEthernet, Interface Type: Ethernet, Operational Status: Up;
Unicast IPAddresses: fe80::50d5:a704:caa:1782%28; 192.168.44.186;
Gateway IPAddresses: 192.168.44.1;
Network Interface, Name: Local Area Connection* 3, Description: Microsoft Wi-Fi Direct Virtual Adapter, Interface Type: Wireless80211, Operational Status: Down;
Unicast IPAddresses: fe80::4858:caba:16a2:4d71%7; 169.254.77.113;
Network Interface, Name: Wi-Fi, Description: Intel(R) Dual Band Wireless-AC 7265, Interface Type: Wireless80211, Operational Status: Down;
Unicast IPAddresses: fe80::50a9:4391:91c5:e74c%6; 169.254.231.76;
Network Interface, Name: Bluetooth Network Connection, Description: Bluetooth Device (Personal Area Network), Interface Type: Ethernet, Operational Status: Down;
Unicast IPAddresses: fe80::59c1:3650:3a3e:1bfb%4; 169.254.27.251;
Network Interface, Name: VMware Network Adapter VMnet1, Description: VMware Virtual Ethernet Adapter for VMnet1, Interface Type: Ethernet, Operational Status: Up;
Unicast IPAddresses: fe80::804b:542b:39e3:eb3%23; 192.168.75.1;
Network Interface, Name: VMware Network Adapter VMnet8, Description: VMware Virtual Ethernet Adapter for VMnet8, Interface Type: Ethernet, Operational Status: Up;
Unicast IPAddresses: fe80::3470:956:d35d:1d93%24; 192.168.19.1;
Network Interface, Name: Loopback Pseudo-Interface 1, Description: Software Loopback Interface 1, Interface Type: Loopback, Operational Status: Up;
Unicast IPAddresses: ::1; 127.0.0.1;
Network Interface, Name: isatap.{B8A65470-1D90-4D58-B4EC-8E0B71C620F9}, Description: Microsoft ISATAP Adapter #5, Interface Type: Tunnel, Operational Status: Down;
Unicast IPAddresses: fe80::5efe:192.168.75.1%22;
Network Interface, Name: isatap.{51C5DCAA-4E40-4C17-B424-3F8FC10AE5AA}, Description: Microsoft ISATAP Adapter #7, Interface Type: Tunnel, Operational Status: Down;
Unicast IPAddresses: fe80::5efe:192.168.19.1%25;
Network Interface, Name: isatap.xxxxx.com, Description: Microsoft ISATAP Adapter #8, Interface Type: Tunnel, Operational Status: Down;
Unicast IPAddresses: fe80::5efe:192.168.44.186%26;

[19.08.2015 08:59:52] <01> Info     Starting job mode: 'Normal'
[19.08.2015 08:59:52] <01> Info     [Session] Id '29cc9dc2-457e-4198-92bc-1da1e2819029', State 'Working'
[19.08.2015 08:59:52] <01> Info     Custom command execution (pre script) disabled
[19.08.2015 08:59:52] <01> Info     [SleepLocker] Enable sleep prevention
[19.08.2015 08:59:52] <01> Info     [SleepLocker] Sleep prevention power scheme params AC: True 4294967295, DC: True 300
[19.08.2015 08:59:53] <01> Info     Session parameters: MDM3YiA/IDZjZGY1ID8gZjlhODdm
[19.08.2015 08:59:53] <01> Info     [WinRT Provider] Connection cost type is: Unrestricted
[19.08.2015 08:59:53] <01> Info     [WinRT Provider] Real WinRT Provider initialized
[19.08.2015 08:59:53] <01> Info     [WinRT Provider] Connection cost type is: Unrestricted
[19.08.2015 08:59:53] <01> Info     Creating foreign backup accessor, target machine 'veeambackup.xxxxx.com', remote repository 'Endpoint Backups'.
[19.08.2015 08:59:53] <01> Info     Connecting to remote VBR, ips: '192.168.44.41', port: '10001', sessionName: 'Backup Job JPA-LAPTOP'.
[19.08.2015 08:59:53] <01> Info             [RemoteBackupService] Requesting a new remote session: user identity '.\endpoint', machine name 'JPA-LAPTOP', uuid: '35434435-3134-3157-3931-315734314435'
[19.08.2015 08:59:54] <01> Info             [RemoteBackupService] Remote session has been started, id: '8c9406fa-f9f9-4b5c-ade5-0959cf2ac993'
[19.08.2015 08:59:54] <01> Info     Local protocol version: 1, remote protocol version: 1
[19.08.2015 08:59:54] <01> Info     Protocol version check returned - Compatible
[19.08.2015 08:59:54] <01> Info     [LeaseKeeper] Created, ttl 600sec
[19.08.2015 08:59:55] <01> Info     [Repository] Saving options
[19.08.2015 08:59:55] <01> Info     Getting list of volumes and disks on local machine (removable disks is excluded).
[19.08.2015 08:59:55]      Info     < 6e8> Enumerating non shadow fixed volumes.
[19.08.2015 08:59:55]      Info     < 6e8>     \\?\Volume{0adaa62f-e985-4871-816a-7bfc61adcf9a}, label: 'Windows', file system: NTFS, DOS name: \Device\HarddiskVolume4
[19.08.2015 08:59:55]      Info     < 6e8>       Mount points:
[19.08.2015 08:59:55]      Info     < 6e8>         C:\
[19.08.2015 08:59:55]      Info     < 6e8>       Extents:
[19.08.2015 08:59:55]      Info     < 6e8>         Disk 0, offset1089470464, length 237682819072
[19.08.2015 08:59:55]      Info     < 6e8>     \\?\Volume{4d78d5e8-a521-438f-9e10-98b8108ca88c}, label: 'RECOVERY', file system: NTFS, DOS name: \Device\HarddiskVolume5
[19.08.2015 08:59:55]      Info     < 6e8>       Mount points:
[19.08.2015 08:59:55]      Info     < 6e8>         D:\
[19.08.2015 08:59:55]      Info     < 6e8>       Extents:
[19.08.2015 08:59:55]      Info     < 6e8>         Disk 0, offset238772289536, length 17282629632
[19.08.2015 08:59:55]      Info     < 6e8>     \\?\Volume{261e80ef-dc13-44c7-90b1-5b22c94a0113}, label: 'WINRE', file system: NTFS, DOS name: \Device\HarddiskVolume1
[19.08.2015 08:59:55]      Info     < 6e8>       Mount points:
[19.08.2015 08:59:55]      Info     < 6e8>       Extents:
[19.08.2015 08:59:55]      Info     < 6e8>         Disk 0, offset1048576, length 681574400
[19.08.2015 08:59:57]      Info     < 6e8>     Enumerating installed physical disks
[19.08.2015 08:59:57]      Info     < 6e8>         Disk 0, capacity: 238.4GB, bus type: SATA, read-only state: read-write, pack state: 1
[19.08.2015 08:59:57]      Info     < 6e8>           Device: embedded, path: \\?\scsi#disk&ven_axns381e&prod_-256gm-b#4&25664d10&0&000000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}
[19.08.2015 08:59:57]      Info     < 6e8>           Layout: GPT, disk ID: {9e758e58-051d-4b16-9eb0-793c2bb6de5e}
[19.08.2015 08:59:57]      Info     < 6e8>             Partition 0, type ID {de94bba4-06d1-4d40-a16a-bfd50179d6ac} (Microsoft recovery), ID {261e80ef-dc13-44c7-90b1-5b22c94a0113}, offset 1048576, length 681574400
[19.08.2015 08:59:57]      Info     < 6e8>             Partition 1, type ID {c12a7328-f81f-11d2-ba4b-00a0c93ec93b} (EFI system), ID {64d5d623-5f0f-4f7a-9a1c-8f38ecf75055}, offset 682622976, length 272629760
[19.08.2015 08:59:57]      Info     < 6e8>             Partition 2, type ID {e3c9e316-0b5c-4db8-817d-f92df00215ae} (Microsoft reserved space), ID {490150c5-1f51-4234-ab26-22bfc2f6af67}, offset 955252736, length 134217728
[19.08.2015 08:59:57]      Info     < 6e8>             Partition 3, type ID {ebd0a0a2-b9e5-4433-87c0-68b6b72699c7} (basic data), ID {0adaa62f-e985-4871-816a-7bfc61adcf9a}, offset 1089470464, length 237682819072
[19.08.2015 08:59:57]      Info     < 6e8>             Partition 4, type ID {ebd0a0a2-b9e5-4433-87c0-68b6b72699c7} (basic data), ID {4d78d5e8-a521-438f-9e10-98b8108ca88c}, offset 238772289536, length 17282629632
[19.08.2015 08:59:57]      Info     < 6e8>     Enumerating installed physical disks. Ok.
[19.08.2015 08:59:57]      Info     < 6e8>     Enumerating VDS volumes
[19.08.2015 08:59:57]      Info     < 6e8>         Skipping VDS pack from volumes enumeration : pack is offline.
[19.08.2015 08:59:57]      Info     < 6e8>         Volume name: \\?\GLOBALROOT\Device\HarddiskVolume4, type: VDS_VT_SIMPLE, is online
[19.08.2015 08:59:57]      Info     < 6e8>           GUID path names:
[19.08.2015 08:59:57]      Info     < 6e8>             \\?\Volume{0adaa62f-e985-4871-816a-7bfc61adcf9a}\
[19.08.2015 08:59:57]      Info     < 6e8>         Volume name: \\?\GLOBALROOT\Device\HarddiskVolume5, type: VDS_VT_SIMPLE, is online
[19.08.2015 08:59:57]      Info     < 6e8>           GUID path names:
[19.08.2015 08:59:57]      Info     < 6e8>             \\?\Volume{4d78d5e8-a521-438f-9e10-98b8108ca88c}\
[19.08.2015 08:59:57]      Info     < 6e8>     Enumerating VDS volumes. Ok.
[19.08.2015 08:59:57]      Info     < 6e8> Boot volume is '{0adaa62f-e985-4871-816a-7bfc61adcf9a}', disk number: '0'.
[19.08.2015 08:59:57]      Info     < 6e8> system volume device: '\Device\HarddiskVolume2'.
[19.08.2015 08:59:57]      Info     < 6e8> Volume '\\?\Volume{261e80ef-dc13-44c7-90b1-5b22c94a0113}' is an GPT recovery volume.
[19.08.2015 08:59:57] <01> Info         Layout: '<ShadowVolumesLayout><ShadowVolumes/><LiveVolumes><LiveVolume Id="{0adaa62f-e985-4871-816a-7bfc61adcf9a}" Name="\\?\Volume{0adaa62f-e985-4871-816a-7bfc61adcf9a}" Label="Windows" FilesystemType="NTFS" Capacity="237682819072" UsedSize="90872606720" Roles="4" VdsType="VDS_VT_SIMPLE" VdsOfflineStatus="false"><MountPoints><MountPoint Path="C:\"/></MountPoints><Extents><Extent DiskNumber="0" StartingOffset="1089470464" ExtentLength="237682819072"/></Extents></LiveVolume><LiveVolume Id="{4d78d5e8-a521-438f-9e10-98b8108ca88c}" Name="\\?\Volume{4d78d5e8-a521-438f-9e10-98b8108ca88c}" Label="RECOVERY" FilesystemType="NTFS" Capacity="17282629632" UsedSize="15305162752" Roles="0" VdsType="VDS_VT_SIMPLE" VdsOfflineStatus="false"><MountPoints><MountPoint Path="D:\"/></MountPoints><Extents><Extent DiskNumber="0" StartingOffset="238772289536" ExtentLength="17282629632"/></Extents></LiveVolume><LiveVolume Id="{261e80ef-dc13-44c7-90b1-5b22c94a0113}" Name="\\?\Volume{261e80ef-dc13-44c7-90b1-5b22c94a0113}" Label="WINRE" FilesystemType="NTFS" Capacity="681574400" UsedSize="286113792" Roles="1" VdsType="VDS_VT_SIMPLE" VdsOfflineStatus="false"><MountPoints/><Extents><Extent DiskNumber="0" StartingOffset="1048576" ExtentLength="681574400"/></Extents></LiveVolume></LiveVolumes><Disks><Disk DiskNumber="0" BusType="SATA" Capacity="256060514304" IsReadOnly="false" ReadOnlyState="2" IsVirtualDisk="false" IsRemovable="false" LogicalSectorSize="512" FriendlyName="AXNS381E-256GM-B" PackStateFlags="1"><DriveLayout PartitionStyle="GPT" DiskId="{9e758e58-051d-4b16-9eb0-793c2bb6de5e}" StartingUsableOffset="17408" UsableLength="256060480000" MaxPartitionCount="128"><Partitions><Partition PartitionNumber="1" StartingOffset="1048576" PartitionLength="681574400" PartitionStyle="GPT" PartitionType="{de94bba4-06d1-4d40-a16a-bfd50179d6ac}" PartitionId="{261e80ef-dc13-44c7-90b1-5b22c94a0113}" Attributes="9223372036854775809" Name="Basic data partition"/><Partition PartitionNumber="2" StartingOffset="682622976" PartitionLength="272629760" PartitionStyle="GPT" PartitionType="{c12a7328-f81f-11d2-ba4b-00a0c93ec93b}" PartitionId="{64d5d623-5f0f-4f7a-9a1c-8f38ecf75055}" Attributes="9223372036854775808" Name="EFI system partition"/><Partition PartitionNumber="3" StartingOffset="955252736" PartitionLength="134217728" PartitionStyle="GPT" PartitionType="{e3c9e316-0b5c-4db8-817d-f92df00215ae}" PartitionId="{490150c5-1f51-4234-ab26-22bfc2f6af67}" Attributes="9223372036854775808" Name="Microsoft reserved partition"/><Partition PartitionNumber="4" StartingOffset="1089470464" PartitionLength="237682819072" PartitionStyle="GPT" PartitionType="{ebd0a0a2-b9e5-4433-87c0-68b6b72699c7}" PartitionId="{0adaa62f-e985-4871-816a-7bfc61adcf9a}" Attributes="0" Name="Basic data partition"/><Partition PartitionNumber="5" StartingOffset="238772289536" PartitionLength="17282629632" PartitionStyle="GPT" PartitionType="{ebd0a0a2-b9e5-4433-87c0-68b6b72699c7}" PartitionId="{4d78d5e8-a521-438f-9e10-98b8108ca88c}" Attributes="1" Name="Basic data partition"/></Partitions></DriveLayout><DevSetupInfo DevPath="\\?\scsi#disk&amp;ven_axns381e&amp;prod_-256gm-b#4&amp;25664d10&amp;0&amp;000000#{53f56307-b6bf-11d0-94f2-00a0c91efb8b}" IsExternalDisk="false"/></Disk></Disks></ShadowVolumesLayout>
[19.08.2015 08:59:57] <01> Info     '
[19.08.2015 08:59:57] <01> Info     Job Settings
[19.08.2015 08:59:57] <01> Info     Source -------------------------------------------
[19.08.2015 08:59:57] <01> Info     Backup mode:  volume level backup
[19.08.2015 08:59:57] <01> Info     Included items:
[19.08.2015 08:59:57] <01> Info     Windows (C:)
[19.08.2015 08:59:57] <01> Info     WINRE
[19.08.2015 08:59:57] <01> Info     EFI system partition
[19.08.2015 08:59:57] <01> Info     Destination --------------------------------------
[19.08.2015 08:59:57] <01> Info     Veeam Endpoint Backup repository
[19.08.2015 08:59:57] <01> Info     Backup server: veeambackup.xxxxx.com
[19.08.2015 08:59:57] <01> Info     Backup repository: Endpoint Backups
[19.08.2015 08:59:57] <01> Info     Schedule -----------------------------------------
[19.08.2015 08:59:57] <01> Info     Backup is not scheduled to run automatically
[19.08.2015 08:59:57] <01> Info     Retention days: 14
[19.08.2015 08:59:57] <01> Info     Initializing backup target, mode: 'Normal', agent sharing mode: 'AgentPerTask', repository: 'Endpoint Backups'.
[19.08.2015 08:59:57] <01> Info     [ProxyAgentTracker] Stopping agents on Job: '6e77c327-ff03-4547-85b4-1c229a54eb4e'
[19.08.2015 08:59:59] <01> Info     [CStorageLinksHelper] Asserting storage links...
[19.08.2015 09:00:00] <01> Info     [CStorageLinksHelper] Storage links checked successfully
[19.08.2015 09:00:12] <01> Info     Checking if backup type changed
[19.08.2015 09:00:12] <01> Info     Creating foreign backup accessor, target machine 'veeambackup.xxxxxx.com', remote repository 'Endpoint Backups'.
[19.08.2015 09:00:12] <01> Info     Connecting to remote VBR, ips: '192.168.44.41', port: '10001', sessionName: 'Backup Job JPA-LAPTOP'.
[19.08.2015 09:00:12] <01> Info             [RemoteBackupService] Requesting a new remote session: user identity '.\endpoint', machine name 'JPA-LAPTOP', uuid: '35434435-3134-3157-3931-315734314435'
[19.08.2015 09:00:12] <01> Info             [RemoteBackupService] Remote session has been started, id: '124ca160-f6a2-484c-ba30-1ec206098d99'
[19.08.2015 09:00:12] <01> Info     Local protocol version: 1, remote protocol version: 1
[19.08.2015 09:00:12] <01> Info     Protocol version check returned - Compatible
[19.08.2015 09:00:12] <01> Info     [LeaseKeeper] Created, ttl 600sec
[19.08.2015 09:00:13] <01> Info     [Repository] Saving options
[19.08.2015 09:00:15] <01> Info     [RemoteBackupService] Terminating remote session, id: '124ca160-f6a2-484c-ba30-1ec206098d99'
[19.08.2015 09:00:15] <01> Info     [RemoteBackupService] Remote session was successfully terminated.
[19.08.2015 09:00:15] <01> Info     Backup type did not change
[19.08.2015 09:00:15] <01> Info         Starting target agent ( sharing mode: 'AgentPerTask', host: 'on repository'.).
[19.08.2015 09:00:15] <01> Info     Starting 'Target' repository agent ( WAN accelerator is disabled ), repository 'Endpoint Backups'.
[19.08.2015 09:00:16] <01> Info     [LeaseKeeper] Created, ttl 900sec
[19.08.2015 09:00:16] <01> Info     [ProxyAgent] Starting client agent session, id '7eb8f7f0-bc4d-4486-8f10-326a08e0a865', host 'VEEAMBACKUP', agent id '08675a25-f1cf-4422-ab2f-ee581a2bc63f', IPs '192.168.44.41:2500', PID '4572'
[19.08.2015 09:00:16] <01> Info     [SocketAgentService] Connecting to agent 'VEEAMBACKUP' ('192.168.44.41:2500')
[19.08.2015 09:00:16] <01> Info     [NetSocket] Connect
[19.08.2015 09:00:16] <05> Info           [AP] (7eb8) output: >
[19.08.2015 09:00:16] <01> Info                   [AP] (7eb8) state: ready
[19.08.2015 09:00:16] <01> Info     [AgentReconnectsManager] Starting remote agent control session.
[19.08.2015 09:00:16] <01> Info             [AP] (7eb8) command: 'Invoke: Generic.SetNetworkReconnectOptions\n{\n  (EBoolean) Enabled = true\n  (EUInt64) AttemptIntervalMs = 15000\n  (EUInt64) OverallTimeoutMs = 1800000\n}\n'
[19.08.2015 09:00:16] <06> Info           [AP] (7eb8) output: <VCPCommandResult result="true" exception="" />
[19.08.2015 09:00:16] <06> Info           [AP] (7eb8) output: <VCPCommandArgs />
[19.08.2015 09:00:16] <06> Info           [AP] (7eb8) output: >
[19.08.2015 09:00:16] <01> Info     [ProxyAgent] Starting client agent session, id '96823c46-00c0-4a3a-b460-87e94c069414', host 'VEEAMBACKUP', agent id '08675a25-f1cf-4422-ab2f-ee581a2bc63f', IPs '192.168.44.41:2500', PID '4572'
[19.08.2015 09:00:16] <11> Info             [AP] (7eb8) command: 'Invoke: Generic.EnableAgentReconnectPolicy\n{\n  (EBoolean) IsRemoteAgent = true\n}\n'
[19.08.2015 09:00:16] <01> Info     [SocketAgentService] Connecting to agent 'VEEAMBACKUP' ('192.168.44.41:2500')
[19.08.2015 09:00:16] <06> Info           [AP] (7eb8) output: <VCPCommandResult result="true" exception="" />
[19.08.2015 09:00:16] <01> Info     [NetSocket] Connect
[19.08.2015 09:00:16] <06> Info           [AP] (7eb8) output: <VCPCommandArgs />
[19.08.2015 09:00:16] <06> Info           [AP] (7eb8) output: >

{no other entries after an hour of waiting}
jayadams
Novice
 
Posts: 9
Liked: never
Joined: Wed Oct 24, 2012 1:49 am
Full Name: Jay Adams

Re: Endpoint Backup stalling

Veeam Logoby Dima P. » Wed Aug 19, 2015 2:24 pm

Hello jayadams,

Have you opened a support case from Endpoint's Control Panel > Support? Thanks.
Dima P.
Veeam Software
 
Posts: 6249
Liked: 440 times
Joined: Mon Feb 04, 2013 2:07 pm
Location: SPb
Full Name: Dmitry Popov

Re: Endpoint Backup stalling

Veeam Logoby jayadams » Wed Aug 19, 2015 2:30 pm

No. I didn't know that veeam provided direct support for it. I will do that now.
jayadams
Novice
 
Posts: 9
Liked: never
Joined: Wed Oct 24, 2012 1:49 am
Full Name: Jay Adams

Re: Endpoint Backup stalling

Veeam Logoby Dima P. » Wed Aug 19, 2015 3:11 pm

Great! Do not forget to post the case ID to this thread, please.
Dima P.
Veeam Software
 
Posts: 6249
Liked: 440 times
Joined: Mon Feb 04, 2013 2:07 pm
Location: SPb
Full Name: Dmitry Popov

Re: Endpoint Backup stalling

Veeam Logoby jayadams » Fri Aug 28, 2015 2:42 pm

Veeam support is looking into this issue. Case 01001128.
jayadams
Novice
 
Posts: 9
Liked: never
Joined: Wed Oct 24, 2012 1:49 am
Full Name: Jay Adams


Return to Veeam Agent for Windows



Who is online

Users browsing this forum: Yahoo [Bot] and 13 guests