[Box Backup] Boxbackup 0.10 - Debian server - Mac OS X client - 7/41 errors - logs included

Tobias Balle-Petersen boxbackup@fluffy.co.uk
Tue, 13 Jun 2006 11:47:05 +0200


Hello.

I have been getting 7/41 error problems with a setup consisting of:
1 Linux Debian Boxbackup 0.10 server on LAN (Name: minimoni).
1 Mac OS X 10.4 Boxbackup 0.10 client on LAN (Name: yoiko).
1 Mac OS X 10.4 Boxbackup 0.10 server on WAN (Remote datacenter) (Name: 
maigo).


The client backs up to the 2 servers. On the client i have these 
processes running:
root       638   0.0 -0.3    35548   5364  ??  S    Thu05PM 161:25.08 
/usr/local/bin/bbackupd_debug /etc/box_maigo/bbackupd.conf
root       641   0.0 -3.1   103408  65184  ??  S    Thu05PM 155:50.22 
/usr/local/bin/bbackupd_debug /etc/box_minimoni/bbackupd.conf

As seen above, the client is running the debug version of bbackupd.


Im running the client in snapshot mode.


On the client i start the backups from the crontab:
# Ekstern backup
0 17 * * * /usr/local/bin/bbackupctl -c /etc/box_maigo/bbackupd.conf sync
# Backup til minimoni
0 20 * * * /usr/local/bin/bbackupctl -c /etc/box_minimoni/bbackupd.conf sync


In the logs seen at the end of this post. It appears theres is a long 
wait which results in
a timeout. What could be the cause of this?


It seems, that if I kill and restart the /usr/local/bin/bbackupd_debug 
/etc/box_minimoni/bbackupd.conf
process, the backup will run succesfully the first time.

In the client log, boxbackup says "...reset state and waiting to 
retry..". I have never seen it trying to restart?



My config file /etc/box_minimoni/bbackupd.conf looks like this:
------------------------------------------------------------------
StoreHostname = minimoni.local.kontrapunkt.dk
AccountNumber = 0xA
KeysFile = /etc/box_minimoni/bbackupd/A-FileEncKeys.raw

CertificateFile = /etc/box_minimoni/bbackupd/A-cert.pem
PrivateKeyFile = /etc/box_minimoni/bbackupd/A-key.pem
TrustedCAsFile = /etc/box_minimoni/bbackupd/serverCA.pem

DataDirectory = /var/bbackupd_minimoni

KeepAliveTime = 30


# This script is run whenever bbackupd encounters a problem which requires
# the system administrator to assist:
# 1) The store is full, and no more data can be uploaded.
# 2) Some files or directories were not readable.
# The default script emails the system administrator.

NotifyScript = /etc/box_minimoni/bbackupd/NotifySysadmin.sh


# This configuration file is written for snapshot mode.
# You will need to run bbackupctl to instruct the daemon to upload files.

AutomaticBackup = no
UpdateStoreInterval = 0
MinimumFileAge = 0
MaxUploadWait = 0


# Files above this size (in bytes) are tracked, and if they are renamed 
they will simply be
# renamed on the server, rather than being uploaded again. (64k - 1)

FileTrackingSizeThreshold = 65535


# The daemon does "changes only" uploads for files above this size (in 
bytes).
# Files less than it are uploaded whole without this extra processing.

DiffingUploadSizeThreshold = 8192


# The limit on how much time is spent diffing files. Most files 
shouldn't take very long,
# but if you have really big files you can use this to limit the time 
spent diffing them.
# * Reduce if you are having problems with processor usage.
# * Increase if you have large files, and think the upload of changes is 
too large and want
#   to spend more time searching for unchanged blocks.

MaximumDiffingTime = 20


# Uncomment this line to see exactly what the daemon is going when it's 
connected to the server.

# ExtendedLogging = yes


# Use this to temporarily stop bbackupd from syncronising or connecting 
to the store.
# This specifies a program or script script which is run just before 
each sync, and ideally
# the full path to the interpreter. It will be run as the same user 
bbackupd is running as,
# usually root.
# The script prints either "now" or a number to STDOUT (and a 
terminating newline, no quotes).
# If the result was "now", then the sync will happen. If it's a number, 
then the script will
# be asked again in that number of seconds.
# For example, you could use this on a laptop to only backup when on a 
specific network.

# SyncAllowScript = /path/to/intepreter/or/exe script-name parameters etc


# Where the command socket is created in the filesystem.

CommandSocket = /var/run/bbackupd_minimoni.sock

# Uncomment the StoreObjectInfoFile to enable the experimental archiving
# of the daemon's state (including client store marker and configuration)
# between backup runs. This saves time and increases efficiency when
# bbackupd is frequently stopped and started, since it removes the need
# to rescan all directories on the remote server. However, it is new and
# not yet heavily tested, so use with caution.

# StoreObjectInfoFile = /var/bbackupd_minimoni/bbackupd.state

Server
{
        PidFile = /var/run/bbackupd_minimoni.pid
}

#
# BackupLocations specifies which locations on disc should be backed up. 
Each
# directory is in the format
#
#       name
#       {
#               Path = /path/of/directory
#               (optional exclude directives)
#       }
#
# 'name' is derived from the Path by the config script, but should merely be
# unique.
#
# The exclude directives are of the form
#
#       [Exclude|AlwaysInclude][File|Dir][|sRegex] = regex or full pathname
#
# (The regex suffix is shown as 'sRegex' to make File or Dir plural)
#
# For example:
#
#       ExcludeDir = /home/guest-user
#       ExcludeFilesRegex = *.(mp3|MP3)$
#       AlwaysIncludeFile = /home/username/veryimportant.mp3
#
# This excludes the directory /home/guest-user from the backup along 
with all mp3
# files, except one MP3 file in particular.
#
# In general, Exclude excludes a file or directory, unless the directory is
# explicitly mentioned in a AlwaysInclude directive.
#
# If a directive ends in Regex, then it is a regular expression rather 
than a
# explicit full pathname. See
#
#       man 7 re_format
#
# for the regex syntax on your platform.
#

BackupLocations
{
        backups
        {
                Path = /Volumes/raidA/BACKUPS
        }
        files
        {
                Path = /Volumes/raidA/files
                ExcludeFile = .DS_Store
        }
}
--------------------------------------------------------------------------------------------------------


Below are the logs for the server (minimoni) and the client (yoiko) for 
june 11th and 12th
 

--------------------------------------------------------------------------------------------------------

Client yoiko(June 11th):
Jun 11 20:00:00 yoiko bbackupd[641]: Incoming connection from local 
(UNIX socket)
Jun 11 20:00:00 yoiko bbackupd[641]: Connection from command socket
Jun 11 20:00:00 yoiko bbackupd[641]: TRACE: Receiving command 'sync' 
over command socket\n
Jun 11 20:00:00 yoiko bbackupd[641]: TRACE: Receiving command 'quit' 
over command socket\n
Jun 11 20:00:00 yoiko bbackupd[641]: TRACE: Closing command connection\n
Jun 11 20:00:00 yoiko bbackupd[641]: Beginning scan of local files
Jun 11 20:00:00 yoiko bbackupd[641]: Opening connection to server 
minimoni.local.kontrapunkt.dk...
Jun 11 20:00:01 yoiko bbackupd[641]: TRACE: Send block allocation size 
is 4\n
Jun 11 20:00:01 yoiko bbackupd[641]: Connection made, login successful
Jun 11 20:00:01 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 3244\n
Jun 11 20:00:01 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 3244 to 5420\n
Jun 11 20:42:37 yoiko bbackupd[641]: TRACE: Exception thrown: 
ConnectionException(Conn_TLSReadFailed) at SocketStreamTLS.cpp(361)\n
Jun 11 20:42:37 yoiko bbackupd[641]: Finished scan of local files
Jun 11 20:42:37 yoiko bbackupd[641]: File statistics: total file size 
uploaded 5116915, bytes already on server 0, encoded size 5196105
Jun 11 20:42:37 yoiko bbackupd[641]: TRACE: Wait on command socket, 
delay = 1024000000\n

Client yoiko (June 12th):
Jun 12 20:00:00 yoiko bbackupd[641]: Incoming connection from local 
(UNIX socket)
Jun 12 20:00:00 yoiko bbackupd[641]: Connection from command socket
Jun 12 20:00:00 yoiko bbackupd[641]: TRACE: Receiving command 'sync' 
over command socket\n
Jun 12 20:00:00 yoiko bbackupd[641]: TRACE: Receiving command 'quit' 
over command socket\n
Jun 12 20:00:00 yoiko bbackupd[641]: TRACE: Closing command connection\n
Jun 12 20:00:00 yoiko bbackupd[641]: Beginning scan of local files
Jun 12 20:00:00 yoiko bbackupd[641]: Opening connection to server 
minimoni.local.kontrapunkt.dk...
Jun 12 20:00:00 yoiko bbackupd[641]: TRACE: Send block allocation size 
is 4\n
Jun 12 20:00:00 yoiko bbackupd[641]: Connection made, login successful
Jun 12 20:00:00 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 3244\n
Jun 12 20:00:00 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 3244 to 5420\n
Jun 12 20:00:02 yoiko bbackupd[641]: TRACE: Deleted directory record for 
/Volumes/raidA/files/.TemporaryItems/folders.1094/TemporaryItems\n
Jun 12 20:00:03 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:00:03 yoiko bbackupd[641]: TRACE: Diff: 103936 new bytes 
found, 1 old blocks used\n
Jun 12 20:00:03 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 20:00:03 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 2383\n
.......................
Jun 12 20:31:55 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 2171\n
Jun 12 20:32:07 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 2899\n
Jun 12 20:32:07 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 2899 to 5075\n
Jun 12 20:32:07 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:32:08 yoiko bbackupd[641]: TRACE: Diff: 121216 new bytes 
found, 24 old blocks used\n
Jun 12 20:32:08 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 20:32:08 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 2890\n
Jun 12 20:32:08 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 2890 to 5066\n
Jun 12 20:36:48 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:37:07 yoiko bbackupd[641]: TRACE: Diff: 91963392 new bytes 
found, 1102 old blocks used\n
Jun 12 20:37:07 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 20:37:07 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 4140 to 6235\n
Jun 12 20:37:19 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 6235 to 8411\n
Jun 12 20:37:19 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 8411 to 10587\n
Jun 12 20:37:19 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 10587 to 12763\n
Jun 12 20:37:19 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 12763 to 14939\n
Jun 12 20:37:19 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 14939 to 15083\n
Jun 12 20:37:19 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 15083 to 16767\n
Jun 12 20:37:32 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:37:33 yoiko bbackupd[641]: TRACE: Diff: 147456 new bytes 
found, 1984 old blocks used\n
Jun 12 20:37:33 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 20:37:33 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1068 to 3244\n
Jun 12 20:37:35 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:37:36 yoiko bbackupd[641]: TRACE: Diff: 315392 new bytes 
found, 1985 old blocks used\n
Jun 12 20:37:36 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 20:37:36 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1082 to 2784\n
Jun 12 20:37:36 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 2784 to 3750\n
Jun 12 20:37:39 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:37:40 yoiko bbackupd[641]: TRACE: Diff: 90036 new bytes found, 
89 old blocks used\n
Jun 12 20:37:40 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 20:37:40 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 1098 to 3274\n
Jun 12 20:37:40 yoiko bbackupd[641]: TRACE: Reallocating EncodingBuffer 
from 3274 to 5381\n
Jun 12 20:38:22 yoiko bbackupd[641]: TRACE: Initiated timer for file 
diff control\n
Jun 12 20:38:22 yoiko bbackupd[641]: TRACE: Diff: 0 new bytes found, 21 
old blocks used\n
Jun 12 20:38:22 yoiko bbackupd[641]: TRACE: Suspended timer for file 
diff control\n
Jun 12 21:03:41 yoiko bbackupd[641]: TRACE: Exception thrown: 
ConnectionException(Conn_TLSReadFailed) at SocketStreamTLS.cpp(361)\n
Jun 12 21:03:41 yoiko bbackupd[641]: TRACE: Exception thrown: 
ConnectionException(Conn_TLSWriteFailed) at SocketStreamTLS.cpp(426)\n
Jun 12 21:03:42 yoiko bbackupd[641]: Exception caught (Connection 
TLSReadFailed 7/34), reset state and waiting to retry...
Jun 12 21:03:52 yoiko bbackupd[641]: File statistics: total file size 
uploaded 5970844021, bytes already on server 74397520, encoded size 
4989813100
Jun 12 21:03:52 yoiko bbackupd[641]: TRACE: Wait on command socket, 
delay = 1024000000\n

--------------------------------------------------------------------------------------------------------

Server minimoni(June 11th):
Jun 11 20:00:00 localhost bbstored[3088]: Incoming connection from 
192.168.0.51 port 64848 (handling in child 8328)
Jun 11 20:00:01 localhost bbstored[8328]: Certificate CN: BACKUP-A
Jun 11 20:00:01 localhost bbstored[8328]: Receive Version(0x1)
Jun 11 20:00:01 localhost bbstored[8328]: Receive Version(0x1)
Jun 11 20:00:01 localhost bbstored[8328]: Send Version(0x1)
Jun 11 20:00:01 localhost bbstored[8328]: Send Version(0x1)
Jun 11 20:00:01 localhost bbstored[8328]: Receive Login(0xa,0x0)
Jun 11 20:00:01 localhost bbstored[8328]: Receive Login(0xa,0x0)
Jun 11 20:00:01 localhost bbstored[8328]: Login: Client ID 0000000A, 
Read/Write
Jun 11 20:00:01 localhost bbstored[8328]: Send 
LoginConfirmed(0x415e7d02bc240,0x12116358,0x17700000,0x19c80000)
Jun 11 20:00:01 localhost bbstored[8328]: Send 
LoginConfirmed(0x415e7d02bc240,0x12116358,0x17700000,0x19c80000)
Jun 11 20:00:01 localhost bbstored[8328]: Receive 
ListDirectory(0x2f,0xffffffff,0xc,true)
Jun 11 20:00:01 localhost bbstored[8328]: Receive 
ListDirectory(0x2f,0xffffffff,0xc,true)
Jun 11 20:00:01 localhost bbstored[8328]: Send Success(0x2f)
Jun 11 20:00:01 localhost bbstored[8328]: Send Success(0x2f)
Jun 11 20:00:01 localhost bbstored[8328]: Sending stream, size 4841
Jun 11 20:00:01 localhost bbstored[8328]: Receive 
StoreFile(0x2f,0x415e931a060c0,0x811e6b90e98e9194,0x0,OPAQUE)
Jun 11 20:00:01 localhost bbstored[8328]: Receive 
StoreFile(0x2f,0x415e931a060c0,0x811e6b90e98e9194,0x0,OPAQUE)
Jun 11 20:00:01 localhost bbstored[8328]: Receiving stream, size uncertain
Jun 11 20:00:02 localhost bbstored[8328]: Send Success(0xda80f)
Jun 11 20:00:02 localhost bbstored[8328]: Send Success(0xda80f)
Jun 11 20:15:02 localhost bbstored[8328]: Connection statistics for 
BACKUP-A: IN=5196351 OUT=4965 TOTAL=5201316
Jun 11 20:15:02 localhost bbstored[8328]: in server child, exception 
Connection Protocol_Timeout (Probably a network issue between client and 
server.) (7/41) -- terminating child
Jun 11 20:29:12 localhost bbstored/hk[3089]: Starting housekeeping
Jun 11 20:29:51 localhost bbstored/hk[3089]: On housekeeping, sizes in 
store do not match calculated sizes, correcting
Jun 11 20:29:51 localhost bbstored/hk[3089]: different (store,calc): acc 
0x0000000a, used (303129432,303130701), old (398920,398920), deleted 
(6927317,6927317), dirs (114176,114176)
Jun 11 20:29:53 localhost bbstored/hk[3089]: Finished housekeeping
Jun 11 21:29:12 localhost bbstored/hk[3089]: Starting housekeeping
Jun 11 21:29:52 localhost bbstored/hk[3089]: Finished housekeeping
Jun 11 22:29:12 localhost bbstored/hk[3089]: Starting housekeeping

Server minimoni (June 12th):
Jun 12 20:00:00 localhost bbstored[3088]: Incoming connection from 
192.168.0.51 port 58006 (handling in child 31754)
Jun 12 20:00:00 localhost bbstored[31754]: Certificate CN: BACKUP-A
Jun 12 20:00:00 localhost bbstored[31754]: Receive Version(0x1)
Jun 12 20:00:00 localhost bbstored[31754]: Receive Version(0x1)
Jun 12 20:00:00 localhost bbstored[31754]: Send Version(0x1)
Jun 12 20:00:00 localhost bbstored[31754]: Send Version(0x1)
Jun 12 20:00:00 localhost bbstored[31754]: Receive Login(0xa,0x0)
Jun 12 20:00:00 localhost bbstored[31754]: Receive Login(0xa,0x0)
Jun 12 20:00:00 localhost bbstored[31754]: Login: Client ID 0000000A, 
Read/Write
Jun 12 20:00:00 localhost bbstored[31754]: Send 
LoginConfirmed(0x415e7d02bc240,0x1211684d,0x17700000,0x19c80000)
Jun 12 20:00:00 localhost bbstored[31754]: Send 
LoginConfirmed(0x415e7d02bc240,0x1211684d,0x17700000,0x19c80000)
Jun 12 20:00:00 localhost bbstored[31754]: Receive 
ListDirectory(0x2f,0xffffffff,0xc,true)
Jun 12 20:00:00 localhost bbstored[31754]: Receive 
ListDirectory(0x2f,0xffffffff,0xc,true)
Jun 12 20:00:00 localhost bbstored[31754]: Send Success(0x2f)
Jun 12 20:00:00 localhost bbstored[31754]: Send Success(0x2f)
Jun 12 20:00:00 localhost bbstored[31754]: Sending stream, size 4913
Jun 12 20:00:00 localhost bbstored[31754]: Receive 
StoreFile(0x2f,0x415fd4f687e80,0xea5d2382abba2ff3,0x0,OPAQUE)
Jun 12 20:00:00 localhost bbstored[31754]: Receive 
StoreFile(0x2f,0x415fd4f687e80,0xea5d2382abba2ff3,0x0,OPAQUE)
Jun 12 20:00:00 localhost bbstored[31754]: Receiving stream, size uncertain
Jun 12 20:00:01 localhost bbstored[31754]: Send Success(0xda810)
Jun 12 20:00:01 localhost bbstored[31754]: Send Success(0xda810)
Jun 12 20:00:01 localhost bbstored[31754]: Receive 
ListDirectory(0xb30,0xffffffff,0xc,true)
Jun 12 20:00:01 localhost bbstored[31754]: Receive 
ListDirectory(0xb30,0xffffffff,0xc,true)
...................
Jun 12 20:37:41 localhost bbstored[31754]: Sending stream, size 2023
Jun 12 20:37:41 localhost bbstored[31754]: Receive 
ListDirectory(0xd8d44,0xffffffff,0xc,true)
Jun 12 20:37:41 localhost bbstored[31754]: Receive 
ListDirectory(0xd8d44,0xffffffff,0xc,true)
Jun 12 20:37:41 localhost bbstored[31754]: Send Success(0xd8d44)
Jun 12 20:37:41 localhost bbstored[31754]: Send Success(0xd8d44)
Jun 12 20:37:41 localhost bbstored[31754]: Sending stream, size 2021
Jun 12 20:37:41 localhost bbstored[31754]: Receive 
ListDirectory(0x459aa,0xffffffff,0xc,true)
Jun 12 20:37:41 localhost bbstored[31754]: Receive 
ListDirectory(0x459aa,0xffffffff,0xc,true)
Jun 12 20:37:41 localhost bbstored[31754]: Send Success(0x459aa)
Jun 12 20:37:41 localhost bbstored[31754]: Send Success(0x459aa)
Jun 12 20:37:41 localhost bbstored[31754]: Sending stream, size 1059
Jun 12 20:37:41 localhost bbstored[31754]: Receive 
ListDirectory(0xd8d49,0xffffffff,0xc,true)
Jun 12 20:37:41 localhost bbstored[31754]: Receive 
ListDirectory(0xd8d49,0xffffffff,0xc,true)
Jun 12 20:37:41 localhost bbstored[31754]: Send Success(0xd8d49)
Jun 12 20:37:41 localhost bbstored[31754]: Send Success(0xd8d49)
Jun 12 20:37:41 localhost bbstored[31754]: Sending stream, size 578
Jun 12 20:38:22 localhost bbstored[31754]: Receive 
ListDirectory(0x48989,0xffffffff,0xc,true)
Jun 12 20:38:22 localhost bbstored[31754]: Receive 
ListDirectory(0x48989,0xffffffff,0xc,true)
Jun 12 20:38:22 localhost bbstored[31754]: Send Success(0x48989)
Jun 12 20:38:22 localhost bbstored[31754]: Send Success(0x48989)
Jun 12 20:38:22 localhost bbstored[31754]: Sending stream, size 1105
Jun 12 20:38:22 localhost bbstored[31754]: Receive 
GetBlockIndexByName(0x48989,OPAQUE)
Jun 12 20:38:22 localhost bbstored[31754]: Receive 
GetBlockIndexByName(0x48989,OPAQUE)
Jun 12 20:38:22 localhost bbstored[31754]: Send Success(0x48997)
Jun 12 20:38:22 localhost bbstored[31754]: Send Success(0x48997)
Jun 12 20:38:22 localhost bbstored[31754]: Sending stream, size 700
Jun 12 20:38:22 localhost bbstored[31754]: Receive 
StoreFile(0x48989,0x416035a819f80,0x21bf60c1da071a8c,0x48997,OPAQUE)
Jun 12 20:38:22 localhost bbstored[31754]: Receive 
StoreFile(0x48989,0x416035a819f80,0x21bf60c1da071a8c,0x48997,OPAQUE)
Jun 12 20:38:22 localhost bbstored[31754]: Receiving stream, size uncertain
Jun 12 20:38:22 localhost bbstored[31754]: Send Success(0xdaa21)
Jun 12 20:38:22 localhost bbstored[31754]: Send Success(0xdaa21)
Jun 12 20:40:24 localhost bbstored[31754]: Receive 
ListDirectory(0x51b6e,0xffffffff,0xc,true)
Jun 12 20:40:24 localhost bbstored[31754]: Receive 
ListDirectory(0x51b6e,0xffffffff,0xc,true)
Jun 12 20:40:24 localhost bbstored[31754]: Send Success(0x51b6e)
Jun 12 20:40:24 localhost bbstored[31754]: Send Success(0x51b6e)
Jun 12 20:40:24 localhost bbstored[31754]: Sending stream, size 7073
Jun 12 20:55:24 localhost bbstored[31754]: Connection statistics for 
BACKUP-A: IN=4990013984 OUT=5437254 TOTAL=4995451238
Jun 12 20:55:24 localhost bbstored[31754]: in server child, exception 
Connection Protocol_Timeout (Probably a network issue between client and 
server.) (7/41) -- terminating child
Jun 12 21:29:12 localhost bbstored/hk[3089]: Starting housekeeping
Jun 12 21:37:20 localhost bbstored/hk[3089]: On housekeeping, sizes in 
store do not match calculated sizes, correcting
Jun 12 21:37:20 localhost bbstored/hk[3089]: different (store,calc): acc 
0x0000000a, used (304327145,304349722), old (424813,426042), deleted 
(6927317,6927317), dirs (114239,114243)
Jun 12 21:37:21 localhost bbstored/hk[3089]: Finished housekeeping
Jun 12 22:29:12 localhost bbstored/hk[3089]: Starting housekeeping


Thank you,
Tobias