Image backup not working for server 2.5.25 and with agent 2.5.19 and 2.5.18

1

Trying to backup ubuntu 20.04 instance to urbackup 2.5.25 server and the image backup is not executed gives following error - , tried with both device mapper and datto both giving same issue for all linux clients

uname -r
5.4.0-122-generic

Client Log -

2022-08-05 20:27:40: Shutting down plugins…
2022-08-05 20:27:40: Deleting server…
2022-08-05 20:27:53: WARNING: Shutting down (Signal 15)
2022-08-05 20:37:09: ERROR: Error joining ipv6 multicast group ff12::f894:d:dd00:ef91
2022-08-05 20:39:18: WARNING: Shutting down (Signal 15)
2022-08-05 20:39:18: ERROR: Error joining ipv6 multicast group ff12::f894:d:dd00:ef91
2022-08-05 20:40:43: WARNING: Shutting down (Signal 15)
2022-08-05 20:40:56: ERROR: Error joining ipv6 multicast group ff12::f894:d:dd00:ef91
2022-08-05 20:43:28: WARNING: Shutting down (Signal 15)
2022-08-05 20:43:28: ERROR: Error joining ipv6 multicast group ff12::f894:d:dd00:ef91
2022-08-05 20:43:41: ERROR: Binding tcp socket to port 35621 failed. Another instance of this application may already be active and bound to this port.
2022-08-05 20:43:41: ERROR: FileServ exit with error code: 99
2022-08-05 20:43:41: ERROR: urbackupserver: Failed binding socket to port 35623. Another instance of this application may already be active and bound to this port.
2022-08-05 20:43:41: Started UrBackupClient Backend…
2022-08-05 20:43:42: Internet mode not enabled
2022-08-05 20:43:42: Looking for old Sessions… 0 sessions
2022-08-05 20:44:04: WARNING: Shutting down (Signal 2)
2022-08-05 20:44:04: Deleting lbs…
2022-08-05 20:44:04: Shutting down plugins…
2022-08-05 20:44:04: Deleting server…
2022-08-05 20:47:11: WARNING: Shutting down (Signal 15)
2022-08-05 20:47:12: ERROR: Error joining ipv6 multicast group ff12::f894:d:dd00:ef91
2022-08-05 20:56:08: ERROR: Binding tcp socket to port 35621 failed. Another instance of this application may already be active and bound to this port.
2022-08-05 20:56:08: ERROR: FileServ exit with error code: 99
2022-08-05 20:56:08: ERROR: urbackupserver: Failed binding socket to port 35623. Another instance of this application may already be active and bound to this port.
2022-08-05 20:56:08: Started UrBackupClient Backend…
2022-08-05 20:56:09: Looking for old Sessions… 0 sessions
2022-08-05 20:56:09: Final path: /
2022-08-05 20:56:09: Internet mode not enabled
2022-08-05 20:56:33: WARNING: Shutting down (Signal 2)
2022-08-05 20:56:33: Deleting lbs…
2022-08-05 20:56:33: Shutting down plugins…
2022-08-05 20:56:33: Deleting server…
2022-08-05 21:07:33: ERROR: Binding tcp socket to port 35621 failed. Another instance of this application may already be active and bound to this port.
2022-08-05 21:07:33: ERROR: FileServ exit with error code: 99
2022-08-05 21:07:33: ERROR: urbackupserver: Failed binding socket to port 35623. Another instance of this application may already be active and bound to this port.
2022-08-05 21:07:33: Started UrBackupClient Backend…
2022-08-05 21:07:34: Looking for old Sessions… 0 sessions
2022-08-05 21:07:34: Internet mode not enabled
2022-08-05 21:07:34: Final path: /
2022-08-05 21:07:55: WARNING: Shutting down (Signal 2)
2022-08-05 21:07:55: Deleting lbs…
2022-08-05 21:07:55: Shutting down plugins…
2022-08-05 21:07:55: Deleting server…

Server Log -

netstat -antpl | grep urbackup
tcp 0 0 192.168.100.16:60010 192.168.100.164:35623 ESTABLISHED 66044/urbackupsrv

2022-08-06 00:45:35: ERROR: Getting client settings failed -2
2022-08-06 00:46:56: ERROR: Error sending server signature to client
2022-08-06 00:47:15: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 00:47:15: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 00:47:15: ERROR: Getting client settings failed -2
2022-08-06 00:48:46: ERROR: Error sending server signature to client
2022-08-06 00:48:55: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 00:49:46: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 00:49:46: ERROR: Getting client settings failed -2
2022-08-06 00:50:37: ERROR: Error sending server signature to client
2022-08-06 00:51:26: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 00:52:27: ERROR: Error sending server signature to client
2022-08-06 00:54:17: ERROR: Error sending server signature to client
2022-08-06 00:56:07: ERROR: Error sending server signature to client
2022-08-06 00:56:26: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 00:56:26: ERROR: Getting client settings failed -2
2022-08-06 00:57:57: ERROR: Error sending server signature to client
2022-08-06 00:58:06: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 00:58:33: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 00:58:33: ERROR: Getting client settings failed -2
2022-08-06 00:59:47: ERROR: Error sending server signature to client
2022-08-06 01:00:13: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 01:01:37: ERROR: Error sending server signature to client
2022-08-06 01:01:40: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 01:01:40: ERROR: Getting client settings failed -2
2022-08-06 01:03:20: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 01:03:20: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 01:03:20: ERROR: Getting client settings failed -2
2022-08-06 01:03:27: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error sending server signature to client
2022-08-06 01:03:41: ERROR: No right to download any client
2022-08-06 01:05:00: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 01:05:16: ERROR: Getting Client settings of localhost.localdomain failed - CONNECT error
2022-08-06 01:05:16: ERROR: Getting client settings failed -2
2022-08-06 01:06:56: ERROR: Connecting to ClientService of “localhost.localdomain” failed: Error getting name of client
2022-08-06 01:46:06: WARNING: Shutting down (Signal 15)
2022-08-06 01:47:31: ERROR: Server admin stopped backup.
2022-08-06 01:47:31: ERROR: Backup failed
2022-08-06 01:47:32: WARNING: Exponential backoff: Waiting at least 40m before next image backup
2022-08-06 01:47:34: WARNING: Cannot remove client “localhost.localdomain” ( with id 4): Client is online or backup is in progress
2022-08-06 01:47:34: ERROR: No permission to access “/mnt/backup/10.0.0.1”
2022-08-06 01:51:49: ERROR: Server admin stopped backup.
2022-08-06 01:51:49: ERROR: Backup failed
2022-08-06 01:51:50: WARNING: Exponential backoff: Waiting at least 1h 20m before next image backup
2022-08-06 01:51:59: ERROR: Getting Client settings of ubuntu failed - CONNECT error
2022-08-06 01:51:59: ERROR: Getting client settings failed -2
2022-08-06 01:52:01: ERROR: Getting Client settings of ubuntu failed - CONNECT error
2022-08-06 01:52:01: ERROR: Getting client settings failed -2
2022-08-06 02:22:13: WARNING: Shutting down (Signal 15)

2

I am also getting below error -

08/08/22 15:45 DEBUG Getting client settings…
08/08/22 15:45 DEBUG Sending backup incr interval…
08/08/22 15:46 DEBUG Getting client settings…
08/08/22 15:55 DEBUG Getting client settings…
08/08/22 16:00 DEBUG Cannot do image backup because internet_no_images=true

Windows Backup are running fine, only issue with all linux systems same error for all.

3

Hi !
By any chance, have you checked this setting in “Internet / Active Clients” ?

image

Server version : 2.5.25

Regards,

4

This is on local lan, therefore internet server is disabled. I have also checked the recent dmesg logs and found segfault error:

EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16
EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16
EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
EXT4-fs (dm-2): mounted filesystem with ordered data mode. Opts: (null)
datto: device specified is busy: -16
datto: error during transition to snapshot ioctl handler: -16
datto: block device is already being traced: -22
datto: error setting up base block device: -22
datto: error setting up tracer as active snapshot: -22
datto: error during setup ioctl handler: -22
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16
show_signal_msg: 20 callbacks suppressed
urbackupclientb[7633]: segfault at 0 ip 00000000005a4fe3 sp 00007ffd87701540 error 4 in urbackupclientbackend[400000+525000]
Code: 8d bc 24 58 03 00 00 48 8b 07 48 8b 98 60 01 00 00 ff 90 90 01 00 00 ba 13 00 00 00 89 c6 48 8b 3d e2 dd 5d 00 ff d3 48 89 c3 <48> 8b 00 48 8d 35 ec 49 26 00 4c 89 ff 48 8b 68 48 48 8d 84 24 40
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16
datto: device specified already exists: -16
datto: error during setup ioctl handler: -16

5

I was able to fix the above error with few findings I got -

– Reinstalled a clean server again and reinstalled the linux backup agent again, backup started for linux, However the problem was with multiple volume backup, By default in image backup setting the volume to backup is C even for linux, however when I change it to ALL, it does not backup the volume and fails.

– I tried this with datto and linux device mapper both give same error, this is the /boot/efi volume as below -

df -h
Filesystem Size Used Avail Use% Mounted on
udev 945M 0 945M 0% /dev
tmpfs 193M 668K 192M 1% /run
/dev/vda1 97G 1.1G 96G 2% /
tmpfs 962M 0 962M 0% /dev/shm
tmpfs 5.0M 0 5.0M 0% /run/lock
tmpfs 962M 0 962M 0% /sys/fs/cgroup
/dev/vda15 105M 5.2M 100M 5% /boot/efi

Its is only able to backup vda1 volume but not vda15 volume, if I add another datadisk and change it to ALL it does not backup,

2022-08-18 09:21:11: ERROR: Creating snapshot of “/dev/vda15” failed
2022-08-18 09:21:11: ERROR: Snapshotting device /dev/vda15 via dattobd…
2022-08-18 09:21:11: ERROR: Using /dev/datto0…
2022-08-18 09:21:11: ERROR: CBT=type=datto&reset=1
2022-08-18 09:21:11: ERROR: driver returned an error performing specified action. check dmesg for more info: Device or resource busy
2022-08-18 09:21:11: ERROR: Creating shadowcopy of “/dev/vda15” failed.
2022-08-18 09:21:11: ERROR: Creating shadow copy failed. See client log file for details.
2022-08-18 09:27:58: ERROR: Creating snapshot of “/dev/vda15” failed
2022-08-18 09:27:58: ERROR: Snapshotting device /dev/vda15 via dattobd…
2022-08-18 09:27:58: ERROR: Using /dev/datto0…
2022-08-18 09:27:58: ERROR: CBT=type=datto&reset=1
2022-08-18 09:27:58: ERROR: driver returned an error performing specified action. check dmesg for more info: Device or resource busy
2022-08-18 09:27:58: ERROR: Creating shadowcopy of “/dev/vda15” failed.
2022-08-18 09:27:58: ERROR: Creating shadow copy failed. See client log file for details.

Is there a fix for this? Also I am looking for a step by step how to restore doc for linux image restore. I followed below blog for restore but it gave segfault in the end and machine was not bootable after reboot, Can we use restore iso to restore linux image backup.

https://blog.urbackup.org/368/linux-image-backups-with-urbackup-2-5-y

Also is there a option to mount linux image snapshot on web interface like we do for windows?

Regards
Kiran