[Box Backup] Connection fails during backup to remote server *Resolved*

Matt Brown boxbackup@fluffy.co.uk
Fri, 25 Apr 2008 10:13:13 +0100


Hi Chris,

> I will keep you posted.

The patch is working great for me ...

The server and store have now fully sync'd but here was an example  
from earlier this week which caused a few retry events...

Apr 21 23:29:00 orion Backup (bbackupd)[27749]: Synchronised file: / 
data/Documents_unfiled/Leon 2.dvdproj/Contents/Resources/ 
MPEG 
/._V_ED4C146C5942D440FD5F228236F9FDCE6D6F7669_6000000_P_S1447.960_E2229.880
Apr 21 23:29:00 orion Backup (bbackupd)[27749]: Uploading complete  
file: /data/Documents_unfiled/Leon 2.dvdproj/Contents/Resources/MPEG/ 
V_ED4C146C5942D440FD5F228236F9FDCE6D6F7669_6000000_P_S2229.880_E2894.840
Apr 22 01:45:05 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_TLSWriteFailed) at  
SocketStreamTLS.cpp(442)
Apr 22 01:45:05 orion Backup (bbackupd)[27749]: ERROR: Failed to  
upload file: /data/Documents_unfiled/Leon 2.dvdproj/Contents/Resources/ 
MPEG/ 
V_ED4C146C5942D440FD5F228236F9FDCE6D6F7669_6000000_P_S2229.880_E2894.840 
: caught exception: Connection TLSWriteFailed (Probably a network  
issue between client and server.) (7/33)
Apr 22 01:45:05 orion Backup (bbackupd)[27749]: ERROR: SSL error  
during Write: error:1409F07F:SSL routines:SSL3_WRITE_PENDING:bad write  
retry
Apr 22 01:45:05 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_TLSWriteFailed) at  
SocketStreamTLS.cpp(442)
Apr 22 01:45:05 orion Backup (bbackupd)[27749]: NOTICE: About to  
notify administrator about event backup-error, running script '/etc/ 
box/bbackupd/NotifySysadmin.sh backup-error'
Apr 22 01:45:05 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection TLSWriteFailed (Probably a network issue between  
client and server.) 7/33), reset state and waiting to retry...
Apr 22 01:45:15 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 753134760, bytes already on  
server 95948772, encoded size 475659264
Apr 22 01:45:15 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:45:15 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxxxxx'...
Apr 22 01:45:20 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:45:20 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:45:20 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:45:30 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:46:45 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:46:45 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:46:50 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:46:50 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:46:50 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:47:00 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:48:15 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:48:15 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:48:20 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:48:20 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:48:20 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:48:30 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:49:45 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:49:45 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:49:50 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:49:50 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:49:50 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:50:00 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:51:15 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:51:15 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:51:20 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:51:20 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:51:20 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:51:30 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:52:45 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:52:45 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:52:50 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:52:50 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:52:50 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:53:00 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:54:15 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:54:15 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:54:20 orion Backup (bbackupd)[27749]: WARNING: Exception  
thrown: ConnectionException(Conn_Protocol_UnexpectedReply) at  
autogen_BackupProtocolClient.cpp(1135)
Apr 22 01:54:20 orion Backup (bbackupd)[27749]: WARNING: Suppressing  
duplicate notification about backup-error
Apr 22 01:54:20 orion Backup (bbackupd)[27749]: ERROR: Exception  
caught (Connection Protocol_UnexpectedReply (Server probably reported  
an error.) 7/47), reset state and waiting to retry...
Apr 22 01:54:30 orion Backup (bbackupd)[27749]: NOTICE: File  
statistics: total file size uploaded 0, bytes already on server 0,  
encoded size 0
Apr 22 01:55:45 orion Backup (bbackupd)[27749]: NOTICE: Beginning scan  
of local files
Apr 22 01:55:45 orion Backup (bbackupd)[27749]: Opening connection to  
server 'hercules.xxxxxxx'...
Apr 22 01:55:46 orion Backup (bbackupd)[27749]: Connection made, login  
successful
Apr 22 01:55:46 orion Backup (bbackupd)[27749]: Not deleting unused  
entries - none in list
Apr 22 01:55:46 orion Backup (bbackupd)[27749]: NOTICE: About to  
notify administrator about event backup-start, running script '/etc/ 
box/bbackupd/NotifySysadmin.sh backup-start'
Apr 22 01:55:46 orion Backup (bbackupd)[27749]: Scanning directory: / 
home
Apr 22 01:55:46 orion Backup (bbackupd)[27749]: Skipping excluded  
directory: /home/ftp

Regards

Matt