Loading urbackup/FILE_METADATA

[daniellog]

Hi,

One of my clients backing up is stuck at 100%. When I check the log its just full of the following,

08/22/16 09:48 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 503.523 MB at 1.144 KBit/s 08/22/16 09:49 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 503.625 MB at 1.096 KBit/s 08/22/16 09:50 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 503.734 MB at 1.168 KBit/s 08/22/16 09:51 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 503.844 MB at 1.144 KBit/s 08/22/16 09:52 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 503.945 MB at 1.096 KBit/s 08/22/16 09:53 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 504.055 MB at 1.088 KBit/s 08/22/16 09:54 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 504.164 MB at 1.136 KBit/s 08/22/16 09:55 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 504.266 MB at 1.08 KBit/s 08/22/16 09:56 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 504.375 MB at 1.168 KBit/s 08/22/16 09:57 DEBUG Loading "urbackup/FILE_METADATA|UwWlUcLdvkPwbNfzWPyY|38". Loaded 504.477 MB at 1.096 KBit/s

Can anyone help me fix this?

Thanks,
Dan

[AckRite]

Check this tread and keep track of it.

Slow FILE_METADATA loading: wrong client token?

After upgrading (1.4.14 -> 2.0.32) both client and server I’m having a problem with one of the clients: backup works ok until loading data stage. Then it is very slow in loading metadata. What I see in server log is: 17/08/16 10:14 DEBUG Loading “urbackup/FILE_METADATA|Ppah6tpwcOR3Vea51e5D|1659”. Loaded 13.3516 MB at 11.36 KBit/s 17/08/16 10:15 DEBUG Loading “urbackup/FILE_METADATA|Ppah6tpwcOR3Vea51e5D|1659”. Loaded 13.4219 MB at 8.536 KBit/s … (one line every minute) Client log is f…

[daniellog]

I’ve seen that topic but there looks to be no solution. Ran a strace and just keep getting timeouts,

poll([{fd=37, events=POLLIN}], 1, 1000) = 0 (Timeout) poll([{fd=37, events=POLLIN}], 1, 1000) = 0 (Timeout) poll([{fd=37, events=POLLIN}], 1, 1000) = 0 (Timeout) poll([{fd=37, events=POLLIN}], 1, 1000) = 0 (Timeout) poll([{fd=37, events=POLLIN}], 1, 1000) = 0 (Timeout)

[uroni]

Please make sure this is really the same issue. Namely:

• The client with this issue is a Linux client
• When looking at the client side log file you see a lot of error messages

Otherwise, you could put the client in debug logging mode and post/send that output. With Windows clients a memory dump of the client process would be helpful.

[daniellog]

1. The client is windows not linux
2. No, Just a couple of can’t access certain files which looks normal. Some
   of our other servers have the same errors and they are backing up fine.

[uroni]

If possible a memory dump of “UrBackupClientBackend.exe” would be helpful: https://superuser.com/questions/133938/how-can-i-dump-the-memory-of-a-process-in-windows-7

[daniellog]

Here is a link to the dump

https://mega.nz/#!qkBWRTBB!S7DVqZS-1rhegCrOm5Ru8-saZ_pQpbFxOGYNPyTHiRc

[daniellog]

Has anyone had a look at the dump? Still having this issue with the latest version.

[uroni]

Sorry and thanks for asking.

Just had a look at it. It seems to be the same problem as Full File Backup Stuck at 96% The machine has a lot of users + I broke the user information caching in client 2.0.31. Will be fixed with the next version (client 2.0.33).

[daniellog]

Hi uroni,

Thats great, thanks for your help. Do you know a rough ETA when the new client will be released?