Internet Backup Problem

general discussion
1

HI Ur
thanks for great work, i have test the new feature “backup over internet” every seems to work in the beginning but when the backup rich the 90% of progress it end with communication error. How i can debug this problem? I have activate the debug log on the server but i can see nothing usefull.
Please give how i can help to debug this problem.
Thanks so much

2

Hi,

if you want to you could send the relevant parts of the logs to martin@urbackup.org . The client debug log would be interesting as well. Could you also tell me the error message that causes the backup to fail?

3

Hi Uroni
After your suggestion, i have enhance debug level but i still don’t have log from the Windows Client, very strange… I also made a test with the simple “Full Backup” by internet and this mode is working.
Do you you need some other log to debug the “imageBackup” method?
Many thanks for your great work

4

[quote=“dimiz”]Hi Uroni
After your suggestion, i have enhance debug level but i still don’t have log from the Windows Client, very strange… I also made a test with the simple “Full Backup” by internet and this mode is working.
Do you you need some other log to debug the “imageBackup” method?
Many thanks for your great work[/quote]

Hi,

thanks for your help tracking down that bug. I don’t think it has anything to do with the client. (For the client log: Maybe your changes to args.txt landed in the compatibility layer?)
I have since changed something to the way backups are stopped from the web interface but I don’t think it caused the issue you had. If you want to you could try running a backup
without looking at the web interface to test if the root of the issue lies there.

5

Hi Uroni
as you suggest i will try to test the “Image Backup” without watch the Web GUI and will report the result.
Many thanks

PS: what do you mean with “Maybe your changes to args.txt landed in the compatibility layer?”

6

In windows 7/Vista if you change the args.txt as non-priviledged user windows creates a copy of the file for said non-priviledged user in a compatibility folder. (There is a botton in your explorer window if such files exists)

See e.g. here http://answers.microsoft.com/en-us/windows/forum/windows_vista-files/windows-explorer-compatibility-files/5b377209-cfe4-4be6-959d-e1de4b8be16d

But I just checked and could not save the file at all using notepad. (I always copy the args.txt to the desktop, modify it and then copy it back)

7

Hi Uroni
thanks good to know but i have grant all to my User to solve this issue :-)
Now i can view some more detail in the “debug.log” of the client:

2012-11-04 19:16:07: SERVICE_COMMANDS finished
2012-11-04 19:16:17: Started connection to SERVICE_COMMANDS
2012-11-04 19:16:19: ClientService cmd: #IdgldpVnP52RbxV23VCoa#PING RUNNING -99-#token=jrmgZimt1bWsJhrh393A
2012-11-04 19:16:20: rc=0 hasError=true state=0
2012-11-04 19:16:20: SERVICE_COMMANDS finished
2012-11-04 19:16:30: Started connection to SERVICE_COMMANDS
2012-11-04 19:16:32: ClientService cmd: #IdgldpVnP52RbxV23VCoa#PING RUNNING -100-#token=jrmgZimt1bWsJhrh393A
2012-11-04 19:16:33: rc=0 hasError=true state=0
2012-11-04 19:16:33: SERVICE_COMMANDS finished
2012-11-04 19:16:33: ClientService cmd: PONG
2012-11-04 19:16:36: Started connection to SERVICE_COMMANDS
2012-11-04 19:16:37: ClientService cmd: #IdgldpVnP52RbxV23VCoa#FULL IMAGE letter=C:&token=jrmgZimt1bWsJhrh393A
2012-11-04 19:16:37: orig_target=C: volpath=\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy3
2012-11-04 19:16:37: Started connection to SERVICE_COMMANDS
2012-11-04 19:16:37: ClientService cmd: #IdgldpVnP52RbxV23VCoa#MBR driveletter=C
2012-11-04 19:16:37: rc=0 hasError=true state=0
2012-11-04 19:16:37: SERVICE_COMMANDS finished
2012-11-04 19:16:37: Shadowcopy already present.
2012-11-04 19:16:37: ERROR: Error opening device file (\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy3)
2012-11-04 19:16:37: ERROR: Opening filesystem on device failed. Stopping.
2012-11-04 19:16:37: Device file: “\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy3”
2012-11-04 19:16:37: Sending full image done
2012-11-04 19:16:37: SERVICE_COMMANDS finished
2012-11-04 19:16:37: Started connection to SERVICE_COMMANDS
2012-11-04 19:16:37: ClientService cmd: #IdgldpVnP52RbxV23VCoa#2LOGDATA 1352052998 0-1352050952-Starting full image backup…
0-1352052997-Transferred 24.2892 MB - Average speed: 99.632 KBit/s
2-1352052998-Request of image backup failed. Reason: Opening filesystem on device failed. Stopping.
0-1352052998-Transferred 10.2031 KB - Average speed: 262.016 KBit/s
0-1352052998-Time taken for creating image of client Win7-home: 34m 6s
2-1352052998-Backup not complete because of connection problems

2012-11-04 19:16:37: LockForTransaction in CQuery::Execute Stmt: [INSERT INTO logs (ttime) VALUES (datetime(?, ‘unixepoch’))]
2012-11-04 19:16:38: rc=0 hasError=true state=0
2012-11-04 19:16:38: SERVICE_COMMANDS finished
2012-11-04 19:16:48: urbackupserver: New Connection incomming 86521441 s: 1512
2012-11-04 19:16:48: ClientService cmd: STATUS#pw=uZJX4BsL3exMvSt2iGMNjOUwePq0jd
2012-11-04 19:16:48: rc=0 hasError=true state=0
2012-11-04 19:17:32: ClientService cmd: PONG
2012-11-04 19:17:48: urbackupserver: New Connection incomming 86581447 s: 1452
2012-11-04 19:17:48: ClientService cmd: STATUS#pw=uZJX4BsL3exMvSt2iGMNjOUwePq0jd
2012-11-04 19:17:48: rc=0 hasError=true state=0
2012-11-04 19:18:32: ClientService cmd: PONG
2012-11-04 19:18:48: urbackupserver: New Connection incomming 86641453 s: 1512
2012-11-04 19:18:48: ClientService cmd: STATUS#pw=uZJX4BsL3exMvSt2iGMNjOUwePq0jd
2012-11-04 19:18:48: rc=0 hasError=true state=0

From tab Log on the UrBackup WebGui i see this:

Errors
04.11.12 16:38
Request of image backup failed. Reason: Opening filesystem on device failed. Stopping.
Errors
04.11.12 16:38
Backup not complete because of connection problems

Thanks for your help

8

This looks like a different error. Restarting the UrBackupClientBackend on the client or the client should take care of that.

I think I know what caused this issue and I have taken steps to avoid it in the future, but I can’t be certain without seeing more of the logfile.

9

Hi Uroni
Do you want the full log of the client and the server?
Many thanks

10

If you don’t mind – yes.

11

Hi Uroni
still many thanks for your help! I have mail you the log file, please ask me everything you need to debug this problem.
Regards,
Dimitri

12

Yes it’s the problem I thought it was. Windows removed the shadow copy while the backup was running. I uploaded a version which should handle that case here https://sourceforge.net/projects/urbackup/files/Client/1.0.1RC/ if you want to give it a try.

13

Hi Uroni
Yestarday night i have installed your new UrBackup_Client version and until now seems do the job! The “Image Backup” is in progress from last night and it work, slowly but work.
I’will report the job.
Many thanks

14

Hi Uroni
After 2 days i have stopped the Image Creation but i think is working ;-)
Thanks for your fix and support!!!