BackupPC-users

Re: [BackupPC-users] The famous Backup aborted (Unable to read 4 bytes) error

2012-02-23 08:48:06
Subject: Re: [BackupPC-users] The famous Backup aborted (Unable to read 4 bytes) error
From: Till Hofmann <hofmanntill AT googlemail DOT com>
To: Micha Kersloot <info AT kovoks DOT nl>, "General list for user discussion, questions and support" <backuppc-users AT lists.sourceforge DOT net>
Date: Thu, 23 Feb 2012 14:45:50 +0100


On Thu, Feb 23, 2012 at 2:27 PM, Micha Kersloot <micha AT kovoks DOT nl> wrote:
Hi,

sadly the error appeared this night and the logfiles don't give any more information I think. In one session I get a part that is succeeded and the next part failed:

Inhoud van bestand /var/lib/backuppc/pc/stable03.covide.nl/XferLOG.bad.z, gewijzigd 2012-02-23 05:03:21

incr backup started back to 2012-02-21 23:00:01 (backup #45) for directory /etc
Running: /usr/bin/ssh -q -x -l root stable03.covide.nl rsync --server --sender --numeric-ids --perms --owner --group -D --links --hard-links --times --block-size=2048 --recursive -vv . /etc/
Xfer PIDs are now 21603
Got remote protocol 30
Negotiated protocol version 28
Xfer PIDs are now 21603,21605
 create d 755       0/0        4096 .

incr backup started back to 2012-02-21 23:00:01 (backup #45) for directory /var/covide_files
Running: /usr/bin/ssh -q -x -l root stable03.covide.nl rsync --server --sender --numeric-ids --perms --owner --group -D --links --hard-links --times --block-size=2048 --recursive -vv . /var/covide_files/
Xfer PIDs are now 21606
Read EOF: Connection reset by peer
Tried again: got 0 bytes
Done: 0 files, 0 bytes
Got fatal error during xfer (Unable to read 4 bytes)
Backup aborted (Unable to read 4 bytes)


And it did try it all night:
2012-02-23 01:00:00 Running 2 BackupPC_nightly jobs from 0..15 (out of 0..15)
2012-02-23 01:00:00 Running BackupPC_nightly -m 0 127 (pid=20511)
2012-02-23 01:00:00 Running BackupPC_nightly 128 255 (pid=20512)
2012-02-23 01:00:00 Next wakeup is 2012-02-23 02:00:00
2012-02-23 01:00:01 Started incr backup on stable03.covide.nl (pid=20514, share=/etc)
2012-02-23 01:00:01 Started incr backup on trunk01.covide.nl (pid=20513, share=/etc)
2012-02-23 01:00:11 Started incr backup on stable03.covide.nl (pid=20514, share=/var/covide_files)
2012-02-23 01:03:16 Backup failed on trunk01.covide.nl (Unable to read 4 bytes)
2012-02-23 01:03:26 Backup failed on stable03.covide.nl (Unable to read 4 bytes)
2012-02-23 01:12:01 BackupPC_nightly now running BackupPC_sendEmail
2012-02-23 01:12:08 Finished  admin1  (BackupPC_nightly 128 255)
2012-02-23 01:13:00 Finished  admin  (BackupPC_nightly -m 0 127)
2012-02-23 01:13:00 Pool nightly clean removed 0 files of size 0.00GB
2012-02-23 01:13:00 Pool is 0.00GB, 0 files (0 repeated, 0 max chain, 0 max links), 1954 directories
2012-02-23 01:13:00 Cpool nightly clean removed 0 files of size 0.00GB
2012-02-23 01:13:00 Cpool is 182.36GB, 1522124 files (47 repeated, 12 max chain, 31999 max links), 4369 directories
2012-02-23 02:00:00 Next wakeup is 2012-02-23 03:00:00
2012-02-23 02:00:01 Started incr backup on stable03.covide.nl (pid=20746, share=/etc)
2012-02-23 02:00:01 Started incr backup on trunk01.covide.nl (pid=20745, share=/etc)
2012-02-23 02:00:06 Started incr backup on stable03.covide.nl (pid=20746, share=/var/covide_files)
2012-02-23 02:03:17 Backup failed on trunk01.covide.nl (Unable to read 4 bytes)
2012-02-23 02:03:22 Backup failed on stable03.covide.nl (Unable to read 4 bytes)
2012-02-23 03:00:00 Next wakeup is 2012-02-23 04:00:00
2012-02-23 03:00:02 Started incr backup on trunk01.covide.nl (pid=20952, share=/etc)
2012-02-23 03:00:02 Started incr backup on stable03.covide.nl (pid=20953, share=/etc)
2012-02-23 03:00:09 Started incr backup on trunk01.covide.nl (pid=20952, share=/var/covide_files)
2012-02-23 03:03:17 Backup failed on stable03.covide.nl (Unable to read 4 bytes)
2012-02-23 03:03:25 Backup failed on trunk01.covide.nl (Unable to read 4 bytes)
2012-02-23 04:00:00 Next wakeup is 2012-02-23 05:00:00
2012-02-23 04:00:02 Started incr backup on stable03.covide.nl (pid=21407, share=/etc)
2012-02-23 04:00:02 Started incr backup on trunk01.covide.nl (pid=21406, share=/etc)
2012-02-23 04:00:10 Started incr backup on stable03.covide.nl (pid=21407, share=/var/covide_files)
2012-02-23 04:03:18 Backup failed on trunk01.covide.nl (Unable to read 4 bytes)
2012-02-23 04:03:25 Backup failed on stable03.covide.nl (Unable to read 4 bytes)
2012-02-23 05:00:00 Next wakeup is 2012-02-23 06:00:00
2012-02-23 05:00:02 Started incr backup on stable03.covide.nl (pid=21596, share=/etc)
2012-02-23 05:00:02 Started incr backup on trunk01.covide.nl (pid=21595, share=/etc)
2012-02-23 05:00:06 Started incr backup on stable03.covide.nl (pid=21596, share=/var/covide_files)
2012-02-23 05:03:17 Backup failed on trunk01.covide.nl (Unable to read 4 bytes)
2012-02-23 05:03:21 Backup failed on stable03.covide.nl (Unable to read 4 bytes)
2012-02-23 06:00:00 Next wakeup is 2012-02-23 07:00:00
2012-02-23 07:00:00 Next wakeup is 2012-02-23 08:00:00
2012-02-23 07:00:02 Started incr backup on michathuis (pid=22152, share=/etc)
2012-02-23 07:00:21 Started incr backup on michathuis (pid=22152, share=/home)
2012-02-23 07:05:50 Started incr backup on michathuis (pid=22152, share=/srv/mima)
2012-02-23 07:06:10 Finished incr backup on michathuis
2012-02-23 07:06:10 Running BackupPC_link michathuis (pid=22185)
2012-02-23 07:06:10 Finished michathuis (BackupPC_link michathuis)

Hopefully someone has an idea how to debug this further.


Met vriendelijke groet,

Micha Kersloot

Altijd en overal bij je belangrijkste gegevens?
http://www.exchange-alternatief.com/

KovoKs B.V. is ingeschreven onder KvK nummer: 11033334

----- Oorspronkelijk bericht -----
> Van: "Micha Kersloot" <micha AT kovoks DOT nl>
> Aan: "General list for user discussion, questions and support" <backuppc-users AT lists.sourceforge DOT net>
> Verzonden: Woensdag 22 februari 2012 15:28:14
> Onderwerp: Re: [BackupPC-users] The famous Backup aborted (Unable to read 4   bytes) error
>
> Hi,
>
> I've added the -vv flag to rsync and guess what. All backups
> succeeded this night. Let's see if increasing the loglevel solves
> the problem ;-)
>
> Met vriendelijke groet,
>
> Micha Kersloot
>
> Altijd en overal bij je belangrijkste gegevens?
> http://www.exchange-alternatief.com/
>
> KovoKs B.V. is ingeschreven onder KvK nummer: 11033334
>
> ----- Oorspronkelijk bericht -----
> > Van: "Micha Kersloot" <micha AT kovoks DOT nl>
> > Aan: "Till Hofmann" <hofmanntill AT googlemail DOT com>
> > Cc: korndoerfer AT crelux DOT com, "Micha Kersloot" <info AT kovoks DOT nl>,
> > "General list for user discussion, questions and
> > support" <backuppc-users AT lists.sourceforge DOT net>
> > Verzonden: Dinsdag 21 februari 2012 16:55:33
> > Onderwerp: Re: [BackupPC-users] The famous Backup aborted (Unable
> > to read 4 bytes) error
> >
> > Hi Till,
> >
> > ----- Oorspronkelijk bericht -----
> >
> > > Van: "Till Hofmann" <hofmanntill AT googlemail DOT com>
> > > Aan: "Micha Kersloot" <info AT kovoks DOT nl>, "General list for user
> > > discussion, questions and support"
> > > <backuppc-users AT lists.sourceforge DOT net>
> > > Cc: korndoerfer AT crelux DOT com
> > > Verzonden: Dinsdag 21 februari 2012 16:49:56
> > > Onderwerp: Re: [BackupPC-users] The famous Backup aborted (Unable
> > > to
> > > read 4 bytes) error
> >
> >
> > > add -vv to the rsync arguments and then check the log file again.
> > > It
> > > should tell you a lot more and probably it will tell you what the
> > > error was.
> >
> > I've added this to the RsyncArgs hopefully it will make things
> > clear.
> >
> > Met vriendelijke groet,
> >
> > Micha Kersloot
> >
> > Altijd en overal bij je belangrijkste gegevens?
> > http://www.exchange-alternatief.com/
> >
> > KovoKs B.V. is ingeschreven onder KvK nummer: 11033334
> >
>
> ------------------------------------------------------------------------------
> Virtualization & Cloud Management Using Capacity Planning
> Cloud computing makes use of virtualization - but cloud computing
> also focuses on allowing computing to be delivered as a service.
> http://www.accelacomm.com/jaw/sfnl/114/51521223/
> _______________________________________________
> BackupPC-users mailing list
> BackupPC-users AT lists.sourceforge DOT net
> List:    https://lists.sourceforge.net/lists/listinfo/backuppc-users
> Wiki:    http://backuppc.wiki.sourceforge.net
> Project: http://backuppc.sourceforge.net/
>

------------------------------------------------------------------------------
Virtualization & Cloud Management Using Capacity Planning
Cloud computing makes use of virtualization - but cloud computing
also focuses on allowing computing to be delivered as a service.
http://www.accelacomm.com/jaw/sfnl/114/51521223/
_______________________________________________
BackupPC-users mailing list
BackupPC-users AT lists.sourceforge DOT net
List:    https://lists.sourceforge.net/lists/listinfo/backuppc-users
Wiki:    http://backuppc.wiki.sourceforge.net
Project: http://backuppc.sourceforge.net/


It's weird there is nothing added in the log, usually rsync -vv tells you everything you might wanna know... maybe backup pc filters the output. 

did you check the normal xfer log (not xfer errors)? it's more talkative.

------------------------------------------------------------------------------
Virtualization & Cloud Management Using Capacity Planning
Cloud computing makes use of virtualization - but cloud computing 
also focuses on allowing computing to be delivered as a service.
http://www.accelacomm.com/jaw/sfnl/114/51521223/
_______________________________________________
BackupPC-users mailing list
BackupPC-users AT lists.sourceforge DOT net
List:    https://lists.sourceforge.net/lists/listinfo/backuppc-users
Wiki:    http://backuppc.wiki.sourceforge.net
Project: http://backuppc.sourceforge.net/