Internet server/client restores reporting successful completion on server web UI, but client directory is empty

#1

System info:
urServer version: 2.4.12_amd64 (https://github.com/uroni/urbackup-server-docker)
urClient version: 2.4.10_amd64 (https://github.com/uroni/urbackup-client-docker)
Operating system: Ubuntu Linux with Docker installed. Docker images use OS specified in UrBackup default Dockerfile(s).
Filesystem: storage attached to server: ntfs. storage attached to client: ext4

Issue:
I have file-based backups on the URbackup server I’ve setup. When attempting to restore one file, the restore appears to finish successfully according to the webUI. However, when I check the directory which should have the restored files, the directory is empty. I’ve installed the latest server and client versions as docker containers (source linked above). The client is installed on a headless linux server. (You’ll see below that I enabled “server-confirms” to enable restores to be initiated from the server webUI)

What is the proper method to perform restores with a internet client, such that the files get saved (“restored”) on the client storage?

The Docker urBackup server is being started up like this:

 sudo docker run -d \
-v /etc/localtime:/etc/localtime:ro \
-v /home/jared/dockerfiles/urBackup/server_localBak/defaults:/etc/default/urbackupclient \
-v /media/Seagate_localEX/uroni_test_serverBackupSpace:/backups \
-v /home/jared/dockerfiles/urBackup/End2EndTest/server/database:/var/urbackup \
-v /home/jared/dockerfiles/urBackup/End2EndTest/server/urbackupserver.log:/var/log/urbackup.log \
-p 55413-55415:55413-55415 -p 35623:35623/udp \
-e PUID=1000 \
-e PGID=100 \
ur_end2end_server:2.4.12_amd64

The Docker UrBackup Client is being started up like this:

sudo docker run -d --privileged -p 35635:35621 -p 35634:35622 -p 35633:35623
-v /etc/localtime:/etc/localtime:ro
-v /home/jared/dockerfiles/urBackup/server_localBak/defaults_client:/etc/default/urbackupclient
-v /home/jared/dockerfiles/urBackup/End2EndTest/client/urbackupclient.log:/var/log/urbackupclient.log
-v /media/3TBinternalMount/pydio_storage/pydiomain/jared_media/uroni_client_cleanRestoreTest:/backup/end2end_bak
-e VERBOSITY=debug
-e URBACKUP_BACKUP_VOLUMES=/backup/end2end_bak/default
-e URBACKUP_SERVER_NAME=192.168.1.123
-e URBACKUP_CLIENT_NAME=ur_end2end_CLNTrun
-e URBACKUP_CLIENT_AUTHKEY=OXnognLWsE
-e VERBOSITY=debug
ur_end2end_client:2.4.10_amd64 --restore server-confirms -v debug

The client is pointing to an empty directory, waiting for data to be restored there.

From the server log, I am seeing the following (restore operation is intiated on the web UI at ~21:01:13):

2020-03-22 20:49:19: server_prepare_hash Thread finished (exit)
2020-03-22 20:49:19: server_hash Thread finished - normal
2020-03-22 21:01:13: Starting restore of path “general/pics/somepictures/2016-11-27_04-08-57-img_5122.jpg”…
2020-03-22 21:01:13: ERROR: Client token empty. See client log file for error details. Running a file backup may fix this issue.
2020-03-22 21:01:13: Starting restore…
2020-03-22 21:01:13: Loading file list…
2020-03-22 21:01:18: ERROR: Error getting file list. Errorcode: TIMEOUT (2)
2020-03-22 21:01:30: Looking for old Sessions… 1 sessions
2020-03-22 21:11:48: Starting scheduled incremental file backup…
2020-03-22 21:11:48: Backing up “default” without snapshot.
2020-03-22 21:11:48: ERROR: Cannot access path to backup: “/backup/end2end_bak/default” Errorcode: 2 - No such file or directory
2020-03-22 21:11:48: WARNING: Hint: Directory to backup ("/backup/end2end_bak/default") does not exist. It may have been deleted or renamed. Set the “optional” directory flag if you do not want backups to fail if directories are missing.
2020-03-22 21:11:48: ERROR: Indexing files failed, because of error
2020-03-22 21:11:48: ERROR: Constructing of filelist of “ur_end2end_CLNTrun” failed: error - index error
2020-03-22 21:11:58: ERROR: Backup had an early error. Deleting partial backup.
2020-03-22 21:11:59: WARNING: Exponential backoff: Waiting at least 1h 20m before next file backup
2020-03-22 21:11:59: server_prepare_hash Thread finished (exit)
2020-03-22 21:11:59: server_hash Thread finished - normal

From the client log I’m seeing :

2020-03-22 20:59:43: ClientService cmd: PONG
2020-03-22 21:00:15: ClientService cmd: PONG
2020-03-22 21:00:16: ClientService cmd: PONG
2020-03-22 21:00:43: ClientService cmd: PONG
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#FILE RESTORE client_token=Z4ChC28u4O1y1ZrNxF0y68yZ4MuIRx80xKvgkplGcKOvp14/i67jJZLbZy2lUGMWfL8CjaZWwFD8&server_token=4w4mmHYZcEnHdx2p7Wjk1&id=4&status_id=7&log_id=14&restore_path=general%2Fpics%2Fsomepictures%2F2016-11-27_04-08-57-img_5122.jpg&single_file=1&clean_other=1&ignore_other_fs=1&restore_flags=64&tgroup=0&clientsubname=general
2020-03-22 21:01:13: ERROR: Error decrypting server access token. Access key might be wrong.
2020-03-22 21:01:13: ERROR: Client token empty. See client log file for error details. Running a file backup may fix this issue.
2020-03-22 21:01:13: rc=0 hasError=true state=0
2020-03-22 21:01:13: Starting restore…
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Loading file list…
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Reconnected successfully,
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Reconnected successfully,
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Reconnected successfully,
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Reconnected successfully,
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Reconnected successfully,
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:13: Reconnected successfully,
2020-03-22 21:01:13: Flushing FileClient…
2020-03-22 21:01:13: Server timeout (2) in FileClient
2020-03-22 21:01:13: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:13: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:14: Reconnected successfully,
2020-03-22 21:01:14: Flushing FileClient…
2020-03-22 21:01:14: Server timeout (2) in FileClient
2020-03-22 21:01:14: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:14: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: ClientService cmd: PONG
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:15: Reconnected successfully,
2020-03-22 21:01:15: Flushing FileClient…
2020-03-22 21:01:15: Server timeout (2) in FileClient
2020-03-22 21:01:15: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:15: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:16: Reconnected successfully,
2020-03-22 21:01:16: Flushing FileClient…
2020-03-22 21:01:16: Server timeout (2) in FileClient
2020-03-22 21:01:16: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:16: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:17: Reconnected successfully,
2020-03-22 21:01:17: Flushing FileClient…
2020-03-22 21:01:17: Server timeout (2) in FileClient
2020-03-22 21:01:17: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:17: New channel: Number of Channels: 3
2020-03-22 21:01:18: Reconnected successfully,
2020-03-22 21:01:18: Flushing FileClient…
2020-03-22 21:01:18: Server timeout (2) in FileClient
2020-03-22 21:01:18: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:18: New channel: Number of Channels: 3
2020-03-22 21:01:18: Reconnected successfully,
2020-03-22 21:01:18: Flushing FileClient…
2020-03-22 21:01:18: Server timeout (2) in FileClient
2020-03-22 21:01:18: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:18: New channel: Number of Channels: 3
2020-03-22 21:01:18: Reconnected successfully,
2020-03-22 21:01:18: Flushing FileClient…
2020-03-22 21:01:18: Server timeout (2) in FileClient
2020-03-22 21:01:18: ClientService cmd: #ImFq7ZhX2BDripdl46C1K#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general
2020-03-22 21:01:18: New channel: Number of Channels: 3
2020-03-22 21:01:18: Reconnected successfully,
2020-03-22 21:01:18: Flushing FileClient…
2020-03-22 21:01:18: Server timeout (2) in FileClient
2020-03-22 21:01:18: FileClient: Reconnect tries exhausted (1)
2020-03-22 21:01:18: FileClient: ERR_TIMEOUT
2020-03-22 21:01:18: ERROR: Error getting file list. Errorcode: TIMEOUT (2)
2020-03-22 21:01:18: Removing running process (1) id 7 server_id 7 token 4w4mmHYZcEnHdx2p7Wjk1 action 8
2020-03-22 21:01:43: ClientService cmd: PONG
2020-03-22 21:02:15: ClientService cmd: PONG
2020-03-22 21:02:18: ClientService cmd: PONG
2020-03-22 21:02:43: ClientService cmd: PONG
2020-03-22 21:03:15: ClientService cmd: PONG
2020-03-22 21:03:18: ClientService cmd: PONG
2020-03-22 21:03:43: ClientService cmd: PONG
2020-03-22 21:04:15: ClientService cmd: PONG
2020-03-22 21:04:18: ClientService cmd: PONG
2020-03-22 21:04:43: ClientService cmd: PONG
2020-03-22 21:05:15: ClientService cmd: PONG
2020-03-22 21:05:18: ClientService cmd: PONG
2020-03-22 21:05:43: ClientService cmd: PONG
2020-03-22 21:06:15: ClientService cmd: PONG
2020-03-22 21:06:18: ClientService cmd: PONG
2020-03-22 21:06:43: ClientService cmd: PONG
2020-03-22 21:07:15: ClientService cmd: PONG
2020-03-22 21:07:18: ClientService cmd: PONG
2020-03-22 21:07:43: ClientService cmd: PONG
2020-03-22 21:08:15: ClientService cmd: PONG
2020-03-22 21:08:18: ClientService cmd: PONG
2020-03-22 21:08:43: ClientService cmd: PONG
2020-03-22 21:09:15: ClientService cmd: PONG
2020-03-22 21:09:18: ClientService cmd: PONG
2020-03-22 21:09:43: ClientService cmd: PONG
2020-03-22 21:10:15: ClientService cmd: PONG
2020-03-22 21:10:18: ClientService cmd: PONG
2020-03-22 21:10:43: ClientService cmd: PONG
2020-03-22 21:11:15: ClientService cmd: PONG
2020-03-22 21:11:18: ClientService cmd: PONG
2020-03-22 21:11:43: ClientService cmd: PONG
2020-03-22 21:11:48: ClientService cmd: #IzZOYSL1z2bon2ouOcazY#2PING RUNNING pc_done=&status_id=8&speed_bpms=0&total_bytes=-1&done_bytes=0&paused_fb=1#token=4w4mmHYZcEnHdx2p7Wjk
2020-03-22 21:11:48: ClientService cmd: #IzZOYSL1z2bon2ouOcazY#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=8&async=1#token=4w4mmHYZcEnHdx2p7Wjk
2020-03-22 21:11:48: Removing VSS log data…
2020-03-22 21:11:48: Removing running process (timeout) id 4 server_id 4 token 4w4mmHYZcEnHdx2p7Wjk action 1
2020-03-22 21:11:48: Final path: /backup/end2end_bak/default
2020-03-22 21:11:48: Final path: /backup/end2end_bak/sensitive
2020-03-22 21:11:48: rc=0 hasError=true state=0
2020-03-22 21:11:48: Final path: /backup/end2end_bak/general
2020-03-22 21:11:48: Script list at “/usr/local/etc/urbackup/scripts/list” does not exist. Skipping.
2020-03-22 21:11:48: Script “/usr/local/etc/urbackup/prefilebackup” does not exist
2020-03-22 21:11:48: Final path: /backup/end2end_bak/default
2020-03-22 21:11:48: Final path: /backup/end2end_bak/sensitive
2020-03-22 21:11:48: Final path: /backup/end2end_bak/general
2020-03-22 21:11:48: Creating shadowcopy of “default” in indexDirs()
2020-03-22 21:11:48: done.
2020-03-22 21:11:48: Backing up “default” without snapshot.
2020-03-22 21:11:48: Indexing “default”…
2020-03-22 21:11:48: ERROR: Cannot access path to backup: “/backup/end2end_bak/default” Errorcode: 2 - No such file or directory
2020-03-22 21:11:48: WARNING: Hint: Directory to backup ("/backup/end2end_bak/default") does not exist. It may have been deleted or renamed. Set the “optional” directory flag if you do not want backups to fail if directories are missing.
2020-03-22 21:11:48: ERROR: Indexing files failed, because of error
2020-03-22 21:11:48: Script “/usr/local/etc/urbackup/postfileindex” does not exist
2020-03-22 21:11:48: ClientService cmd: #IzZOYSL1z2bon2ouOcazY#WAIT FOR INDEX async_id=4ff694a5b212fd1ee5e4b93fb0dd36c2#token=4w4mmHYZcEnHdx2p7Wjk
2020-03-22 21:11:48: Wait for async index 4ff694a5b212fd1ee5e4b93fb0dd36c2
2020-03-22 21:11:48: Async index 4ff694a5b212fd1ee5e4b93fb0dd36c2 finished with “error - index error”
2020-03-22 21:11:48: ClientService cmd: #IzZOYSL1z2bon2ouOcazY#GET VSSLOG
2020-03-22 21:11:48: VSS logdata - 454 bytes
2020-03-22 21:11:48: rc=0 hasError=true state=0
2020-03-22 21:11:48: rc=0 hasError=true state=0
2020-03-22 21:11:58: rc=0 hasError=true state=0
2020-03-22 21:11:59: ClientService cmd: #IzZOYSL1z2bon2ouOcazY#2LOGDATA 1584936718 0-1584936708-Starting scheduled incremental file backup…
0-1584936708-Backing up “default” without snapshot.
2-1584936708-Cannot access path to backup: “/backup/end2end_bak/default” Errorcode: 2 - No such file or directory
1-1584936708-Hint: Directory to backup ("/backup/end2end_bak/default") does not exist. It may have been deleted or renamed. Set the “optional” directory flag if you do not want backups to fail if directories are missing.
2-1584936708-Indexing files failed, because of error
2-1584936708-Constructing of filelist of “ur_end2end_CLNTrun” failed: error - index error
2-1584936718-Backup had an early error. Deleting partial backup.

2020-03-22 21:11:59: rc=0 hasError=true state=0
2020-03-22 21:12:15: ClientService cmd: PONG
2020-03-22 21:12:18: ClientService cmd: PONG
2020-03-22 21:12:43: ClientService cmd: PONG
2020-03-22 21:13:15: ClientService cmd: PONG
2020-03-22 21:13:18: ClientService cmd: PONG
2020-03-22 21:13:43: ClientService cmd: PONG
2020-03-22 21:14:15: ClientService cmd: PONG

Is this a bug? Am I doing something (everything?) : ) incorrectly?

#2

The problem is probably that the client does not have access to any backups since it can only access the backups it previously did…

If you download the pre-configured client from the web interface the server will automatically add permissions such that the client can restore previous backups of that client. But since you are using the docker container client it is a bit problematic… One option would be to change the Dockerfile such that it installs the client downloaded from the web interface. (Instead of ADD ${URL} /root/install.sh use ADD downloaded_client.sh /root/install.sh)

If you figure out how to make that more confortable with the client in docker… patches are welcome.

#3

Okay, I overlooked that you start the restore on the server. In this case it is only missing the client_access_key. Running a file backup like described in the error message should fix that. Could you try that and report back? (You’d also have to point it at a existing (empty) folder for that to work)

#4

I am specifying the “internet auth key” to the client. The client apparently uses this to correctly connect to the server. However, I see that there is an additional “Internet restore authentication key” on the server. How/where do I specify that on the client? You can see I’m already specifying the “internet auth key” in my client docker command. However, I can’t find where I specify the “Internet restore authentication key” (which I observe as being a different string of characters from the internet auth key).

I also tried to: “Set the ‘optional’ directory flag” on the server, but I received the same result when doing the restore. That result being: “ERROR: Error decrypting server access token. Access key might be wrong.”

Here is the log from the client:

2020-03-25 15:10:07: ClientService cmd: PONG

2020-03-25 15:10:12: ClientService cmd: PONG

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#FILE RESTORE client_token=B5U9kbkXt4DxXO6f0M/cAVRe5yCw/hoJFlpUfUUveXIgvrQZnqzZK8Wxcm/h3n8DTvGToGaMTtUi&server_token=4w4mmHYZcEnHdx2p7Wjk1&id=6&status_id=4&log_id=8&restore_path=general%2Fpics%2Fsomepictures%2F2016-11-27_04-08-57-img_5122.jpg&single_file=1&clean_other=1&ignore_other_fs=1&restore_flags=64&tgroup=0&clientsubname=general

2020-03-25 15:11:01: ERROR: Error decrypting server access token. Access key might be wrong.

2020-03-25 15:11:01: ERROR: Client token empty. See client log file for error details. Running a file backup may fix this issue.
Jared Comment: Which "client log " is it ^^^ referencing? Is there an additional client log to look at? </>

2020-03-25 15:11:01: rc=0 hasError=true state=0

2020-03-25 15:11:01: Starting restore…

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Loading file list…

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:01: Reconnected successfully,

2020-03-25 15:11:01: Flushing FileClient…

2020-03-25 15:11:01: Server timeout (2) in FileClient

2020-03-25 15:11:01: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:01: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:02: Reconnected successfully,

2020-03-25 15:11:02: Flushing FileClient…

2020-03-25 15:11:02: Server timeout (2) in FileClient

2020-03-25 15:11:02: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:02: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:03: Reconnected successfully,

2020-03-25 15:11:03: Flushing FileClient…

2020-03-25 15:11:03: Server timeout (2) in FileClient

2020-03-25 15:11:03: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:03: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:04: Reconnected successfully,

2020-03-25 15:11:04: Flushing FileClient…

2020-03-25 15:11:04: Server timeout (2) in FileClient

2020-03-25 15:11:04: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:04: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:05: Reconnected successfully,

2020-03-25 15:11:05: Flushing FileClient…

2020-03-25 15:11:05: Server timeout (2) in FileClient

2020-03-25 15:11:05: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:05: New channel: Number of Channels: 3

2020-03-25 15:11:06: Reconnected successfully,

2020-03-25 15:11:06: Flushing FileClient…

2020-03-25 15:11:06: Server timeout (2) in FileClient

2020-03-25 15:11:06: ClientService cmd: #IpFlnrFqGshEJHXF4p6Vj#1CHANNEL capa=0&token=4w4mmHYZcEnHdx2p7Wjk1&restore_version=1&virtual_client=general

2020-03-25 15:11:06: New channel: Number of Channels: 3

2020-03-25 15:11:06: Reconnected successfully,

2020-03-25 15:11:06: Flushing FileClient…

2020-03-25 15:11:06: Server timeout (2) in FileClient

2020-03-25 15:11:06: FileClient: Reconnect tries exhausted (1)

2020-03-25 15:11:06: FileClient: ERR_TIMEOUT

2020-03-25 15:11:06: ERROR: Error getting file list. Errorcode: TIMEOUT (2)

2020-03-25 15:11:06: Removing running process (1) id 4 server_id 4 token 4w4mmHYZcEnHdx2p7Wjk1 action 8

2020-03-25 15:11:07: ClientService cmd: PONG

2020-03-25 15:11:12: ClientService cmd: PONG

2020-03-25 15:12:06: ClientService cmd: PONG

Here is the log of these events from the server.

2020-03-25 15:11:00: Starting restore of path “general/pics/somepictures/2016-11-27_04-08-57-img_5122.jpg”…

2020-03-25 15:11:01: ERROR: Client token empty. See client log file for error details. Running a file backup may fix this issue.

2020-03-25 15:11:01: Starting restore…

2020-03-25 15:11:01: Loading file list…

2020-03-25 15:11:06: ERROR: Error getting file list. Errorcode: TIMEOUT (2)

2020-03-25 15:11:12: FileSrv: Sending script urbackup/FILE_METADATA|zYuMp9IDUjs7kHEGKIVGV12DE not finished yet

I don’t know where/how to specify the key it is looking for. I am specifying the “internet auth key” to the client, but my guess is that the server is looking for the “Internet restore authentication key” to perform the operation being requested by the connected client. This specific key is the one I don’t know how to correctly describe to the client. If this is the source of the problem, will you please shed some light on what the correct method is to specify the internet restore authentication key to the client?