Restore via internet don't work

Hi, I’m use the infscape from aws marketplace and restore don’t work via internet.
Follows the log file for analise :

2017-08-18 13:37:00: ERROR: Error setting file permissions to “urbackup/data”
2017-08-18 13:37:00: urbackupserver: Server started up successfully!
2017-08-18 13:37:00: Started UrBackupClient Backend…
2017-08-18 13:37:00: FileSrv: Binding UDP socket at port 35622…
2017-08-18 13:37:00: FileSrv: done.
2017-08-18 13:37:00: FileSrv: Servername: -##restore##15030634205796-
2017-08-18 13:37:00: FileSrv: Server started up successfully
2017-08-18 13:37:00: FileSrv: UDP Thread startet
2017-08-18 13:37:01: Internet mode not enabled
2017-08-18 13:37:01: Looking for old Sessions… 2 sessions
2017-08-18 13:38:46: urbackupserver: No available slots… starting new Worker
2017-08-18 13:38:46: ClientService cmd: LOGIN FOR DOWNLOAD#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:38:46: Waiting for pings…
2017-08-18 13:38:46: done. (Waiting for pings)
2017-08-18 13:38:46: urbackupserver: No available slots… starting new Worker
2017-08-18 13:38:46: rc=0 hasError=true state=0
2017-08-18 13:38:46: ClientService cmd: GET BACKUPCLIENTS#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:38:46: Waiting for pings…
2017-08-18 13:38:46: done. (Waiting for pings)
2017-08-18 13:38:46: rc=0 hasError=true state=0
2017-08-18 13:40:01: Internet mode not enabled
2017-08-18 13:41:07: WARNING: Shutting down (Signal 15)
2017-08-18 13:41:07: Deleting lbs…
2017-08-18 13:41:07: Shutting down plugins…
2017-08-18 13:41:07: Deleting server…
2017-08-18 13:41:07: SQLite: recovered 24 frames from WAL file /root/urbackup/backup_client.db-wal code: 283
2017-08-18 13:41:07: urbackupserver: Server started up successfully!
2017-08-18 13:41:07: Started UrBackupClient Backend…
2017-08-18 13:41:07: FileSrv: Server started up successfully
2017-08-18 13:41:08: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:08: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:08: Looking for old Sessions… 0 sessions
2017-08-18 13:41:09: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:09: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:09: Trying to connect to internet server “18.231.38.10” at port 55415
2017-08-18 13:41:09: Successfully connected.
2017-08-18 13:41:10: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:10: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:11: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:11: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:12: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:12: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:13: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:13: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:14: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:14: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:15: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:15: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:16: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:16: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:17: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:17: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:18: Client timeout in ClientConnector::Run
2017-08-18 13:41:18: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:19: Client timeout in ClientConnector::Run
2017-08-18 13:41:19: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:20: Client timeout in ClientConnector::Run
2017-08-18 13:41:20: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:21: Client timeout in ClientConnector::Run
2017-08-18 13:41:21: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:22: Client timeout in ClientConnector::Run
2017-08-18 13:41:22: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:23: Client timeout in ClientConnector::Run
2017-08-18 13:41:23: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:24: Client timeout in ClientConnector::Run
2017-08-18 13:41:24: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:25: Client timeout in ClientConnector::Run
2017-08-18 13:41:25: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:26: Client timeout in ClientConnector::Run
2017-08-18 13:41:26: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:27: Client timeout in ClientConnector::Run
2017-08-18 13:41:27: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:28: Client timeout in ClientConnector::Run
2017-08-18 13:41:28: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:29: Client timeout in ClientConnector::Run
2017-08-18 13:41:29: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:30: Client timeout in ClientConnector::Run
2017-08-18 13:41:30: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:31: Client timeout in ClientConnector::Run
2017-08-18 13:41:31: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:32: Client timeout in ClientConnector::Run
2017-08-18 13:41:32: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:33: Client timeout in ClientConnector::Run
2017-08-18 13:41:33: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:34: Client timeout in ClientConnector::Run
2017-08-18 13:41:34: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:35: Started connection to SERVICE_COMMANDS
2017-08-18 13:41:35: ClientService cmd: #ILSnXELDGt6JPNbrXNawG#ADD IDENTITY
2017-08-18 13:41:35: rc=0 hasError=true state=0
2017-08-18 13:41:35: SERVICE_COMMANDS finished
2017-08-18 13:41:35: Started connection to SERVICE_COMMANDS
2017-08-18 13:41:35: ClientService cmd: #ILSnXELDGt6JPNbrXNawG#1CHANNEL capa=4288&token=T2kwdwMXpGECjVt87o6l&restore_version=1&virtual_client=
2017-08-18 13:41:35: New channel: Number of Channels: 1
2017-08-18 13:41:35: Client timeout in ClientConnector::Run
2017-08-18 13:41:35: ClientService cmd: STATUS DETAIL#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:35: urbackupserver: No available slots… starting new Worker
2017-08-18 13:41:35: ClientService cmd: LOGIN FOR DOWNLOAD#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:35: Waiting for pings…
2017-08-18 13:41:35: done. (Waiting for pings)
2017-08-18 13:41:35: Client 0/1: --err–
2017-08-18 13:41:35: ERROR: Client 0/1 ERROR: --err–
2017-08-18 13:41:35: rc=0 hasError=true state=0
2017-08-18 13:41:36: Client timeout in ClientConnector::Run
2017-08-18 13:41:37: Client timeout in ClientConnector::Run
2017-08-18 13:41:38: Client timeout in ClientConnector::Run
2017-08-18 13:41:39: Client timeout in ClientConnector::Run
2017-08-18 13:41:39: ClientService cmd: GET SALT#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&username=admin
2017-08-18 13:41:39: Waiting for pings…
2017-08-18 13:41:39: done. (Waiting for pings)
2017-08-18 13:41:39: Client 0/1: --ok;gtPRR92whIXulgCTu8yY;luMXASz8yO;10000–
2017-08-18 13:41:39: rc=0 hasError=true state=0
2017-08-18 13:41:40: Client timeout in ClientConnector::Run
2017-08-18 13:41:41: Client timeout in ClientConnector::Run
2017-08-18 13:41:42: Client timeout in ClientConnector::Run
2017-08-18 13:41:43: Client timeout in ClientConnector::Run
2017-08-18 13:41:44: ClientService cmd: LOGIN FOR DOWNLOAD#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&username=admin&password0=6ab7296f02291531b98945d2c75b8628
2017-08-18 13:41:44: Waiting for pings…
2017-08-18 13:41:44: done. (Waiting for pings)
2017-08-18 13:41:44: Client 0/1: --ok–
2017-08-18 13:41:44: rc=0 hasError=true state=0
2017-08-18 13:41:44: ClientService cmd: GET BACKUPCLIENTS#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:44: Waiting for pings…
2017-08-18 13:41:44: done. (Waiting for pings)
2017-08-18 13:41:44: Client 0/1: --1|teste
2|teste-2

2017-08-18 13:41:44: rc=0 hasError=true state=0
2017-08-18 13:41:44: Client timeout in ClientConnector::Run
2017-08-18 13:41:45: ClientService cmd: GET BACKUPIMAGES teste#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:45: Waiting for pings…
2017-08-18 13:41:45: done. (Waiting for pings)
2017-08-18 13:41:45: rc=0 hasError=true state=0
2017-08-18 13:41:45: Client timeout in ClientConnector::Run
2017-08-18 13:41:47: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=true
2017-08-18 13:41:47: Downloading image…
2017-08-18 13:41:47: Waiting for pings…
2017-08-18 13:41:47: done. (Waiting for pings)
2017-08-18 13:41:47: In mutex…
2017-08-18 13:41:47: Downloading from channel 0
2017-08-18 13:41:47: Imagesize 556
2017-08-18 13:41:47: Downloading MBR…
2017-08-18 13:41:47: Downloading MBR done
2017-08-18 13:41:47: Download done -2
2017-08-18 13:41:57: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false
2017-08-18 13:41:57: Downloading image…
2017-08-18 13:41:57: Waiting for pings…
2017-08-18 13:41:57: done. (Waiting for pings)
2017-08-18 13:41:57: In mutex…
2017-08-18 13:41:57: Downloading from channel 0
2017-08-18 13:41:57: ClientService cmd: GET DOWNLOADPROGRESS#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:41:57: Sending progress…
2017-08-18 13:41:57: Imagesize 64225279488
2017-08-18 13:41:57: Used bytes 15647428608
2017-08-18 13:48:27: Unescaped something at 883
2017-08-18 13:52:29: ERROR: Read Timeout -2 CS
2017-08-18 13:52:29: Download done -2
2017-08-18 13:52:29: Client timeout in ClientConnector::Run - Channel (total timeout)
2017-08-18 13:52:29: Service finished. Transferred 1.12387 GB
2017-08-18 13:52:29: Encryption overhead: 186.892 KB
2017-08-18 13:52:29: Transferred uncompressed: 2.55516 GB (ratio: 2.2739)
2017-08-18 13:52:29: Average sent paket size: 54 bytes
2017-08-18 13:52:29: SERVICE_COMMANDS finished
2017-08-18 13:52:29: ClientService cmd: GET DOWNLOADPROGRESS#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 13:52:29: Sending progress…
2017-08-18 13:52:59: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false&offset=2923163648
2017-08-18 13:52:59: Downloading image…
2017-08-18 13:52:59: Waiting for pings…
2017-08-18 13:52:59: done. (Waiting for pings)
2017-08-18 13:52:59: In mutex…
2017-08-18 13:52:59: No channel found!
2017-08-18 13:52:59: Download done -2
2017-08-18 13:53:29: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false&offset=2923163648
2017-08-18 13:53:29: Downloading image…
2017-08-18 13:53:29: Waiting for pings…
2017-08-18 13:53:29: done. (Waiting for pings)
2017-08-18 13:53:29: In mutex…
2017-08-18 13:53:29: No channel found!
2017-08-18 13:53:29: Download done -2
2017-08-18 13:53:59: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false&offset=2923163648
2017-08-18 13:53:59: Downloading image…
2017-08-18 13:53:59: Waiting for pings…
2017-08-18 13:53:59: done. (Waiting for pings)
2017-08-18 13:53:59: In mutex…
2017-08-18 13:53:59: No channel found!
2017-08-18 13:53:59: Download done -2
2017-08-18 13:54:05: Started connection to SERVICE_COMMANDS
2017-08-18 13:54:05: ClientService cmd: #ILSnXELDGt6JPNbrXNawG#ADD IDENTITY
2017-08-18 13:54:05: rc=0 hasError=true state=0
2017-08-18 13:54:05: SERVICE_COMMANDS finished
2017-08-18 13:54:05: Started connection to SERVICE_COMMANDS
2017-08-18 13:54:05: ClientService cmd: #ILSnXELDGt6JPNbrXNawG#1CHANNEL capa=4288&token=T2kwdwMXpGECjVt87o6l&restore_version=1&virtual_client=
2017-08-18 13:54:05: New channel: Number of Channels: 1
2017-08-18 13:54:29: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false&offset=2923163648
2017-08-18 13:54:29: Downloading image…
2017-08-18 13:54:29: Waiting for pings…
2017-08-18 13:54:29: done. (Waiting for pings)
2017-08-18 13:54:29: In mutex…
2017-08-18 13:54:29: Downloading from channel 0
2017-08-18 13:54:29: Imagesize -1
2017-08-18 13:54:29: ERROR: Error reading size
2017-08-18 13:54:29: Download done -2
2017-08-18 13:54:29: SERVICE_COMMANDS finished
2017-08-18 13:54:59: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false&offset=2923163648
2017-08-18 13:54:59: Downloading image…
2017-08-18 13:54:59: Waiting for pings…
2017-08-18 13:54:59: done. (Waiting for pings)
2017-08-18 13:54:59: In mutex…
2017-08-18 13:54:59: No channel found!
2017-08-18 13:54:59: Download done -2
2017-08-18 13:55:29: Started connection to SERVICE_COMMANDS
2017-08-18 13:55:29: ClientService cmd: #ILSnXELDGt6JPNbrXNawG#1CHANNEL capa=4288&token=T2kwdwMXpGECjVt87o6l&restore_version=1&virtual_client=
2017-08-18 13:55:29: New channel: Number of Channels: 1
2017-08-18 13:56:30: ClientService cmd: PONG
2017-08-18 13:57:30: ClientService cmd: PONG
2017-08-18 13:58:30: ClientService cmd: PONG
2017-08-18 13:59:30: ClientService cmd: PONG
2017-08-18 14:00:30: ClientService cmd: PONG
2017-08-18 14:01:30: ClientService cmd: PONG
2017-08-18 14:02:30: ClientService cmd: PONG
2017-08-18 14:03:30: ClientService cmd: PONG
2017-08-18 14:04:30: ClientService cmd: PONG
2017-08-18 14:05:30: ClientService cmd: PONG
2017-08-18 14:06:30: ClientService cmd: PONG
2017-08-18 14:07:30: ClientService cmd: PONG
2017-08-18 14:08:30: ClientService cmd: PONG
2017-08-18 14:09:30: ClientService cmd: PONG
2017-08-18 14:09:36: ClientService cmd: DOWNLOAD IMAGE#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu&img_id=87749591&time=1501800213&mbr=false
2017-08-18 14:09:36: Downloading image…
2017-08-18 14:09:36: Waiting for pings…
2017-08-18 14:09:36: done. (Waiting for pings)
2017-08-18 14:09:36: In mutex…
2017-08-18 14:09:36: Downloading from channel 0
2017-08-18 14:09:36: Imagesize -1
2017-08-18 14:09:36: ERROR: Error reading size
2017-08-18 14:09:36: Download done -2
2017-08-18 14:09:36: SERVICE_COMMANDS finished
2017-08-18 14:09:36: ClientService cmd: GET DOWNLOADPROGRESS#pw=G7i0NgcsltT0ukZsbIGhMLxnUkQuYu
2017-08-18 14:09:36: Sending progress…
2017-08-18 14:10:36: Started connection to SERVICE_COMMANDS
2017-08-18 14:10:36: ClientService cmd: #ILSnXELDGt6JPNbrXNawG#1CHANNEL capa=4288&token=T2kwdwMXpGECjVt87o6l&restore_version=1&virtual_client=
2017-08-18 14:10:36: New channel: Number of Channels: 1
2017-08-18 14:11:08: Looking for old Sessions… 0 sessions
2017-08-18 14:11:36: ClientService cmd: PONG
2017-08-18 14:12:36: ClientService cmd: PONG
2017-08-18 14:13:36: ClientService cmd: PONG
2017-08-18 14:14:36: ClientService cmd: PONG
2017-08-18 14:15:36: ClientService cmd: PONG

Thanks for the log! Is this with the latest restore (2.1.1)?

No, I’m use restore 2.1.0 !

It should work with 2.1.1

Thank you