FIle backups not starting

I’m running UrBackup Client 0.40.1 on Windows 7 x64 and UrBackup Server 0.26.1 on Debian Squeeze.

Image backups are working fine but file backups starts/completes without any file backups actually happening.

I’ve tried different paths in the Include path for the backups.

debug.log & /var/log/urbackup.log are not showing any errors or warnings.

Any tips for debugging ?

Did you set any paths that it should backup via “Add/remove paths” on the Client tray icon or in the server interface (Included files only works on those paths)

Paths were set from the Client tray icon. These paths also reflect on the server side when viewing the client configs.

Can you see a log entry for the file backup in the web interface?

You get more infos by changing the loglevel:
For the client this can be done in C:\Program files\UrBackup\args.txt
Change the “warn” there to “debug” and restart the service. (UrBackupClientBackend)

I’ve changed the logging level to debug.

From the web interface the file backups look like this:

Computer name Backup time Errors Warnings Action
rcoetzee-HP 2012-08-12 08:09 0 0 Full file backup

Here is the debug.log after changing the log level & starting a full file backup:

08/12/12 08:08:23: Loaded -fileserv- plugin
08/12/12 08:08:23: Loaded -fsimageplugin- plugin
08/12/12 08:08:23: Loaded -cryptoplugin- plugin
08/12/12 08:08:23: Starting as client…
08/12/12 08:08:23: Started UrBackup…
08/12/12 08:08:23: urbackupserver: Server started up sucessfully!
08/12/12 08:08:23: Backup privileges set successfully
08/12/12 08:08:23: Info: retval 0
08/12/12 08:08:23: Info: retval 0
08/12/12 08:08:23: Info: Window size=524288
08/12/12 08:08:23: Binding udp socket…
08/12/12 08:08:23: done.
08/12/12 08:08:23: Disabling new behavior…
08/12/12 08:08:23: Servername: -rcoetzee-HP-
08/12/12 08:08:23: Server started up sucessfully
08/12/12 08:08:23: UDP Thread startet
08/12/12 08:08:24: Looking for old Sessions… 0 sessions
08/12/12 08:08:33: urbackupserver: New Connection incomming 77335873 s: 456
08/12/12 08:08:33: urbackupserver: No available slots… starting new Worker
08/12/12 08:08:33: Incoming data for client…
08/12/12 08:08:33: ClientService cmd: #Ioictweygojnhhabwqvqjwneoxpxyxo#1CHANNEL capa=0
08/12/12 08:08:33: New channel: Number of Channels: 1
08/12/12 08:08:45: Receiving UDP packet…
08/12/12 08:08:45: UDP: PING received… sending PONG
08/12/12 08:08:47: urbackupserver: New Connection incomming 77349694 s: 476
08/12/12 08:08:47: Incoming data for client…
08/12/12 08:08:47: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:08:47: Incoming data for client…
08/12/12 08:08:47: rc=0 hasError=true state=0
08/12/12 08:08:47: urbackupserver: Removing user77349710
08/12/12 08:09:22: urbackupserver: New Connection incomming 77385107 s: 488
08/12/12 08:09:22: Incoming data for client…
08/12/12 08:09:22: ClientService cmd: START BACKUP FULL#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:09:22: Incoming data for client…
08/12/12 08:09:22: rc=0 hasError=true state=0
08/12/12 08:09:22: urbackupserver: Removing user77385122
08/12/12 08:09:22: urbackupserver: New Connection incomming 77385122 s: 488
08/12/12 08:09:22: urbackupserver: New Connection incomming 77385122 s: 492
08/12/12 08:09:22: Incoming data for client…
08/12/12 08:09:22: ClientService cmd: #Ioictweygojnhhabwqvqjwneoxpxyxo#PING RUNNING -0-#token=vpwajufjdvusdcsebmcrxyhcqfrohr
08/12/12 08:09:22: Incoming data for client…
08/12/12 08:09:22: ClientService cmd: #Ioictweygojnhhabwqvqjwneoxpxyxo#2START FULL BACKUP#token=vpwajufjdvusdcsebmcrxyhcqfrohr
08/12/12 08:09:22: Incoming data for client…
08/12/12 08:09:22: rc=0 hasError=true state=0
08/12/12 08:09:22: urbackupserver: Removing user77385138
08/12/12 08:09:22: Incoming data for client…
08/12/12 08:09:22: rc=0 hasError=true state=0
08/12/12 08:09:22: urbackupserver: Removing user77385153
08/12/12 08:09:23: urbackupserver: New Connection incomming 77386136 s: 496
08/12/12 08:09:23: Incoming data for client…
08/12/12 08:09:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:09:23: Incoming data for client…
08/12/12 08:09:23: rc=0 hasError=true state=0
08/12/12 08:09:23: urbackupserver: Removing user77386152
08/12/12 08:09:23: urbackupserver: New Connection incomming 77386167 s: 496
08/12/12 08:09:23: Incoming data for client…
08/12/12 08:09:23: ClientService cmd: #Ioictweygojnhhabwqvqjwneoxpxyxo#2LOGDATA 1344744563
08/12/12 08:09:23: Incoming data for client…
08/12/12 08:09:23: rc=0 hasError=true state=0
08/12/12 08:09:23: urbackupserver: Removing user77386183
08/12/12 08:09:33: Incoming data for client…
08/12/12 08:09:33: ClientService cmd: PONG
08/12/12 08:09:36: Receiving UDP packet…
08/12/12 08:09:36: UDP: PING received… sending PONG
08/12/12 08:10:23: urbackupserver: New Connection incomming 77446165 s: 104
08/12/12 08:10:23: Incoming data for client…
08/12/12 08:10:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:10:23: Incoming data for client…
08/12/12 08:10:23: rc=0 hasError=true state=0
08/12/12 08:10:23: urbackupserver: Removing user77446181
08/12/12 08:10:27: Receiving UDP packet…
08/12/12 08:10:27: UDP: PING received… sending PONG
08/12/12 08:10:33: Incoming data for client…
08/12/12 08:10:33: ClientService cmd: PONG
08/12/12 08:11:18: Receiving UDP packet…
08/12/12 08:11:18: UDP: PING received… sending PONG
08/12/12 08:11:23: urbackupserver: New Connection incomming 77506179 s: 496
08/12/12 08:11:23: Incoming data for client…
08/12/12 08:11:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:11:23: Incoming data for client…
08/12/12 08:11:23: rc=0 hasError=true state=0
08/12/12 08:11:23: urbackupserver: Removing user77506195
08/12/12 08:11:33: Incoming data for client…
08/12/12 08:11:33: ClientService cmd: PONG
08/12/12 08:12:09: Receiving UDP packet…
08/12/12 08:12:09: UDP: PING received… sending PONG
08/12/12 08:12:23: urbackupserver: New Connection incomming 77566193 s: 476
08/12/12 08:12:23: Incoming data for client…
08/12/12 08:12:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:12:23: Incoming data for client…
08/12/12 08:12:23: rc=0 hasError=true state=0
08/12/12 08:12:23: urbackupserver: Removing user77566193
08/12/12 08:12:33: Incoming data for client…
08/12/12 08:12:33: ClientService cmd: PONG
08/12/12 08:13:00: Receiving UDP packet…
08/12/12 08:13:00: UDP: PING received… sending PONG
08/12/12 08:13:23: urbackupserver: New Connection incomming 77626206 s: 476
08/12/12 08:13:23: Incoming data for client…
08/12/12 08:13:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:13:23: Incoming data for client…
08/12/12 08:13:23: rc=0 hasError=true state=0
08/12/12 08:13:23: urbackupserver: Removing user77626222
08/12/12 08:13:33: Incoming data for client…
08/12/12 08:13:33: ClientService cmd: PONG
08/12/12 08:13:51: Receiving UDP packet…
08/12/12 08:13:51: UDP: PING received… sending PONG
08/12/12 08:14:23: urbackupserver: New Connection incomming 77686220 s: 476
08/12/12 08:14:23: Incoming data for client…
08/12/12 08:14:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:14:23: Incoming data for client…
08/12/12 08:14:23: rc=0 hasError=true state=0
08/12/12 08:14:23: urbackupserver: Removing user77686220
08/12/12 08:14:33: Incoming data for client…
08/12/12 08:14:33: ClientService cmd: PONG
08/12/12 08:14:42: Receiving UDP packet…
08/12/12 08:14:42: UDP: PING received… sending PONG
08/12/12 08:15:23: urbackupserver: New Connection incomming 77746233 s: 476
08/12/12 08:15:23: Incoming data for client…
08/12/12 08:15:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:15:23: Incoming data for client…
08/12/12 08:15:23: rc=0 hasError=true state=0
08/12/12 08:15:23: urbackupserver: Removing user77746233
08/12/12 08:15:33: Incoming data for client…
08/12/12 08:15:33: ClientService cmd: PONG
08/12/12 08:15:33: Receiving UDP packet…
08/12/12 08:15:33: UDP: PING received… sending PONG
08/12/12 08:16:23: urbackupserver: New Connection incomming 77806247 s: 476
08/12/12 08:16:23: Incoming data for client…
08/12/12 08:16:23: ClientService cmd: STATUS#pw=XBFlG8VMhfhuimmYRiLMMeIqDk9W40
08/12/12 08:16:23: Incoming data for client…
08/12/12 08:16:23: rc=0 hasError=true state=0
08/12/12 08:16:23: urbackupserver: Removing user77806247
08/12/12 08:16:24: Receiving UDP packet…
08/12/12 08:16:24: UDP: PING received… sending PONG
08/12/12 08:16:33: Incoming data for client…
08/12/12 08:16:33: ClientService cmd: PONG

I’m sorry but I can’t see what is wrong. Maybe on the server side.
Can you put the server into debug mode via editing /etc/default/urbackup_srv (change error or warn to debug) and post the relevant part of /var/log/urbackup.log ?

Thanks.

Good afternoon

Here are the logs from the server:

08/12/12 19:15:41: HTTP: New Connection incomming 2056432 s: 48
08/12/12 19:15:41: HTTP: Waking up…2056432
08/12/12 19:15:41: Incoming data for client…
08/12/12 19:15:41: Parsing done… starting handling request_num: 0 2056433
08/12/12 19:15:41: HTTP: Removing user2056444
08/12/12 19:15:41: HTTP: Sleeping…2056454
08/12/12 19:16:20: msg=START BACKUP FULL
08/12/12 19:16:20: Doing full file backup…
08/12/12 19:16:20: rcoetzee-HP: Connecting for filelist…
08/12/12 19:16:20: rcoetzee-HP: Waiting for filelist
08/12/12 19:16:20: Constructing of filelist of “rcoetzee-HP” failed: no backup dirs
08/12/12 19:16:21: LockForTransaction in CQuery::Execute Stmt: [INSERT INTO files (backupid, fullpath, shahash, filesize, created, rsize, did_count, clientid, incremental) SELECT backupid, fullpath, shahash, filesize, created, rsize, 0 AS did_count, clientid, incremental FROM files_tmp]
08/12/12 19:16:21: Updating statistics…
08/12/12 19:16:21: Updating image stats…
08/12/12 19:16:21: Updating deleted files…
08/12/12 19:16:22: Updating file stats…
08/12/12 19:16:22: Done updating statistics.

Looks like its not seeing the Windows directory I specified “C:\Users*”

I’ve specified it from the client & server side but it still shows this failed message.

Any ideas?

Are you sure you specified them via “Add/Remove Paths” and NOT via “Settings”, because normally you do not see them on the server (Contrary to the included paths settings)?

Problem is fixed.

I removed all the include excludes from both sides & added the paths with the Add/Remove paths.

Works great now. Any plans to maybe push file backups to zip file repositories?

Good.

If you want it compressed you should use fusecompress, zfs or btrfs and store it on that. You can also always use duplicity etc. to backup the “clients” folder in urbackup on the server.

I’ll try btrfs this weekend.

Thanks again

I’d advise to use the latest kernel then (from unstable/sid).