Server 2.5.17 beta/Client 2.5.11 beta (Updated 3x)

[dewitpj]

Ah - did a re-install - now I have the volume_snapshot, sorry about that.

[GieltjE]

Using server 2.5.17 in combination with client 2.5.11 (or any above 2.5.8) I get the following on all clients, after this message the only way to go back is kill the server:

06/11/20 11:24	INFO	Indexing of “system” done. 17 filesystem lookups 0 db lookups and 0 db updates
06/11/20 11:24	INFO	Backing up “crontabs” without snapshot.
06/11/20 11:24	INFO	Indexing of “crontabs” done. 1 filesystem lookups 0 db lookups and 0 db updates
06/11/20 11:24	DEBUG	localhost: Doing backup with hashed transfer…
06/11/20 11:24	INFO	localhost: Loading file list…
06/11/20 11:24	DEBUG	localhost Starting incremental backup…
06/11/20 11:24	INFO	localhost: Calculating file tree differences…
06/11/20 11:24	DEBUG	Reflink copying is enabled
06/11/20 11:24	INFO	localhost: Calculating tree difference size…
06/11/20 11:24	INFO	localhost: Linking unchanged and loading new files…
06/11/20 11:24	DEBUG	Loading file “mtab” (metadata only)
06/11/20 11:24	DEBUG	Loading file “resolv.conf” (metadata only)
06/11/20 11:24	DEBUG	Loading file “os-release” (metadata only)
06/11/20 11:24	DEBUG	Loading file “rmt” (metadata only)
06/11/20 11:24	INFO	Referencing snapshot on “localhost” for path “etc” failed: FAILED
06/11/20 11:24	DEBUG	Loading file “localtime” (metadata only)

The client log:
2020-11-06 11:24:01: Started connection to SERVICE_COMMANDS
2020-11-06 11:24:01: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=&status_id=1&speed_bpms=0&total_bytes=-1&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:02: Started connection to SERVICE_COMMANDS
2020-11-06 11:24:02: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#3START BACKUP group=0&running_jobs=1&sha=528&with_permissions=1&with_scripts=1&with_orig_path=1&with_sequence=1&with_proper_symlinks=1&status_id=1&phash=1&async=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:02: Removing VSS log data…
2020-11-06 11:24:02: rc=0 hasError=true state=0
2020-11-06 11:24:02: SERVICE_COMMANDS finished
2020-11-06 11:24:02: Final path: /etc
2020-11-06 11:24:02: Final path: /root
2020-11-06 11:24:02: Final path: /var/www
2020-11-06 11:24:02: Final path: /lib/systemd/system
2020-11-06 11:24:02: Final path: /var/spool/cron/crontabs
2020-11-06 11:24:02: Script list at “/usr/local/etc/urbackup/scripts/list” does not exist. Skipping.
2020-11-06 11:24:02: Script “/usr/local/etc/urbackup/prefilebackup” does not exist
2020-11-06 11:24:02: Final path: /etc
2020-11-06 11:24:02: Final path: /root
2020-11-06 11:24:02: Final path: /var/www
2020-11-06 11:24:02: Final path: /lib/systemd/system
2020-11-06 11:24:02: Final path: /var/spool/cron/crontabs
2020-11-06 11:24:02: Creating shadowcopy of “etc” in indexDirs()
2020-11-06 11:24:02: done.
2020-11-06 11:24:02: Backing up “etc” without snapshot.
2020-11-06 11:24:02: Disabling CBT on volume “/etc”
2020-11-06 11:24:02: Indexing “etc”…
2020-11-06 11:24:02: Not following symlink “/etc/localtime” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/mtab” because symlink target at “/proc/3327857/mounts” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/os-release” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/resolv.conf” because symlink target at “/run/resolvconf/resolv.conf” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/rmt” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/init.d/README” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/init.d/rc” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/init.d/rcS” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/netdata/000.-.USE.THE.orig.LINK.TO.COPY.AND.EDIT.STOCK.CONFIG.FILES” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/netdata/orig” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/network/run” because symlink target at “/run/network” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/postfix/makedefs.out” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/resolvconf/run” because symlink target at “/run/resolvconf” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/sv/acpid/supervise” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/sv/acpid/log/supervise” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/sv/irqbalance/supervise” because symlink target at “/run/runit/supervise/irqbalance” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/sv/ssh/supervise” because symlink target at “/run/runit/supervise/ssh” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/sv/ssh/log/supervise” because symlink target at “/run/runit/supervise/ssh.log” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/bootlogs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/bootmisc.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/brightness.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/checkfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/checkroot-bootclean.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/checkroot.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/halt.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/hostname.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/killprocs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/motd.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mount-configfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mountall-bootclean.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mountall.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mountdevsubfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mountkernfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mountnfs-bootclean.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/mountnfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/rc.local.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/reboot.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/rmnologin.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/sendsigs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/single.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/umountfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/umountnfs.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/umountroot.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/etc/systemd/system/urandom.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Started connection to SERVICE_COMMANDS
2020-11-06 11:24:02: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#WAIT FOR INDEX async_id=93d48073656cae3699398d919c3a8c3e#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:02: Wait for async index 93d48073656cae3699398d919c3a8c3e
2020-11-06 11:24:02: Not following symlink “/etc/systemd/user/sockets.target.wants/dirmngr.socket” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/systemd/user/sockets.target.wants/gpg-agent-browser.socket” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/systemd/user/sockets.target.wants/gpg-agent-extra.socket” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/systemd/user/sockets.target.wants/gpg-agent-ssh.socket” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/systemd/user/sockets.target.wants/gpg-agent.socket” because of configuration.
2020-11-06 11:24:02: Not following symlink “/etc/systemd/user/sockets.target.wants/pk-debconf-helper.socket” because of configuration.
2020-11-06 11:24:02: Indexing of “etc” done. 328 filesystem lookups 0 db lookups and 1 db updates
2020-11-06 11:24:02: Creating shadowcopy of “root” in indexDirs()
2020-11-06 11:24:02: done.
2020-11-06 11:24:02: Backing up “root” without snapshot.
2020-11-06 11:24:02: Disabling CBT on volume “/root”
2020-11-06 11:24:02: Indexing “root”…
2020-11-06 11:24:02: Indexing of “root” done. 574 filesystem lookups 0 db lookups and 0 db updates
2020-11-06 11:24:02: Creating shadowcopy of “www” in indexDirs()
2020-11-06 11:24:02: done.
2020-11-06 11:24:02: Backing up “www” without snapshot.
2020-11-06 11:24:02: Disabling CBT on volume “/var/www”
2020-11-06 11:24:02: Indexing “www”…
2020-11-06 11:24:02: Indexing of “www” done. 6 filesystem lookups 0 db lookups and 0 db updates
2020-11-06 11:24:02: Creating shadowcopy of “system” in indexDirs()
2020-11-06 11:24:02: done.
2020-11-06 11:24:02: Backing up “system” without snapshot.
2020-11-06 11:24:02: Disabling CBT on volume “/lib/systemd/system”
2020-11-06 11:24:02: Indexing “system”…
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/cryptdisks-early.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/cryptdisks.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/hwclock.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/nfs-common.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/rc.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/rcS.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/screen-cleanup.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/sudo.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Not following symlink “/lib/systemd/system/x11-common.service” because symlink target at “/dev/null” is excluded
2020-11-06 11:24:02: Indexing of “system” done. 17 filesystem lookups 0 db lookups and 0 db updates
2020-11-06 11:24:02: Creating shadowcopy of “crontabs” in indexDirs()
2020-11-06 11:24:02: done.
2020-11-06 11:24:02: Backing up “crontabs” without snapshot.
2020-11-06 11:24:02: Disabling CBT on volume “/var/spool/cron/crontabs”
2020-11-06 11:24:02: Indexing “crontabs”…
2020-11-06 11:24:02: Indexing of “crontabs” done. 1 filesystem lookups 0 db lookups and 0 db updates
2020-11-06 11:24:02: Script “/usr/local/etc/urbackup/postfileindex” does not exist
2020-11-06 11:24:02: Async index 93d48073656cae3699398d919c3a8c3e finished with “done”
2020-11-06 11:24:02: Started connection to SERVICE_FILESRV
2020-11-06 11:24:02: FileSRV received 167
2020-11-06 11:24:02: FileSrv: Received data…
2020-11-06 11:24:02: FileSrv: Received a Packet.
2020-11-06 11:24:02: FileSrv: Sending script output (normal) phash_{9c28ff72-5a74-487b-b5e1-8f1c96cd0cf4}/phash_93d48073656cae3699398d919c3a8c3e|0|0|KzaBC5zksvabsELeG6oe metadata_id=0
2020-11-06 11:24:02: FileSrv: Mapped name: /usr/local/var/urbackup/phash/phash_93d48073656cae3699398d919c3a8c3e|0|0|KzaBC5zksvabsELeG6oe
2020-11-06 11:24:02: Started connection to SERVICE_COMMANDS
2020-11-06 11:24:02: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#GET VSSLOG
2020-11-06 11:24:02: VSS logdata - 717 bytes
2020-11-06 11:24:02: rc=0 hasError=true state=0
2020-11-06 11:24:02: SERVICE_COMMANDS finished
2020-11-06 11:24:02: rc=0 hasError=true state=0
2020-11-06 11:24:02: SERVICE_COMMANDS finished
2020-11-06 11:24:02: Started connection to SERVICE_FILESRV
2020-11-06 11:24:02: Started connection to SERVICE_FILESRV
2020-11-06 11:24:02: FileSRV received 93
2020-11-06 11:24:02: FileSrv: Received data…
2020-11-06 11:24:02: FileSrv: Received a Packet.
2020-11-06 11:24:02: FileSrv: Sending file (normal) urbackup/filelist.ub metadata_id=0
2020-11-06 11:24:02: FileSrv: Mapped name: /usr/local/var/urbackup/data/filelist.ub
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: Received flush.
2020-11-06 11:24:03: FileSRV received 120
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: FileSrv: Sending file (normal) urbackup/tokens_KzaBC5zksvabsELeG6oe.properties metadata_id=0
2020-11-06 11:24:03: FileSrv: Mapped name: /usr/local/var/urbackup/data/tokens_KzaBC5zksvabsELeG6oe.properties
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: Received flush.
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: FileSRV received 126
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: FileSrv: Sending script output (normal) urbackup/FILE_METADATA|KzaBC5zksvabsELeG6oe|97 metadata_id=0
2020-11-06 11:24:03: FileSrv: Mapped name: /usr/local/var/urbackup/data/FILE_METADATA|KzaBC5zksvabsELeG6oe|97
2020-11-06 11:24:03: Removing pipe file urbackup/FILE_METADATA|KzaBC5zksvabsELeG6oe|97
2020-11-06 11:24:03: Pipe file has exit code 0
2020-11-06 11:24:03: FileSrv: Sending script urbackup/FILE_METADATA|KzaBC5zksvabsELeG6oe|96 not finished yet
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: Received flush.
2020-11-06 11:24:03: FileSrv: Recv Error in RecvMessage
2020-11-06 11:24:03: SERVICE_FILESRV finished
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: Started connection to SERVICE_FILESRV
2020-11-06 11:24:03: FileSRV received 84
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: FileSrv: Sending meta data of KzaBC5zksvabsELeG6oe|etc/mtab metadata_id=62
2020-11-06 11:24:03: FileSrv: Mapped name: /etc/mtab
2020-11-06 11:24:03: FileSRV received 21
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: Received flush.
2020-11-06 11:24:03: FileSRV received 91
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: FileSrv: Sending meta data of KzaBC5zksvabsELeG6oe|etc/resolv.conf metadata_id=80
2020-11-06 11:24:03: FileSrv: Mapped name: /etc/resolv.conf
2020-11-06 11:24:03: FileSRV received 21
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: Received flush.
2020-11-06 11:24:03: FileSRV received 194
2020-11-06 11:24:03: FileSrv: Received data…
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: FileSrv: Sending meta data of KzaBC5zksvabsELeG6oe|etc/os-release metadata_id=69
2020-11-06 11:24:03: FileSrv: Mapped name: /etc/os-release
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: FileSrv: Sending meta data of KzaBC5zksvabsELeG6oe|etc/rmt metadata_id=81
2020-11-06 11:24:03: FileSrv: Mapped name: /etc/rmt
2020-11-06 11:24:03: FileSrv: Received a Packet.
2020-11-06 11:24:03: Received flush.
2020-11-06 11:24:04: Started connection to SERVICE_COMMANDS
2020-11-06 11:24:04: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#START SC “etc”#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:04: Creating shadowcopy of “etc”…
2020-11-06 11:24:04: done.
2020-11-06 11:24:04: Disabling CBT on volume “/etc”
2020-11-06 11:24:04: ERROR: Creating shadowcopy of “etc” failed.
2020-11-06 11:24:04: rc=0 hasError=true state=0
2020-11-06 11:24:04: SERVICE_COMMANDS finished
2020-11-06 11:24:04: FileSRV received 110
2020-11-06 11:24:04: FileSrv: Received data…
2020-11-06 11:24:04: FileSrv: Received a Packet.
2020-11-06 11:24:04: FileSrv: Sending meta data of KzaBC5zksvabsELeG6oe|etc/localtime metadata_id=47
2020-11-06 11:24:04: FileSrv: Mapped name: /etc/localtime
2020-11-06 11:24:04: FileSrv: Received a Packet.
2020-11-06 11:24:04: Received flush.
2020-11-06 11:24:11: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&eta_ms=155516&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:21: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&eta_ms=145516&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:31: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&eta_ms=135515&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:41: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&eta_ms=125515&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:51: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&eta_ms=115514&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:24:55: ClientService cmd: PONG
2020-11-06 11:25:01: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&eta_ms=105513&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:25:02: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:03: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:03: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:03: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:03: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:04: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:04: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…
2020-11-06 11:25:04: FileSrv: 1 min Timeout deleting Buffers (1024 KB) and waiting 1h more…

After which it infinitely repeats:
2020-11-06 11:50:02: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:50:12: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:50:22: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:50:32: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:50:42: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:50:52: ClientService cmd: #IjbZTmwvLydHyPRyHFZd1#2PING RUNNING pc_done=0&status_id=1&speed_bpms=0&total_bytes=25681015&done_bytes=0&paused_fb=1#token=KzaBC5zksvabsELeG6oe
2020-11-06 11:50:55: ClientService cmd: PONG

[uroni]

@orogor

That’s better now it doesnt crashes, but backups aren t successfull.
Should i disable parralelism, because you want to solve that later ?

I updated again. Parallel hashing should work now again (with one thread).

[orogor]

Not sure about where to set thread. i have
Beta: Number of parallel client file hash threads per file backup = 1
But i still get an error during the backup. Currently trying without parrallelism.

07/11/20 17:29	INFO	ddd[home]: Linking unchanged and loading new files…
07/11/20 17:29	ERROR	Current parallel hash position greated than requested. 570 > 3
07/11/20 17:29	ERROR	Error getting parallel hash for file “.bash_history” line 3 (2)
07/11/20 17:29	INFO	Referencing snapshot on “ddd[home]” for path “home/clientsubname=home” failed: FAILED
07/11/20 17:29	INFO	Waiting for parallel hash load stream to finish
07/11/20 17:29	ERROR	Error during parallel hash load: TIMEOUT
07/11/20 17:29	INFO	Waiting for file transfers…
07/11/20 17:29	INFO	Waiting for file hashing and copying threads…
07/11/20 17:29	INFO	Waiting for metadata download stream to finish
07/11/20 17:29	INFO	Writing new file list…
07/11/20 17:30	DEBUG	Some metadata was missing
07/11/20 17:30	DEBUG	Client disconnected while backing up. Copying partial file…
07/11/20 17:30	DEBUG	Syncing file system…
07/11/20 17:30	INFO	Transferred 7.8479 MB - Average speed: 457.32 KBit/s
07/11/20 17:30	INFO	(Before compression: 30.7733 MB ratio: 3.92121)
07/11/20 17:30	DEBUG	Script does not exist urbackup/post_incr_filebackup
07/11/20 17:30	INFO	Time taken for backing up client ddd[home]: 3m 39s
07/11/20 17:30	ERROR	Backup failed

[uroni]

Did you update the server as well?

[orogor]

No, doing this was right now, i thought it was a client side bug.

[orogor]

Ok, that works
Thanks

[GieltjE]

With the latest debian testing the server always crashes (even after rebuild):
urbackupsrv[1466945]: segfault at 10 ip 00007f6f45220fe0 sp 00007ffc11fc2180 error 4 in libcrypto++.so.8.4.0[7f6f450f7000+1e6000]
urbackupsrv[1466986]: segfault at 10 ip 00007fdd32a03fe0 sp 00007ffc25ef9a00 error 4 in libcrypto++.so.8.4.0[7fdd328da000+1e6000]
urbackupsrv[1466996]: segfault at 10 ip 00007f764d8affe0 sp 00007fff6d78d6f0 error 4 in libcrypto++.so.8.4.0[7f764d786000+1e6000]