Mario Domgörgen
2007-Oct-08 08:51 UTC
rsync error: protocol incompatibility (code 2) at main.c(1385)
Hello,
i'm trying to backup one of my hosts with a two rsync-scripts, which are
mainly just calling on the client side
/usr/bin/rsync --server --sender -vlogDtprz --delete-excluded
--numeric-ids --exclude-from=/etc/sm-backup/rsync.exclude /
and on the server side
rsync -avz --numeric-ids -e "ssh -i $key" --delete --delete-excluded
rsync@$client:/ $DATA_PATH/$client/daily.0
The backup return with the following message on the server side "rsync
error: protocol incompatibility (code 2) at main.c(1385)
[generator=2.6.9]", but i can't see any error message on the client
side. On both machines the newest version of rsync from the debian
stable repository is installed (2.6.9-2etch1).
I ran strace on both side and attach the last lines of both strace
ouputs to this message.
Best regards,
Mario Domg?rgen
-------------- next part --------------
open("var/log/auth.log", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=106301, ...}) = 0
read(3, "Oct 7 06:25:04 newsletter CRON["..., 106301) = 106301
close(3) = 0
open("var/log/daemon.log", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=265082, ...}) = 0
mmap2(NULL, 266240, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0xb78f2000
read(3, "Oct 7 06:25:20 newsletter modpr"..., 262500) = 262500
read(3, "k7/modules.dep: No such file or "..., 2582) = 2582
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\374\17\0\7", 4) = 4
gettimeofday({1191827977, 789629}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1,
"9:\"exportIDs\";N;s:8:\"dbprefix\";s"..., 4092) =
4092
gettimeofday({1191827977, 789822}, NULL) = 0
munmap(0xb78f2000, 266240) = 0
close(3) = 0
open("var/log/dpkg.log", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=377, ...}) = 0
read(3, "2007-10-08 09:19:05 install stra"..., 377) = 377
close(3) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\251\n\0\7", 4) = 4
gettimeofday({1191827977, 790348}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\nOct 8 09:09:20 newsletter modp"..., 2729) = 2729
gettimeofday({1191827977, 790508}, NULL) = 0
select(1, [0], [], NULL, {60, 0}) = 1 (in [0], left {60, 0})
read(0, "\0\0\0\0\315P\0\0\0\0\315P\0\0\0\0\315P\0\0\0\0\315P\0"...,
8184) = 1770
open("var/log/lastlog", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0664, st_size=292584, ...}) = 0
mmap2(NULL, 266240, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0xb78f2000
read(3, "\222n\260Ftty1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
262500) = 262500
read(3, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
30084) = 30084
munmap(0xb78f2000, 266240) = 0
close(3) = 0
open("var/log/messages", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=3057, ...}) = 0
read(3, "Oct 7 06:25:03 newsletter syslo"..., 3057) = 3057
close(3) = 0
open("var/log/syslog", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=36176, ...}) = 0
read(3, "Oct 8 06:25:02 newsletter syslo"..., 36176) = 36176
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\374\17\0\7", 4) = 4
gettimeofday({1191827977, 848086}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "nR\0\0\10\200\242\1\0\0\274\2\0\0\2\0\0\0\254\2\0\0\300"...,
4092) = 4092
gettimeofday({1191827977, 848282}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\374\17\0\7", 4) = 4
gettimeofday({1191827977, 848403}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, ":20 newsletter modprobe: FATAL: "..., 4092) = 4092
gettimeofday({1191827977, 848580}, NULL) = 0
close(3) = 0
open("var/log/user.log", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=2881, ...}) = 0
read(3, "Oct 8 06:00:01 newsletter php5:"..., 2881) = 2881
close(3) = 0
open("var/log/wtmp", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0664, st_size=1152, ...}) = 0
read(3, "\7\0\0\0cM\0\0ttyp0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
1152) = 1152
close(3) = 0
open("var/log/apache2/access.log", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0640, st_size=101574, ...}) = 0
read(3, "81.169.183.60 - - [07/Oct/2007:0"..., 101574) = 101574
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\374\17\0\7", 4) = 4
gettimeofday({1191827977, 860227}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "tter rsync_wrapper: Creating tar"..., 4092) = 4092
gettimeofday({1191827977, 860422}, NULL) = 0
close(3) = 0
open("var/log/mysql/mysql-bin.000368", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0660, st_size=13016, ...}) = 0
read(3, "\376bin\36\261\tG\17\1\0\0\0^\0\0\0b\0\0\0\1\0\4\0005."...,
13016) = 13016
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\374\17\0\7", 4) = 4
gettimeofday({1191827977, 861075}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "- [08/Oct/2007:09:12:10 +0200] \""..., 4092) = 4092
gettimeofday({1191827977, 861253}, NULL) = 0
close(3) = 0
open("var/log/ntpstats/loopstats", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1143, ...}) = 0
read(3, "54381 197.309 -0.001239000 -60.4"..., 1143) = 1143
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\374\17\0\7", 4) = 4
gettimeofday({1191827977, 861796}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, ".`fe_users`, `simplenewsletter__"..., 4092) = 4092
gettimeofday({1191827977, 861973}, NULL) = 0
close(3) = 0
open("var/log/ntpstats/loopstats.20071008", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1143, ...}) = 0
read(3, "54381 197.309 -0.001239000 -60.4"..., 1143) = 1143
close(3) = 0
open("var/log/ntpstats/peerstats", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=8460, ...}) = 0
read(3, "54381 104.388 168.75.65.20 9414 "..., 8460) = 8460
close(3) = 0
open("var/log/ntpstats/peerstats.20071008", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=8460, ...}) = 0
read(3, "54381 104.388 168.75.65.20 9414 "..., 8460) = 8460
close(3) = 0
open("var/run/utmp", O_RDONLY|O_LARGEFILE) = 3
fstat64(3, {st_mode=S_IFREG|0664, st_size=3456, ...}) = 0
read(3, "\10\0\0\0x\3\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"...,
3456) = 3456
close(3) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "7\17\0\7", 4) = 4
gettimeofday({1191827977, 863322}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, ".004235 10\n54381 19876.397 0.001"..., 3895) = 3895
gettimeofday({1191827977, 863498}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\4\0\0\7\377\377\377\377", 8) = 8
gettimeofday({1191827977, 863618}, NULL) = 0
select(1, [0], [], NULL, {60, 0}) = 1 (in [0], left {59, 936000})
read(0, "\377\377\377\377\377\377\377\377", 8184) = 8
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\4\0\0\7\377\377\377\377", 8) = 8
gettimeofday({1191827977, 922729}, NULL) = 0
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\4\0\0\7\377\377\377\377", 8) = 8
gettimeofday({1191827977, 922852}, NULL) = 0
time(NULL) = 1191827977
select(2, NULL, [1], [1], {60, 0}) = 1 (out [1], left {60, 0})
write(1, "\24\0\0\7\344v\0\0\207\277\27\0\334\331\306\36\227J\0\0"...,
24) = 24
gettimeofday({1191827977, 923009}, NULL) = 0
select(1, [0], [], NULL, {60, 0}) = 1 (in [0], left {59, 748000})
read(0, "\377\377\377\377", 8184) = 4
rt_sigaction(SIGUSR1, {SIG_IGN}, NULL, 8) = 0
rt_sigaction(SIGUSR2, {SIG_IGN}, NULL, 8) = 0
exit_group(0) = ?
Process 19028 detached
-------------- next part --------------
[pid 11038] lstat64("var/www/server_core/rewrite/.svn",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/tmp",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/tmp/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/tmp/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/tmp/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/tmp/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/rewrite/.svn/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap", {st_mode=S_IFDIR|0755,
st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/tmp",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/tmp/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/tmp/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/tmp/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/tmp/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/soap/.svn/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/tmp",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/.svn/tmp/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/tmp/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/.svn/tmp/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/tmp/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/.svn/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/emails",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/emails/.svn",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/emails/.svn/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/server_core/templates/emails/.svn/tmp",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/tmp/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/tmp/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/tmp/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/tmp/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038]
lstat64("var/www/server_core/templates/emails/.svn/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung", {st_mode=S_IFDIR|0755,
st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn", {st_mode=S_IFDIR|0755,
st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/tmp",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/tmp/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/tmp/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/tmp/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/tmp/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/.svn/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/tmp",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/tmp/prop-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/tmp/props",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/tmp/text-base",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/tmp/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] lstat64("var/www/verwaltung/helper/.svn/wcprops",
{st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
[pid 11038] time(NULL) = 1191827978
[pid 11038] select(5, [3], [4], [4], {60, 0}) = 1 (out [4], left {60, 0})
[pid 11038] write(4, "\377\377\377\377", 4 <unfinished ...>
[pid 11039] <... select resumed> ) = 1 (in [4])
[pid 11038] <... write resumed> ) = 4
[pid 11039] read(4, <unfinished ...>
[pid 11038] time( <unfinished ...>
[pid 11039] <... read resumed> "\377\377\377\377", 16384) = 4
[pid 11038] <... time resumed> NULL) = 1191827978
[pid 11039] select(7, [3 4], [3], NULL, NULL <unfinished ...>
[pid 11038] kill(11043, SIGUSR2 <unfinished ...>
[pid 11043] <... select resumed> ) = ? ERESTARTNOHAND (To be
restarted)
[pid 11039] <... select resumed> ) = 1 (out [3])
[pid 11038] <... kill resumed> ) = 0
[pid 11043] --- SIGUSR2 (User defined signal 2) @ 0 (0) ---
[pid 11039] write(3,
",\222\246\261f\370\300\237\232\240\7\205\270v+t\317\247"..., 48
<unfinished ...>
[pid 11038] waitpid(11043, <unfinished ...>
[pid 11043] write(1, "\n", 1 <unfinished ...>
[pid 11039] <... write resumed> ) = 48
[pid 11038] <... waitpid resumed> 0xbf838c08, WNOHANG) = 0
[pid 11043] <... write resumed> ) = 1
[pid 11039] select(7, [3 4], [], NULL, NULL <unfinished ...>
[pid 11038] gettimeofday( <unfinished ...>
[pid 11043] write(1, "sent 30436 bytes received 15563"..., 61
<unfinished ...>
[pid 11038] <... gettimeofday resumed> {1191827978, 171493}, NULL) = 0
[pid 11043] <... write resumed> ) = 61
[pid 11038] select(0, NULL, NULL, NULL, {0, 20000} <unfinished ...>
[pid 11043] write(1, "total size is 516348380 speedup"..., 43) = 43
[pid 11043] exit_group(0) = ?
Process 11043 detached
[pid 11038] <... select resumed> ) = ? ERESTARTNOHAND (To be
restarted)
[pid 11038] --- SIGCHLD (Child exited) @ 0 (0) ---
[pid 11038] waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}],
WNOHANG) = 11043
[pid 11038] waitpid(-1, 0xbf8388a0, WNOHANG) = 0
[pid 11038] sigreturn() = ? (mask now [])
[pid 11038] gettimeofday({1191827978, 172153}, NULL) = 0
[pid 11038] select(0, NULL, NULL, NULL, {0, 20000} <unfinished ...>
[pid 11039] <... select resumed> ) = 1 (in [3])
[pid 11039] read(3,
"\226f\263(G\224K\304\240\253x<9%7\277c}\373\215\332\305"..., 8192)
= 128
[pid 11039] close(5) = 0
[pid 11039] close(4) = 0
[pid 11039] close(6) = 0
[pid 11039] select(7, [3], [3], NULL, NULL) = 1 (out [3])
[pid 11039] write(3,
"C6\33\22\237\335\326Z%\213\226\372\1_\270v\255/\210n\315"..., 32) =
32
[pid 11039] rt_sigaction(SIGWINCH, NULL, {0x80516b0, [], 0}, 8) = 0
[pid 11039] rt_sigaction(SIGWINCH, {SIG_DFL}, NULL, 8) = 0
[pid 11039] ioctl(0, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfed0fa8) = -1 EINVAL
(Invalid argument)
[pid 11039] fcntl64(0, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
[pid 11039] fcntl64(0, F_SETFL, O_RDWR) = 0
[pid 11039] ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfed0fa8) = -1 EINVAL
(Invalid argument)
[pid 11039] fcntl64(1, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
[pid 11039] fcntl64(1, F_SETFL, O_RDWR) = 0
[pid 11039] ioctl(2, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfed0fa8) = -1 ENOTTY
(Inappropriate ioctl for device)
[pid 11039] fcntl64(2, F_GETFL) = 0x8001 (flags O_WRONLY|O_LARGEFILE)
[pid 11039] gettimeofday({1191827978, 178222}, NULL) = 0
[pid 11039] shutdown(3, 2 /* send and receive */) = 0
[pid 11039] close(3) = 0
[pid 11039] brk(0x80eb000) = 0x80eb000
[pid 11039] brk(0x80d8000) = 0x80d8000
[pid 11039] brk(0x80bf000) = 0x80bf000
[pid 11039] exit_group(2) = ?
Process 11039 detached
<... select resumed> ) = ? ERESTARTNOHAND (To be
restarted)
--- SIGCHLD (Child exited) @ 0 (0) ---
waitpid(-1, [{WIFEXITED(s) && WEXITSTATUS(s) == 2}], WNOHANG) = 11039
waitpid(-1, 0xbf8388a0, WNOHANG) = -1 ECHILD (No child processes)
sigreturn() = ? (mask now [])
gettimeofday({1191827978, 179329}, NULL) = 0
select(0, NULL, NULL, NULL, {0, 13000}) = 0 (Timeout)
gettimeofday({1191827978, 192392}, NULL) = 0
waitpid(11043, 0xbf838c08, WNOHANG) = -1 ECHILD (No child processes)
waitpid(11039, 0xbf838c48, WNOHANG) = -1 ECHILD (No child processes)
rt_sigaction(SIGUSR1, {SIG_IGN}, NULL, 8) = 0
rt_sigaction(SIGUSR2, {SIG_IGN}, NULL, 8) = 0
waitpid(11039, 0xbf838c78, WNOHANG) = -1 ECHILD (No child processes)
getpid() = 11038
kill(11039, SIGUSR1) = -1 ESRCH (No such process)
getpid() = 11038
kill(11043, SIGUSR1) = -1 ESRCH (No such process)
write(2, "rsync error: protocol incompatib"..., 80rsync error:
protocol incompatibility (code 2) at main.c(1385) [generator=2.6.9]) = 80
write(2, "\n", 1
) = 1
munmap(0xb7f53000, 4096) = 0
exit_group(2) = ?
Process 11038 detached
Matt McCutchen
2007-Oct-12 04:52 UTC
rsync error: protocol incompatibility (code 2) at main.c(1385)
On 10/8/07, Mario Domg?rgen <md@simplethings.de> wrote:> i'm trying to backup one of my hosts with a two rsync-scripts, which are > mainly just calling on the client side > > /usr/bin/rsync --server --sender -vlogDtprz --delete-excluded > --numeric-ids --exclude-from=/etc/sm-backup/rsync.exclude / > > and on the server side > > rsync -avz --numeric-ids -e "ssh -i $key" --delete --delete-excluded > rsync@$client:/ $DATA_PATH/$client/daily.0 > > The backup return with the following message on the server side "rsync > error: protocol incompatibility (code 2) at main.c(1385) > [generator=2.6.9]", but i can't see any error message on the client > side. On both machines the newest version of rsync from the debian > stable repository is installed (2.6.9-2etch1).The first command is of the form that rsync uses to invoke itself over a remote shell. Are you running this command yourself? If so, why? Is this instead of or in addition to rsync invoking itself on the "client" as a result of the second command? If you explain your setup a bit more, I will have a better idea of why it isn't working. Matt
Matt McCutchen
2007-Oct-15 12:21 UTC
rsync error: protocol incompatibility (code 2) at main.c(1385)
Please CC the list so people other than me can help you and so your messages will be available to future users. On 10/15/07, Mario Domg?rgen <md@simplethings.de> wrote:> No, i don't run the client side command myself. The backup should start > from the server, so i use the the second command to connect to the > client with an unpriviledged user, that has a forced command in his > authorized_keys file.The particular data sent by the rsync protocol depends heavily on the options used, so if the forced command doesn't match the remote command that rsync is trying to invoke, the protocol will get corrupted. I bet this is what caused the error. Forcing a particular --server command is brittle; instead, you should force an appropriately configured single-use daemon (my personal preference) or use the "support/rrsync" script in the source tree. See https://bugzilla.samba.org/show_bug.cgi?id=4163 . Matt