strange errors with pre5

Daniel Maher dma at witbe.net
Fri Nov 9 13:32:44 GMT 2007


On Thu, 2007-11-08 at 21:24 -0800, Wayne Davison wrote:
> I've just released rsync 3.0.0pre5, the latest pre-release version of
> the upcoming 3.0.0 release.
> 
> Things are progressing well in our efforts to stamp out bugs for the
> final release.  Please keep up the good work in testing this and sending
> email to the rsync mailing list with your questions, comments, bug
> reports, etc.  Thanks!

First off, thanks as always for the great work on rsync. :)

Under 3.0.0pre4 i was encountering a behaviour whereby many files were
"missed" by rsync.  If i supplied full paths to these files, rsync would
copy them, but otherwise they would not be seen at all.

The 3.0.0pre5 release appears to see them - assuming i run in dry-mode.
As soon as i run an actual backup process, i receive an error condition.
What's perhaps more bizarre, is that if i increase verbosity via -vvv,
the error does not occur (!), and the transfer functions as expected.
I've attached the error output in a text file (real-run-err.txt).

Also, i have encountered an odd error whereby rsync is erroring out
while attempting to back up /etc/samba on the target machine.  This is
not a samba mounted directory - it is just the standard samba config
dir.  I have also included some output as an attachment (samba-err.txt).
It appears to be similar, but not identical, to the error noted above;
for example, pre4 saw the files, and with this /particular/ scenario,
increasing verbosity does not "solve" the problem...


Thanks!


--
dan.
-------------- next part --------------
$ /usr/bin/sudo /usr/bin/rsync -A -X -bavzR --fake-super --delete --numeric-ids --rsync-path="/usr/bin/sudo /usr/bin/rsync" --log-file=/opt/rsync-backup/rsync-backup.log --backup-dir=/opt/rsync-backup/FILE-SERVER/2007-11-09T13:24:27 -e "/usr/bin/ssh -i /home/rsync-backup/.ssh/id_rsa" rsync-backup at FILE-SERVER:"/data/Samba/Witbe" /opt/rsync-backup/FILE-SERVER/CURRENT/

rsync: writefd_unbuffered failed to write 8 bytes [receiver]: Broken pipe (32)
rsync error: error in rsync protocol data stream (code 12) at io.c(1499) [receiver=3.0.0pre5]
receiving incremental file list
*** glibc detected *** /usr/bin/rsync: free(): invalid pointer: 0x09c8d5f0 ***
======= Backtrace: =========
/lib/libc.so.6[0xa79df1]
/lib/libc.so.6(cfree+0x90)[0xa7d430]
/usr/bin/rsync[0x8067659]
/usr/bin/rsync[0x8067ebf]
/usr/bin/rsync[0x8052a82]
/usr/bin/rsync[0x8052e74]
/usr/bin/rsync[0x8057c2c]
/usr/bin/rsync[0x8061ba6]
/usr/bin/rsync[0x8061e34]
/usr/bin/rsync[0x8062f82]
/lib/libc.so.6(__libc_start_main+0xe0)[0xa27f70]
/usr/bin/rsync[0x804a621]
======= Memory map: ========
00110000-00111000 r-xp 00110000 00:00 0          [vdso]
00111000-0011b000 r-xp 00000000 fd:03 1239144    /lib/libnss_files-2.6.so
0011b000-0011c000 r-xp 00009000 fd:03 1239144    /lib/libnss_files-2.6.so
0011c000-0011d000 rwxp 0000a000 fd:03 1239144    /lib/libnss_files-2.6.so
00727000-0072e000 r-xp 00000000 fd:03 1739780    /usr/lib/libpopt.so.0.0.0
0072e000-0072f000 rwxp 00006000 fd:03 1739780    /usr/lib/libpopt.so.0.0.0
009f3000-00a0e000 r-xp 00000000 fd:03 1239106    /lib/ld-2.6.so
00a0e000-00a0f000 r-xp 0001a000 fd:03 1239106    /lib/ld-2.6.so
00a0f000-00a10000 rwxp 0001b000 fd:03 1239106    /lib/ld-2.6.so
00a12000-00b60000 r-xp 00000000 fd:03 1239122    /lib/libc-2.6.so
00b60000-00b62000 r-xp 0014e000 fd:03 1239122    /lib/libc-2.6.so
00b62000-00b63000 rwxp 00150000 fd:03 1239122    /lib/libc-2.6.so
00b63000-00b66000 rwxp 00b63000 00:00 0 
00c16000-00c1a000 r-xp 00000000 fd:03 1239218    /lib/libattr.so.1.1.0
00c1a000-00c1b000 rwxp 00003000 fd:03 1239218    /lib/libattr.so.1.1.0
00c1d000-00c23000 r-xp 00000000 fd:03 1239210    /lib/libacl.so.1.1.0
00c23000-00c24000 rwxp 00005000 fd:03 1239210    /lib/libacl.so.1.1.0
00dc3000-00dce000 r-xp 00000000 fd:03 1239172    /lib/libgcc_s-4.1.2-20070925.so.1
00dce000-00dcf000 rwxp 0000a000 fd:03 1239172    /lib/libgcc_s-4.1.2-20070925.so.1
08048000-0809d000 r-xp 00000000 fd:03 1731090    /usr/bin/rsync
0809d000-080a1000 rw-p 00054000 fd:03 1731090    /usr/bin/rsync
080a1000-080b0000 rw-p 080a1000 00:00 0 
09c37000-09cd0000 rw-p 09c37000 00:00 0 
b7a00000-b7a21000 rw-p b7a00000 00:00 0 
b7a21000-b7b00000 ---p b7a21000 00:00 0 
b7be2000-b7c65000 rw-p b7be2000 00:00 0 
b7c86000-b7c87000 rw-p b7c86000 00:00 0 
b7c87000-b7c8e000 r--s 00000000 fd:03 1761571    /usr/lib/gconv/gconv-modules.cache
b7c8e000-b7c8f000 rw-p b7c8e000 00:00 0 
b7c8f000-b7d71000 r--p 009df000 fd:03 1730802    /usr/lib/locale/locale-archive
b7d71000-b7f71000 r--p 00000000 fd:03 1730802    /usr/lib/locale/locale-archive
b7f71000-b7f73000 rw-p b7f71000 00:00 0 
bf858000-bf86d000 rw-p bf858000 00:00 0          [stack]
DEBUG: Object: /data/Webdocs
DEBUG: Option: acl
DEBUG: Option: xattr
DEBUG: Command: /usr/bin/sudo /usr/bin/rsync -A -X -bavzR --fake-super --delete --numeric-ids --rsync-path="/usr/bin/sudo /usr/bin/rsync" --log-file=/opt/rsync-backup/rsync-backup.log --backup-dir=/opt/rsync-backup/FILE-SERVER/2007-11-09T13:24:27 -e "/usr/bin/ssh -i /home/rsync-backup/.ssh/id_rsa" rsync-backup at FILE-SERVER:"/data/Webdocs" /opt/rsync-backup/FILE-SERVER/CURRENT/
rsync: writefd_unbuffered failed to write 8 bytes [receiver]: Broken pipe (32)
rsync error: error in rsync protocol data stream (code 12) at io.c(1499) [receiver=3.0.0pre5]

-------------- next part --------------
$ /usr/bin/sudo /usr/bin/rsync --stats -vv -A -X -bavzR --fake-super --delete --numeric-ids --rsync-path="/usr/bin/sudo /usr/bin/rsync" --log-file=/opt/rsync-backup/rsync-backup.log --backup-dir=/opt/rsync-backup/FILE-SERVER/2007-11-09T13:27:07 -e "/usr/bin/ssh -i /home/rsync-backup/.ssh/id_rsa" rsync-backup at FILE-SERVER:"/etc/samba" /opt/rsync-backup/FILE-SERVER/CURRENT/

backup_dir is /opt/rsync-backup/FILE-SERVER/2007-11-09T13:27:07/
opening connection using: /usr/bin/ssh -i /home/rsync-backup/.ssh/id_rsa -l rsync-backup FILE-SERVER "/usr/bin/sudo /usr/bin/rsync" --server --sender -vvvblogDtpAXrRze30.14i --backup-dir "/opt/rsync-backup/FILE-SERVER/2007-11-09T13:27:07" --numeric-ids . /etc/samba 
receiving incremental file list
server_sender starting pid=18478
[sender] make_file(/etc,*,2)
recv_file_name(/etc)
received 1 names
recv_file_list done
send_file_list done
[sender] make_file(/etc/samba,*,2)
recv_file_name(/etc/samba)
received 1 names
recv_file_list done
get_local_name count=2 /opt/rsync-backup/FILE-SERVER/CURRENT/
recv_files(1) starting
send_files starting
[sender] make_file(/etc/samba/secrets.tdb,*,2)
[sender] make_file(/etc/samba/smb.conf,*,2)
[sender] make_file(/etc/samba/smbpasswd,*,2)
[sender] make_file(/etc/samba/smbusers,*,2)
[sender] make_file(/etc/samba/backup.tar.gz,*,2)
generator starting pid=12404
delta-transmission enabled
recv_generator(etc,1)
recv_generator(etc,2)
recv_generator(etc/samba,3)
recv_file_name(/etc/samba/secrets.tdb)
recv_file_name(/etc/samba/smb.conf)
recv_file_name(/etc/samba/smbpasswd)
recv_file_name(/etc/samba/smbusers)
recv_file_name(/etc/samba/backup.tar.gz)
[sender] make_file(/etc/samba/archive-20051115,*,2)
recv_file_name(/etc/samba/archive-20051115)
[sender] make_file(/etc/samba/update-groups.sh,*,2)
recv_file_name(/etc/samba/update-groups.sh)
[sender] make_file(/etc/samba/lmhosts,*,2)
recv_file_name(/etc/samba/lmhosts)
[sender] make_file(/etc/samba/init-groups.sh,*,2)
*** glibc detected *** /usr/bin/rsync: free(): invalid next size (fast): 0x09ae8ee8 ***
======= Backtrace: =========
/lib/libc.so.6[0xa79df1]
/lib/libc.so.6(cfree+0x90)[0xa7d430]
/usr/bin/rsync[0x8076591]
/usr/bin/rsync[0x8067620]
/usr/bin/rsync[0x8067ebf]
/usr/bin/rsync[0x8052a82]
/usr/bin/rsync[0x8052e74]
/usr/bin/rsync[0x8057c2c]
/usr/bin/rsync[0x8061ba6]
/usr/bin/rsync[0x8061e34]
/usr/bin/rsync[0x8062f82]
/lib/libc.so.6(__libc_start_main+0xe0)[0xa27f70]
/usr/bin/rsync[0x804a621]
======= Memory map: ========
00110000-00111000 r-xp 00110000 00:00 0          [vdso]
00727000-0072e000 r-xp 00000000 fd:03 1739780    /usr/lib/libpopt.so.0.0.0
0072e000-0072f000 rwxp 00006000 fd:03 1739780    /usr/lib/libpopt.so.0.0.0
009f3000-00a0e000 r-xp 00000000 fd:03 1239106    /lib/ld-2.6.so
00a0e000-00a0f000 r-xp 0001a000 fd:03 1239106    /lib/ld-2.6.so
00a0f000-00a10000 rwxp 0001b000 fd:03 1239106    /lib/ld-2.6.so
00a12000-00b60000 r-xp 00000000 fd:03 1239122    /lib/libc-2.6.so
00b60000-00b62000 r-xp 0014e000 fd:03 1239122    /lib/libc-2.6.so
00b62000-00b63000 rwxp 00150000 fd:03 1239122    /lib/libc-2.6.so
00b63000-00b66000 rwxp 00b63000 00:00 0 
00c16000-00c1a000 r-xp 00000000 fd:03 1239218    /lib/libattr.so.1.1.0
00c1a000-00c1b000 rwxp 00003000 fd:03 1239218    /lib/libattr.so.1.1.0
00c1d000-00c23000 r-xp 00000000 fd:03 1239210    /lib/libacl.so.1.1.0
00c23000-00c24000 rwxp 00005000 fd:03 1239210    /lib/libacl.so.1.1.0
00dc3000-00dce000 r-xp 00000000 fd:03 1239172    /lib/libgcc_s-4.1.2-20070925.so.1
00dce000-00dcf000 rwxp 0000a000 fd:03 1239172    /lib/libgcc_s-4.1.2-20070925.so.1
08048000-0809d000 r-xp 00000000 fd:03 1731090    /usr/bin/rsync
0809d000-080a1000 rw-p 00054000 fd:03 1731090    /usr/bin/rsync
080a1000-080b0000 rw-p 080a1000 00:00 0 
09ad4000-09b2a000 rw-p 09ad4000 00:00 0 
b7b00000-b7b21000 rw-p b7b00000 00:00 0 
b7b21000-b7c00000 ---p b7b21000 00:00 0 
b7c03000-b7cc8000 rw-p b7c03000 00:00 0 
b7ce9000-b7cf0000 r--s 00000000 fd:03 1761571    /usr/lib/gconv/gconv-modules.cache
b7cf0000-b7cf2000 rw-p b7cf0000 00:00 0 
b7cf2000-b7dd4000 r--p 009df000 fd:03 1730802    /usr/lib/locale/locale-archive
b7dd4000-b7fd4000 r--p 00000000 fd:03 1730802    /usr/lib/locale/locale-archive
b7fd4000-b7fd6000 rw-p b7fd4000 00:00 0 
bff96000-bffac000 rw-p bff96000 00:00 0          [stack]
recv_file_name(/etc/samba/init-groups.sh)
received 9 names
recv_file_list done
recv_generator(etc/samba,3)
delete_in_dir(etc/samba)
[generator] make_file(etc/samba/lmhosts,*,2)
[generator] make_file(etc/samba/smbpasswd,*,2)
[generator] make_file(etc/samba/init-groups.sh,*,2)
[generator] make_file(etc/samba/update-groups.sh,*,2)
[generator] make_file(etc/samba/smb.conf,*,2)
[generator] make_file(etc/samba/backup.tar.gz,*,2)
[generator] make_file(etc/samba/archive-20051115,*,2)
[generator] make_file(etc/samba/secrets.tdb,*,2)
[generator] make_file(etc/samba/smbusers,*,2)
[generator] make_file(etc/samba/.smbpasswd.8LPiW0,*,2)
delete_item(etc/samba/.smbpasswd.8LPiW0) mode=100600 flags=2
[generator] make_file(etc/samba/.smbpasswd.8LPiW0,*,0)
[generator] make_file(etc,*,0)
rsync: writefd_unbuffered failed to write 8 bytes [receiver]: Broken pipe (32)
rsync error: error in rsync protocol data stream (code 12) at io.c(1499) [receiver=3.0.0pre5]



More information about the rsync mailing list