Internet client connection issue

general discussion
1

Have 1 internet client which will not stay connected to the server to complete a backup.

The machine is a Windows 7 x86 machine with only around 40Kbit/s upload link (slow I know), I have set the bandwidth limit for the client to 20Kbit/s to prevent it flooding
the clients connection. Client has compression & encryption enabled for transfers.

My client log reads like this…

2013-08-08 12:42:58: WARNING: Upgrading...  

2013-08-08 12:42:58: ERROR: Error preparing Query [SELECT tvalue FROM misc WHERE tkey='db_version']: no such table: misc  

2013-08-08 12:44:41: WARNING: Info not found at 'c:' - reindexing
2013-08-08 
12:45:04: WARNING: Info not found at 'd:' - reindexing

And my server logs are giving messages (not client PC with issues is brad-PC)…

2013-08-08 13:06:50: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:00: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:10: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:20: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:30: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:40: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:48: New Connection incomming
2013-08-08 13:07:48: SelectThread woke up...
2013-08-08 13:07:48: Notifying worker...
2013-08-08 13:07:48: Execution Time: 1 ms - time=14633523
2013-08-08 13:07:48: Client disconnected
2013-08-08 13:07:49: New Connection incomming
2013-08-08 13:07:49: SelectThread woke up...
2013-08-08 13:07:49: Notifying worker...
2013-08-08 13:07:49: Execution Time: 1 ms - time=14634817
2013-08-08 13:07:49: Client disconnected
2013-08-08 13:07:50: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:07:50: New Connection incomming
2013-08-08 13:07:50: SelectThread woke up...
2013-08-08 13:07:50: Notifying worker...
2013-08-08 13:07:50: Execution Time: 31 ms - time=14635596
2013-08-08 13:07:50: Client disconnected
2013-08-08 13:07:51: msg=
2013-08-08 13:07:51: Image transfer hashed=true
2013-08-08 13:08:00: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:08:10: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:08:17: Error sending 'running' ping to client
2013-08-08 13:08:17: Timeout: Error sending 'running' ping to client
2013-08-08 13:08:20: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:08:27: Connecting to target service...
2013-08-08 13:08:27: Established internet connection. Service=0
2013-08-08 13:08:28: InternetService: New Connection incomming 14673211 s: 42
2013-08-08 13:08:28: Authed+capa for client 'DRYSERVER' (token auth) - 1 spare connections
2013-08-08 13:08:30: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:08:40: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:08:50: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:09:00: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:09:10: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:09:20: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:09:30: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:09:40: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:09:50: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:10:00: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:10:10: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:10:20: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-08 13:10:25: Client exitet: brad-PC
2013-08-08 13:10:25: Stopping channel...
2013-08-08 13:10:30: server_get Thread for client brad-PC finished
2013-08-08 13:10:30: server_prepare_hash Thread finished
2013-08-08 13:10:30: Setting cachesize to 40960
2013-08-08 13:10:30: server_hash Thread finished - normal
2013-08-08 13:11:15: Client finished: brad-PC

The client with the issue will reconnect to the server every 30 mins or so and try to finish off the backup by starting an incremental but a few mins
after is is disconnected again. The client is behind a firewall with NAT enabled.

Thanks

2

Can you put the client into debug log mode? (Change warn to debug in args.txt)

I’ll set the limit 20 kbit/s as well and see what happens.

3

Managed to reproduce it. Fix should be in http://urpc.dyndns.org/urbackup_unstable/urbackup-server-1.2.4.286.tar.gz .

The throttling did not work for low speeds, that is, it did send too big bursts and then slept too long.

4

Sorry for delays in testing, having filesystem issues on my dev server atm, will provide an update when I can installed new version of urbackup_srv on my test server, hopefull tomorrow as im waiting for a full file backup to complete (7 days so far but all is looking well).

Thanks

5

Beat that:

2013-08-29 11:03:05(info): Transferred 61.3651 GB - Average speed: 471.072 KBit/s
2013-08-29 11:03:05(info): Time taken for backing up client atomsrv: 12 days 23h 36m 32s
:)
6

Have installed the new version “urbackup-server-1.2.4.286.tar.gz” and backup completed successfully first attempt but there were a couple of issues.

Couple of messages in the log file to note …

2013-08-31 12:51:26: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:51:36: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:51:41: Ping timeout in InternetServiceConnector::Run
2013-08-31 12:51:46: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:51:56: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:51:56: Establish timeout: Deleting internet client "DRYSERVER"
2013-08-31 12:52:06: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:52:16: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:52:26: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-08-31 12:52:36: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client

These messages are occurring every 10 secs for client brad-PC (backup was successful though)

Also these are the messages in the log when the backup completed…

2013-08-31 13:00:12: GT: Loaded file "IMG_0224.JPG"
2013-08-31 13:00:12: ERROR: Connecting to ClientService of "brad-PC" failed: Removing shadow copy on "brad-PC" for path "Desktop" failed
2013-08-31 13:00:12: ERROR: Connecting to ClientService of "brad-PC" failed: Sending status (DID BACKUP) to client failed
2013-08-31 13:00:12: PT: Hashing file "IMG_0224.JPG"
2013-08-31 13:00:12: Setting cachesize to 40960
2013-08-31 13:00:12: Setting cachesize to 40960
2013-08-31 13:00:12: HT: Copying file: "/mnt/backups/brad-PC/130831-1242/Desktop/parrots/IMG_0224.JPG"
2013-08-31 13:00:13: New Connection incomming
2013-08-31 13:00:13: SelectThread woke up...
2013-08-31 13:00:13: Notifying worker...
2013-08-31 13:00:13: Execution Time: 3 ms - time=1180426
2013-08-31 13:00:13: Client disconnected
2013-08-31 13:00:14: New Connection incomming
2013-08-31 13:00:14: SelectThread woke up...
2013-08-31 13:00:14: Notifying worker...
2013-08-31 13:00:14: Execution Time: 3 ms - time=1181725
2013-08-31 13:00:14: Client disconnected
2013-08-31 13:00:14: Creating symbolic links. -1
2013-08-31 13:00:14: Creating symbolic links. -2
2013-08-31 13:00:14: Symbolic links created.
2013-08-31 13:00:14: Transferred 10.9653 MB - Average speed: 86.576 KBit/s
2013-08-31 13:00:15: Connecting Channel to ClientService failed - CONNECT error -55
2013-08-31 13:00:15: Time taken for backing up client brad-PC: 18m 12s
2013-08-31 13:00:16: New Connection incomming
2013-08-31 13:00:16: SelectThread woke up...
2013-08-31 13:00:16: Notifying worker...
2013-08-31 13:00:16: Execution Time: 3 ms - time=1183022
2013-08-31 13:00:16: Client disconnected
2013-08-31 13:00:16: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client

The server tells me I have a successful backup but it failed to delete shadow copy & send status to client …

Log (brad-PC)
Level Time Message
Errors

31.08.13 13:00
Connecting to ClientService of “brad-PC” failed: Removing shadow copy on “brad-PC” for path “Desktop” failed
Errors

31.08.13 13:00
Connecting to ClientService of “brad-PC” failed: Sending status (DID BACKUP) to client failed

My Client tells me i have no current backup

Also note that this is only happening on 1 client

Ill enable debug log level and post results

7

Would your prefer results with urbackup-server-1.2.4.286.tar.gz or the latest stable 1.2.4. Either way I believe it is an issue with my
client rather than the server. I will also try disabling all firewalls, anti-virus & configure direct connection rather than NAT. I will also
run the client as another user to see if it makes a difference.

Edit: Test 1

  • Upgraded server to lasted stable built from source (urbackup-server-1.2.4.304)

  • Reinstalled client on client machine

  • Server log debug enabled

  • Restarted client machine

    2013-09-01 13:48:06: InternetService: New Connection incomming 439039 s: 61
    2013-09-01 13:48:06: Authed+capa for client ‘brad-PC’ - 1 spare connections
    2013-09-01 13:48:26: New Connection incomming
    2013-09-01 13:48:26: SelectThread woke up…
    2013-09-01 13:48:26: Notifying worker…
    2013-09-01 13:48:26: Execution Time: 5 ms - time=459817
    2013-09-01 13:48:26: Client disconnected
    2013-09-01 13:48:28: New Connection incomming
    2013-09-01 13:48:28: SelectThread woke up…
    2013-09-01 13:48:28: Notifying worker…
    2013-09-01 13:48:28: Execution Time: 9 ms - time=461998
    2013-09-01 13:48:28: Client disconnected
    2013-09-01 13:48:36: New Connection incomming
    2013-09-01 13:48:36: SelectThread woke up…
    2013-09-01 13:48:36: Notifying worker…
    2013-09-01 13:48:36: SelectThread woke up…
    2013-09-01 13:48:36: Notifying worker…
    2013-09-01 13:48:36: Execution Time: 48 ms - time=469249
    2013-09-01 13:48:36: Client disconnected
    2013-09-01 13:48:41: New Connection incomming
    2013-09-01 13:48:41: SelectThread woke up…
    2013-09-01 13:48:41: Notifying worker…
    2013-09-01 13:48:41: Execution Time: 29 ms - time=474969
    2013-09-01 13:48:41: Client disconnected
    2013-09-01 13:48:45: New Connection incomming
    2013-09-01 13:48:45: SelectThread woke up…
    2013-09-01 13:48:45: Notifying worker…
    2013-09-01 13:48:45: Execution Time: 33 ms - time=478317
    2013-09-01 13:48:45: Client disconnected
    2013-09-01 13:48:48: New Backupclient: brad-PC
    2013-09-01 13:48:48: Connecting to target service…
    2013-09-01 13:48:49: Established internet connection. Service=0
    2013-09-01 13:48:49: InternetService: New Connection incomming 482241 s: 83
    2013-09-01 13:48:49: Created new database connection for urbackup/backup_server.db
    2013-09-01 13:48:49: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:49: Connecting to target service…
    2013-09-01 13:48:50: Established internet connection. Service=0
    2013-09-01 13:48:50: InternetService: New Connection incomming 483244 s: 83
    2013-09-01 13:48:50: Created new database connection for urbackup/backup_server.db
    2013-09-01 13:48:50: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:50: Connecting to target service…
    2013-09-01 13:48:50: Established internet connection. Service=0
    2013-09-01 13:48:51: Getting client settings…
    2013-09-01 13:48:51: InternetService: New Connection incomming 484270 s: 90
    2013-09-01 13:48:51: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:51: Connecting to target service…
    2013-09-01 13:48:52: Established internet connection. Service=1
    2013-09-01 13:48:52: InternetService: New Connection incomming 485337 s: 98
    2013-09-01 13:48:52: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:52: Sending backup incr intervall…
    2013-09-01 13:48:52: Connecting to target service…
    2013-09-01 13:48:53: Established internet connection. Service=0
    2013-09-01 13:48:53: InternetService: New Connection incomming 486475 s: 83
    2013-09-01 13:48:54: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:54: Connecting to target service…
    2013-09-01 13:48:54: Established internet connection. Service=0
    2013-09-01 13:48:54: InternetService: New Connection incomming 487608 s: 90
    2013-09-01 13:48:54: Image transfer hashed=true
    2013-09-01 13:48:54: Starting incremental file backup…
    2013-09-01 13:48:54: brad-PC: Doing backup with hashes…
    2013-09-01 13:48:54: brad-PC: Doing backup with intra file diffs…
    2013-09-01 13:48:54: brad-PC: Connecting for filelist…
    2013-09-01 13:48:55: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:55: Connecting to target service…
    2013-09-01 13:48:55: Established internet connection. Service=0
    2013-09-01 13:48:55: InternetService: New Connection incomming 488721 s: 83
    2013-09-01 13:48:56: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:56: Connecting to target service…
    2013-09-01 13:48:56: Established internet connection. Service=0
    2013-09-01 13:48:56: brad-PC: Waiting for filelist
    2013-09-01 13:48:56: InternetService: New Connection incomming 489797 s: 97
    2013-09-01 13:48:57: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:48:57: Connecting to target service…
    2013-09-01 13:48:57: Established internet connection. Service=0
    2013-09-01 13:48:57: InternetService: New Connection incomming 490806 s: 97
    2013-09-01 13:48:58: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:07: Connecting to target service…
    2013-09-01 13:49:08: Established internet connection. Service=0
    2013-09-01 13:49:08: InternetService: New Connection incomming 501350 s: 97
    2013-09-01 13:49:08: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:18: Connecting to target service…
    2013-09-01 13:49:18: Established internet connection. Service=0
    2013-09-01 13:49:18: InternetService: New Connection incomming 511814 s: 98
    2013-09-01 13:49:19: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:21: Connecting to target service…
    2013-09-01 13:49:21: Connecting to target service…
    2013-09-01 13:49:22: Established internet connection. Service=0
    2013-09-01 13:49:22: Established internet connection. Service=0
    2013-09-01 13:49:22: InternetService: New Connection incomming 515386 s: 37
    2013-09-01 13:49:22: InternetService: New Connection incomming 515396 s: 51
    2013-09-01 13:49:22: Authed+capa for client ‘office2-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:22: Authed+capa for client ‘DRYSERVER’ (token auth) - 1 spare connections
    2013-09-01 13:49:28: Connecting to target service…
    2013-09-01 13:49:29: brad-PC: Doing backup with hashed transfer…
    2013-09-01 13:49:29: brad-PC: Connecting to client…
    2013-09-01 13:49:29: Established internet connection. Service=0
    2013-09-01 13:49:29: InternetService: New Connection incomming 522392 s: 97
    2013-09-01 13:49:29: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:29: Connecting to target service…
    2013-09-01 13:49:30: Established internet connection. Service=1
    2013-09-01 13:49:30: InternetService: New Connection incomming 523465 s: 98
    2013-09-01 13:49:30: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:31: Connecting to target service…
    2013-09-01 13:49:31: Established internet connection. Service=1
    2013-09-01 13:49:31: brad-PC: Loading filelist…
    2013-09-01 13:49:31: InternetService: New Connection incomming 524558 s: 100
    2013-09-01 13:49:32: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:33: brad-PC Starting incremental backup…
    2013-09-01 13:49:33: brad-PC: Calculating file tree differences…
    2013-09-01 13:49:33: brad-PC: Calculating tree difference size…
    2013-09-01 13:49:33: brad-PC: Linking unchanged and loading new files…
    2013-09-01 13:49:33: Created new database connection for urbackup/backup_server.db
    2013-09-01 13:49:33: Connecting to target service…
    2013-09-01 13:49:33: Established internet connection. Service=0
    2013-09-01 13:49:33: InternetService: New Connection incomming 526992 s: 105
    2013-09-01 13:49:34: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:34: Connecting to target service…
    2013-09-01 13:49:34: Established internet connection. Service=0
    2013-09-01 13:49:35: InternetService: New Connection incomming 528073 s: 100
    2013-09-01 13:49:35: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:35: Connecting to target service…
    2013-09-01 13:49:35: Established internet connection. Service=0
    2013-09-01 13:49:36: InternetService: New Connection incomming 529142 s: 105
    2013-09-01 13:49:36: No old file for “Introduction to Oracle 9i PL-SQL Student Guide (Vol 1).rar”
    2013-09-01 13:49:36: Loading file “Introduction to Oracle 9i PL-SQL Student Guide (Vol 1).rar”
    2013-09-01 13:49:36: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:49:39: Connecting to target service…
    2013-09-01 13:49:39: Established internet connection. Service=0
    2013-09-01 13:49:39: InternetService: New Connection incomming 532962 s: 106
    2013-09-01 13:49:40: Authed+capa for client ‘brad-PC’ (token auth) - 1 spare connections
    2013-09-01 13:51:15: Error sending ‘running’ ping to client
    2013-09-01 13:51:15: Timeout: Error sending ‘running’ ping to client
    2013-09-01 13:51:18: GT: Loaded file “Introduction to Oracle 9i PL-SQL Student Guide (Vol 1).rar”
    2013-09-01 13:51:18: No old file for “Introduction to Oracle 9i SQL Student Guide (Vol 2).rar”
    2013-09-01 13:51:18: Loading file “Introduction to Oracle 9i SQL Student Guide (Vol 2).rar”
    2013-09-01 13:51:18: PT: Hashing file “Introduction to Oracle 9i PL-SQL Student Guide (Vol 1).rar”
    2013-09-01 13:51:18: Setting cachesize to 40960
    2013-09-01 13:51:18: HT: Linked file: “/mnt/backups/brad-PC/130901-1348/brad/Introduction to Oracle 9i PL-SQL Student Guide (Vol 1).rar”
    2013-09-01 13:51:23: msg=
    2013-09-01 13:51:23: Getting client settings…
    2013-09-01 13:51:23: Connecting to target service…
    2013-09-01 13:51:24: Established internet connection. Service=0
    2013-09-01 13:51:24: InternetService: New Connection incomming 637263 s: 100
    2013-09-01 13:51:24: Image transfer hashed=true
    2013-09-01 13:51:24: Authed+capa for client ‘office2-PC’ (token auth) - 1 spare connections
    2013-09-01 13:51:25: Connecting to target service…
    2013-09-01 13:51:28: Copying files from tmp table…
    2013-09-01 13:51:28: done.
    2013-09-01 13:51:35: Connecting on internet connection failed. Service=0
    2013-09-01 13:51:36: Establishing internet connection failed. Service=0
    2013-09-01 13:51:36: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:51:56: Establishing internet connection failed. Service=0
    2013-09-01 13:51:56: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client

    2013-09-01 13:52:08: Establish timeout: Deleting internet client “brad-PC”
    2013-09-01 13:52:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:52:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:52:28: msg=
    2013-09-01 13:52:28: Getting client settings…
    2013-09-01 13:52:28: Connecting to target service…
    2013-09-01 13:52:28: Established internet connection. Service=0
    2013-09-01 13:52:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:52:29: InternetService: New Connection incomming 702057 s: 37
    2013-09-01 13:52:29: Image transfer hashed=true
    2013-09-01 13:52:29: Authed+capa for client ‘DRYSERVER’ (token auth) - 1 spare connections
    2013-09-01 13:52:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:52:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:52:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:53:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:53:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:53:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:53:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:53:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:53:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:54:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:54:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:54:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:54:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:54:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:54:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:55:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:55:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:55:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:55:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:55:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:55:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:56:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:56:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:56:24: msg=
    2013-09-01 13:56:24: Image transfer hashed=true
    2013-09-01 13:56:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:56:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:56:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:56:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:57:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:57:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:57:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:57:29: msg=
    2013-09-01 13:57:29: Image transfer hashed=true
    2013-09-01 13:57:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:57:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:57:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:58:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:58:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:58:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:58:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:58:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:58:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:59:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:59:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:59:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:59:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:59:48: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 13:59:58: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 14:00:08: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 14:00:18: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 14:00:28: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client
    2013-09-01 14:00:38: Connecting to ClientService of “brad-PC” failed: Error sending ‘running’ ping to client

Client debug file looks something like this…

2013-09-01 13:36:22: WARNING: Upgrading...
2013-09-01 13:36:22: ERROR: Error preparing Query [SELECT tvalue FROM misc WHERE tkey='db_version']: no such table: misc
2013-09-01 13:40:28: WARNING: Info not found at 'c:' - reindexing
2013-09-01 13:40:36: WARNING: Info not found at 'd:' - reindexing
2013-09-01 13:45:27: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 13:45:27: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 13:45:28: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 13:45:28: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\

Backup still in progress atm ill let you know the final results. The client icon is currently showing Red. ie: “No current backup”. Server is still processing the data from the client though as I write this.

Edit: OK has finished now successfully as far as server is concerned. Client still showing red and “No current backup”.

Relevant ending of log once backup completed…

2013-09-01 20:59:48: HT: Copying file: "/mnt/backups/brad-PC/130901-1845/something/SavedVariables/oqueue.lua"
2013-09-01 20:59:49: GT: Loaded file "oqueue.lua.bak"
2013-09-01 20:59:49: PT: Hashing file "oqueue.lua.bak"
2013-09-01 20:59:49: HT: Copying file: "/mnt/backups/brad-PC/130901-1845/something/oqueue.lua.bak"
2013-09-01 20:59:49: Connecting to target service...
2013-09-01 20:59:54: Establish timeout: Deleting internet client "brad-PC"
2013-09-01 20:59:56: Connecting to ClientService of "brad-PC" failed: Error sending 'running' ping to client
2013-09-01 20:59:59: Connecting on internet connection failed. Service=0
2013-09-01 20:59:59: ERROR: Connecting to ClientService of "brad-PC" failed: Removing shadow copy on "brad-PC" for path "WTF" failed
2013-09-01 20:59:59: ERROR: Connecting to ClientService of "brad-PC" failed: Sending status (DID BACKUP) to client failed
2013-09-01 21:00:00: Setting cachesize to 40960
2013-09-01 21:00:01: Creating symbolic links. -1
2013-09-01 21:00:01: Creating symbolic links. -2
2013-09-01 21:00:01: Symbolic links created.
2013-09-01 21:00:01: Transferred 82.6497 MB - Average speed: 86.392 KBit/s
2013-09-01 21:00:01: Time taken for backing up client brad-PC: 2h 14m 15s
2013-09-01 21:00:02: WARNING: Connecting to ClientService of "brad-PC" failed: Sending logdata to client failed
2013-09-01 21:00:02: Updating statistics...
2013-09-01 21:00:02: Updating image stats...
2013-09-01 21:00:02: Updating deleted files...
2013-09-01 21:00:02: Updating file stats...
2013-09-01 21:00:02: File processing speed: 0.000457434 files/s
2013-09-01 21:00:02: Updating files stats: 1%
2013-09-01 21:00:02: File processing speed: 4000 files/s
2013-09-01 21:00:02: Updating files stats: 2%
2013-09-01 21:00:02: File processing speed: 5000 files/s
2013-09-01 21:00:02: Updating files stats: 3%
2013-09-01 21:00:02: File processing speed: 2500 files/s
2013-09-01 21:00:02: Updating files stats: 4%
2013-09-01 21:00:02: File processing speed: 4000 files/s
2013-09-01 21:00:02: Updating files stats: 5%
2013-09-01 21:00:02: File processing speed: 5000 files/s
2013-09-01 21:00:02: Updating files stats: 6%
2013-09-01 21:00:02: Updating files stats: 7%
2013-09-01 21:00:02: File processing speed: 9000 files/s
2013-09-01 21:00:02: Updating files stats: 8%
2013-09-01 21:00:02: Updating files stats: 9%
2013-09-01 21:00:02: File processing speed: 9000 files/s
2013-09-01 21:00:02: Updating files stats: 10%

and this the client log…

2013-09-01 13:36:22: WARNING: Upgrading...
2013-09-01 13:36:22: ERROR: Error preparing Query [SELECT tvalue FROM misc WHERE tkey='db_version']: no such table: misc
2013-09-01 13:40:28: WARNING: Info not found at 'c:' - reindexing
2013-09-01 13:40:36: WARNING: Info not found at 'd:' - reindexing
2013-09-01 13:45:27: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 13:45:27: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 13:45:28: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 13:45:28: ERROR: Couldn't follow up to root. Current path: $RmMetadata\$TxfLog\
2013-09-01 14:52:21: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 14:52:40: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 15:58:40: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 15:59:06: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 17:02:39: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 17:02:56: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 17:41:38: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 17:41:54: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 18:45:50: WARNING: Removing reference because restart own was specified and only own tokens are present
2013-09-01 18:46:03: WARNING: Removing reference because restart own was specified and only own tokens are present
8

I connected the client via a wireless 3g stick and the backup worked flawlessly. (updated client status through the backup & no connection errors in server log) Prior to this i tested with firewalls disabled & AV software disabled with no luck.

I believe the issue resided within the dsl modem router configuration the client was using but cannot verify now because ive moved house. Strangely im using the same modem / router still but I
did a factory reset before i configured it at the new address and all it working well.

I noticed Urbackup is using both TCP & UDP for internet clients and im thinking UDP is used for status updates information between the client & the server, whereas TCP for the actual data being transferred to the server. Im wondering it maybe my modems firewall / security had of been blocking UDP (either a hard block or or because it detected the traffic as a flood or DOS attack) and thus the reasons for the issues. Or maybe some NAT issue.

Anyway all is good now, my problem is solved.

Thanks for all your help, have Urbackup working very stable now on all my Windows clients. Next stage ill be connecting some Linux clients.

9

Hi All!

I have a problem with the internet client on Win10.
I’ve installed the 2.4.11 client on a laptop.
The connection is up and running:
ur1

The installer has generated from the server side were version is: 2.4.13.
When i try to start a backup form web gui, i get the following error message:

ur2
ur21204×64 4.37 KB

The necessary ports are opened in the router, Interned mode has enabled in the server.
I have a public IP, so i think dyndns etc, not necessary. And the connection is up, you can see.
Have you any idea what is the problem with my settings?

ur3
ur3890×631 357 KB

Many Thanks!
Feko