Rsync hanging, even with timeout and contimeout specified
Dan McGee
dan at archlinux.org
Fri Nov 8 07:47:05 MST 2013
Here's the details and some debugging I did for starters, but I have no
idea where to proceed from here. Help please? Command to reproduce should
be in the strace output below.
-Dan
P.S. Sorry for sending this a few times; I think only one will make it
through to the list since I wasn't subscribed initially.
$ rsync --version
rsync version 3.1.0 protocol version 31
Copyright (C) 1996-2013 by Andrew Tridgell, Wayne Davison, and others.
Web site: http://rsync.samba.org/
Capabilities:
64-bit files, 64-bit inums, 64-bit timestamps, 64-bit long ints,
socketpairs, hardlinks, symlinks, IPv6, batchfiles, inplace,
append, ACLs, xattrs, iconv, symtimes, prealloc
rsync comes with ABSOLUTELY NO WARRANTY. This is free software, and you
are welcome to redistribute it under certain conditions. See the GNU
General Public Licence for details.
$ strace -f rsync --contimeout=10 --timeout=10 rsync://
mirror.aarnet.edu.au/archlinux/lastsync /tmp/hanging-lastsync
execve("/usr/bin/rsync", ["rsync", "--contimeout=10", "--timeout=10",
"rsync://mirror.aarnet.edu.au/arc"..., "/tmp/hanging-lastsync"], [/* 25
vars */]) = 0
brk(0)
= 0x1b83000
access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or
directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=44018, ...}) = 0
mmap(NULL, 44018, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f4310dfc000
close(3) = 0
open("/usr/lib/libacl.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\300
\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=35384, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310dfb000
mmap(NULL, 2130592, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f43109df000
mprotect(0x7f43109e7000, 2093056, PROT_NONE) = 0
mmap(0x7f4310be6000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7f4310be6000
close(3) = 0
open("/usr/lib/libz.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3,
"\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000#\0\0\0\0\0\0"..., 832) =
832
fstat(3, {st_mode=S_IFREG|0755, st_size=88592, ...}) = 0
mmap(NULL, 2183688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f43107c9000
mprotect(0x7f43107de000, 2093056, PROT_NONE) = 0
mmap(0x7f43109dd000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x14000) = 0x7f43109dd000
close(3) = 0
open("/usr/lib/libpopt.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0&\0\0\0\0\0\0"...,
832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=48280, ...}) = 0
mmap(NULL, 2143504, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f43105bd000
mprotect(0x7f43105c8000, 2093056, PROT_NONE) = 0
mmap(0x7f43107c7000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xa000) = 0x7f43107c7000
close(3) = 0
open("/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
read(3,
"\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\34\2\0\0\0\0\0"...,
832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=2027541, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310dfa000
mmap(NULL, 3844624, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f4310212000
mprotect(0x7f43103b4000, 2093056, PROT_NONE) = 0
mmap(0x7f43105b3000, 24576, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1a1000) = 0x7f43105b3000
mmap(0x7f43105b9000, 14864, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f43105b9000
close(3) = 0
open("/usr/lib/libattr.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3,
"\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`\24\0\0\0\0\0\0"..., 832) =
832
fstat(3, {st_mode=S_IFREG|0755, st_size=18736, ...}) = 0
mmap(NULL, 2113912, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f431000d000
mprotect(0x7f4310011000, 2093056, PROT_NONE) = 0
mmap(0x7f4310210000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7f4310210000
close(3) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310df9000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310df8000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310df7000
arch_prctl(ARCH_SET_FS, 0x7f4310df8700) = 0
mprotect(0x7f43105b3000, 16384, PROT_READ) = 0
mprotect(0x7f4310210000, 4096, PROT_READ) = 0
mprotect(0x7f43107c7000, 4096, PROT_READ) = 0
mprotect(0x7f43109dd000, 4096, PROT_READ) = 0
mprotect(0x7f4310be6000, 4096, PROT_READ) = 0
mprotect(0x65a000, 4096, PROT_READ) = 0
mprotect(0x7f4310e07000, 4096, PROT_READ) = 0
munmap(0x7f4310dfc000, 44018) = 0
rt_sigaction(SIGUSR1, {0x41d320, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
rt_sigaction(SIGUSR2, {0x41ded0, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
rt_sigaction(SIGCHLD, {0x41d2c0, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
geteuid() = 1000
getegid() = 100
umask(0) = 022
umask(022) = 0
brk(0) = 0x1b83000
brk(0x1ba4000) = 0x1ba4000
open("/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=3116512, ...}) = 0
mmap(NULL, 3116512, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f430fd14000
close(3) = 0
open("/usr/etc/popt", O_RDONLY) = -1 ENOENT (No such file or
directory)
open("/etc/popt", O_RDONLY) = -1 ENOENT (No such file or
directory)
stat("/etc/popt.d", 0x7fff492b4ab0) = -1 ENOENT (No such file or
directory)
open("/home/dmcgee/.popt", O_RDONLY) = -1 ENOENT (No such file or
directory)
rt_sigaction(SIGINT, {0x40d780, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
rt_sigaction(SIGHUP, {0x40d780, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
rt_sigaction(SIGTERM, {0x40d780, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
rt_sigprocmask(SIG_UNBLOCK, [HUP INT USR1 USR2 TERM CHLD], NULL, 8) = 0
rt_sigaction(SIGPIPE, {SIG_IGN, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
rt_sigaction(SIGXFSZ, {SIG_IGN, [], SA_RESTORER|SA_NOCLDSTOP,
0x7f4310247450}, NULL, 8) = 0
getcwd("/tmp", 4095) = 5
socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_LOCAL, sun_path="/var/run/nscd/socket"}, 110) = -1
ENOENT (No such file or directory)
close(3) = 0
socket(PF_LOCAL, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_LOCAL, sun_path="/var/run/nscd/socket"}, 110) = -1
ENOENT (No such file or directory)
close(3) = 0
open("/etc/nsswitch.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=234, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310e06000
read(3, "# Begin /etc/nsswitch.conf\n\npass"..., 4096) = 234
read(3, "", 4096) = 0
close(3) = 0
munmap(0x7f4310e06000, 4096) = 0
open("/etc/host.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=63, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310e06000
read(3, "#\n# /etc/host.conf\n#\n\norder host"..., 4096) = 63
read(3, "", 4096) = 0
close(3) = 0
munmap(0x7f4310e06000, 4096) = 0
getpid() = 30534
open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=173, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310e06000
read(3, "# Generated by dhcpcd from eth0\n"..., 4096) = 173
read(3, "", 4096) = 0
close(3) = 0
munmap(0x7f4310e06000, 4096) = 0
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=44018, ...}) = 0
mmap(NULL, 44018, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f4310dfc000
close(3) = 0
open("/usr/lib/libnss_files.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3,
"\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000\"\0\0\0\0\0\0"..., 832)
= 832
fstat(3, {st_mode=S_IFREG|0755, st_size=51784, ...}) = 0
mmap(NULL, 2148488, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f430fb07000
mprotect(0x7f430fb12000, 2097152, PROT_NONE) = 0
mmap(0x7f430fd12000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xb000) = 0x7f430fd12000
close(3) = 0
mprotect(0x7f430fd12000, 4096, PROT_READ) = 0
munmap(0x7f4310dfc000, 44018) = 0
open("/etc/hosts", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=393, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310e06000
read(3, "#\n# /etc/hosts: static lookup ta"..., 4096) = 393
read(3, "", 4096) = 0
close(3) = 0
munmap(0x7f4310e06000, 4096) = 0
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=44018, ...}) = 0
mmap(NULL, 44018, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f4310dfc000
close(3) = 0
open("/usr/lib/libnss_dns.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3,
"\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\0\21\0\0\0\0\0\0"..., 832)
= 832
fstat(3, {st_mode=S_IFREG|0755, st_size=22936, ...}) = 0
mmap(NULL, 2117896, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f430f901000
mprotect(0x7f430f906000, 2093056, PROT_NONE) = 0
mmap(0x7f430fb05000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7f430fb05000
close(3) = 0
open("/usr/lib/libresolv.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3,
"\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220:\0\0\0\0\0\0"..., 832)
= 832
fstat(3, {st_mode=S_IFREG|0755, st_size=84840, ...}) = 0
mmap(NULL, 2189928, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) =
0x7f430f6ea000
mprotect(0x7f430f6fe000, 2093056, PROT_NONE) = 0
mmap(0x7f430f8fd000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13000) = 0x7f430f8fd000
mmap(0x7f430f8ff000, 6760, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f430f8ff000
close(3) = 0
mprotect(0x7f430f8fd000, 4096, PROT_READ) = 0
mprotect(0x7f430fb05000, 4096, PROT_READ) = 0
munmap(0x7f4310dfc000, 44018) = 0
socket(PF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET, sin_port=htons(53),
sin_addr=inet_addr("127.0.0.1")}, 16) = 0
gettimeofday({1383869704, 698589}, NULL) = 0
poll([{fd=3, events=POLLOUT}], 1, 0) = 1 ([{fd=3, revents=POLLOUT}])
sendmmsg(3, {{{msg_name(0)=NULL,
msg_iov(1)=[{"\250\317\1\0\0\1\0\0\0\0\0\0\6mirror\6aarnet\3edu\2a"...,
38}], msg_controllen=0, msg_flags=0}, 38}, {{msg_name(0)=NULL,
msg_iov(1)=[{"d\273\1\0\0\1\0\0\0\0\0\0\6mirror\6aarnet\3edu\2a"..., 38}],
msg_controllen=0, msg_flags=0}, 38}}, 2, MSG_NOSIGNAL) = 2
poll([{fd=3, events=POLLIN}], 1, 5000) = 1 ([{fd=3, revents=POLLIN}])
ioctl(3, FIONREAD, [54]) = 0
recvfrom(3, "\250\317\201\200\0\1\0\1\0\0\0\0\6mirror\6aarnet\3edu\2a"...,
2048, 0, {sa_family=AF_INET, sin_port=htons(53),
sin_addr=inet_addr("127.0.0.1")}, [16]) = 54
gettimeofday({1383869704, 702952}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 4995) = 1 ([{fd=3, revents=POLLIN}])
ioctl(3, FIONREAD, [66]) = 0
recvfrom(3, "d\273\201\200\0\1\0\1\0\0\0\0\6mirror\6aarnet\3edu\2a"...,
1994, 0, {sa_family=AF_INET, sin_port=htons(53),
sin_addr=inet_addr("127.0.0.1")}, [16]) = 66
close(3) = 0
open("/etc/gai.conf", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=2584, ...}) = 0
fstat(3, {st_mode=S_IFREG|0644, st_size=2584, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310e06000
read(3, "# Configuration for getaddrinfo("..., 4096) = 2584
read(3, "", 4096) = 0
close(3) = 0
munmap(0x7f4310e06000, 4096) = 0
socket(PF_NETLINK, SOCK_RAW, 0) = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=30534, groups=00000000}, [12]) = 0
sendto(3, "\24\0\0\0\26\0\1\3\10-|R\0\0\0\0\0\0\0\0", 20, 0,
{sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"D\0\0\0\24\0\2\0\10-|RFw\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"...,
4096}], msg_controllen=0, msg_flags=0}, 0) = 284
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"@\0\0\0\24\0\2\0\10-|RFw\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"...,
4096}], msg_controllen=0, msg_flags=0}, 0) = 192
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000},
msg_iov(1)=[{"\24\0\0\0\3\0\2\0\10-|RFw\0\0\0\0\0\0", 4096}],
msg_controllen=0, msg_flags=0}, 0) = 20
close(3) = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET, sin_port=htons(873),
sin_addr=inet_addr("202.158.214.106")}, 16) = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(36226),
sin_addr=inet_addr("173.255.192.10")}, [16]) = 0
close(3) = 0
socket(PF_INET6, SOCK_DGRAM, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET6, sin6_port=htons(873), inet_pton(AF_INET6,
"2001:388:30bc:cafe::beef", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0},
28) = 0
getsockname(3, {sa_family=AF_INET6, sin6_port=htons(42032),
inet_pton(AF_INET6, "2600:3c00::f03c:91ff:fe96:cbca", &sin6_addr),
sin6_flowinfo=0, sin6_scope_id=0}, [28]) = 0
close(3) = 0
socket(PF_INET6, SOCK_STREAM, IPPROTO_TCP) = 3
rt_sigaction(SIGALRM, {0x4334d0, [], SA_RESTORER, 0x7f4310247450}, NULL, 8)
= 0
alarm(10) = 0
connect(3, {sa_family=AF_INET6, sin6_port=htons(873), inet_pton(AF_INET6,
"2001:388:30bc:cafe::beef", &sin6_addr), sin6_flowinfo=0, sin6_scope_id=0},
28) = 0
alarm(0) = 10
open("/usr/lib/gconv/gconv-modules.cache", O_RDONLY) = -1 ENOENT (No such
file or directory)
open("/usr/lib/gconv/gconv-modules", O_RDONLY|O_CLOEXEC) = 4
fstat(4, {st_mode=S_IFREG|0644, st_size=56284, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
0x7f4310e06000
read(4, "# GNU libc iconv configuration.\n"..., 4096) = 4096
read(4, "1002//\tJUS_I.B1.002//\nmodule\tJUS"..., 4096) = 4096
read(4, "ISO-IR-110//\t\tISO-8859-4//\nalias"..., 4096) = 4096
read(4, "\t\tISO-8859-14//\nalias\tISO_8859-1"..., 4096) = 4096
read(4, "IC-ES//\nalias\tEBCDICES//\t\tEBCDIC"..., 4096) = 4096
read(4, "DIC-CP-ES//\t\tIBM284//\nalias\tCSIB"..., 4096) = 4096
read(4, "//\nalias\tCSIBM864//\t\tIBM864//\nal"..., 4096) = 4096
read(4, "BM939//\nmodule\tIBM939//\t\tINTERNA"..., 4096) = 4096
read(4, "EUC-CN//\nalias\tCN-GB//\t\t\tEUC-CN/"..., 4096) = 4096
read(4, "T//\nmodule\tISO-2022-CN-EXT//\tINT"..., 4096) = 4096
read(4, "//\t\tISO_5428//\nalias\tISO_5428:19"..., 4096) = 4096
read(4, "CII-8\t1\n\n#\tfrom\t\t\tto\t\t\tmodule\t\tc"..., 4096) = 4096
brk(0x1bc5000) = 0x1bc5000
read(4, "\tfrom\t\t\tto\t\t\tmodule\t\tcost\nalias\t"..., 4096) = 4096
read(4, "INTERNAL\t\tIBM9448\t\t1\nmodule\tINTE"..., 4096) = 3036
read(4, "", 4096) = 0
close(4) = 0
munmap(0x7f4310e06000, 4096) = 0
write(3, "@RSYNCD: 31.0\n", 14) = 14
read(3, ^CProcess 30534 detached
<detached ...>
rsync error: received SIGINT, SIGTERM, or SIGHUP (code 20) at rsync.c(632)
[Receiver=3.1.0]
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.samba.org/pipermail/rsync/attachments/20131108/230fbe1b/attachment.html>
More information about the rsync
mailing list