Weird error on guix download ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz

  • Open
  • quality assurance status badge
Details
3 participants
  • Danny Milosavljevic
  • Ludovic Courtès
  • Tobias Geerinckx-Rice
Owner
unassigned
Submitted by
Danny Milosavljevic
Severity
normal
D
D
Danny Milosavljevic wrote on 19 Aug 2020 13:17
(address . bug-guix@gnu.org)
20200819131758.5d68b961@scratchpost.org
Hi,

I get the following weird error on

guix download ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz

Starting download of /tmp/guix-file.PnA5jm
From ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz...
In procedure connect*: Permission denied
failed to download "/tmp/guix-file.PnA5jm" from "ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz"
guix download: error: ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz: download failed

strace -f -s 0:

[pid 16141] connect(16, {sa_family=AF_UNIX, sun_path="/var/run/nscd/socket"}, 110) = 0
[pid 16141] sendto(16, ""..., 21, MSG_NOSIGNAL, NULL, 0) = 21
[pid 16141] poll([...], 1, 5000) = 1 ([...])
[pid 16141] recvmsg(16, {msg_name=NULL, msg_namelen=0, msg_iov=[...], msg_iovlen=2, msg_control=[{cmsg_len=20, cmsg_level=SOL_SOCKET, cmsg_type=SCM_RIGHTS, cmsg_data=[...]}], msg_controllen=20, msg_flags=MSG_CMSG_CLOEXEC}, MSG_CMSG_CLOEXEC) = 17
[pid 16141] mmap(NULL, 217032, PROT_READ, MAP_SHARED, 17, 0) = 0x7f8ad32db000
[pid 16141] close(17) = 0
[pid 16141] close(16) = 0
[pid 16141] openat(AT_FDCWD, "/etc/gai.conf", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
[pid 16141] futex(0x7f8ad809b5c4, FUTEX_WAKE_PRIVATE, 2147483647) = 0
[pid 16141] socket(AF_INET, SOCK_DGRAM|SOCK_CLOEXEC, IPPROTO_IP) = 16
[pid 16141] connect(16, {sa_family=AF_INET, sin_port=htons(21), sin_addr=inet_addr("8.43.85.97")}, 16) = 0
[pid 16141] getsockname(16, {sa_family=AF_INET, sin_port=htons(57279), sin_addr=inet_addr("192.168.10.207")}, [28->16]) = 0
[pid 16141] close(16) = 0
[pid 16141] socket(AF_INET6, SOCK_DGRAM|SOCK_CLOEXEC, IPPROTO_IP) = 16
[pid 16141] connect(16, {sa_family=AF_INET6, sin6_port=htons(21), sin6_flowinfo=htonl(0), inet_pton(AF_INET6, "2620:52:3:1:0:246e:9693:128c", &sin6_addr), sin6_scope_id=0}, 28) = 0
[pid 16141] getsockname(16, {sa_family=AF_INET6, sin6_port=htons(42412), sin6_flowinfo=htonl(0), inet_pton(AF_INET6, "2a02:8388:281:94f0::93b", &sin6_addr), sin6_scope_id=0}, [28]) = 0
[pid 16141] close(16) = 0
[pid 16141] socket(AF_INET6, SOCK_STREAM, IPPROTO_IP) = 16
[pid 16141] fcntl(16, F_GETFL) = 0x2 (flags O_RDWR)
[pid 16141] fcntl(16, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 16141] connect(16, {sa_family=AF_INET6, sin6_port=htons(21), sin6_flowinfo=htonl(0), inet_pton(AF_INET6, "2620:52:3:1:0:246e:9693:128c", &sin6_addr), sin6_scope_id=0}, 28) = -1 EINPROGRESS (Operation now in progress)
[pid 16141] rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
[pid 16141] select(17, [3], [16], [16], {tv_sec=10, tv_usec=0}) = 1 (out [16], left {tv_sec=9, tv_usec=880026})
[pid 16141] fcntl(16, F_SETFL, O_RDWR) = 0
[pid 16141] getsockopt(16, SOL_SOCKET, SO_ERROR, [0], [8->4]) = 0
[pid 16141] fstat(16, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
[pid 16141] read(16, ""..., 4096) = 40
[pid 16141] brk(0x1385000) = 0x1385000
[pid 16141] brk(0x137d000) = 0x137d000
[pid 16141] brk(0x1375000) = 0x1375000
[pid 16141] brk(0x136d000) = 0x136d000
[pid 16141] write(16, ""..., 16) = 16
[pid 16141] read(16, ""..., 4096) = 34
[pid 16141] write(16, ""..., 23) = 23
[pid 16141] read(16, ""..., 4096) = 23
[pid 16141] write(16, ""..., 8) = 8
[pid 16141] read(16, ""..., 4096) = 31
[pid 16141] write(16, ""..., 33) = 33
[pid 16141] read(16, ""..., 4096) = 13
[pid 16141] write(16, ""..., 7) = 7
[pid 16141] read(16, ""..., 4096) = 37
[pid 16141] write(16, ""..., 9) = 9
[pid 16141] read(16, ""..., 4096) = 37
[pid 16141] write(16, ""..., 10) = 10
[pid 16141] read(16, ""..., 4096) = 37
[pid 16141] write(16, ""..., 8) = 8
[pid 16141] read(16, ""..., 4096) = 31
[pid 16141] write(16, ""..., 6) = 6
[pid 16141] read(16, ""..., 4096) = 45
[pid 16141] socket(AF_INET6, SOCK_STREAM, IPPROTO_TCP) = 17
[pid 16141] fcntl(17, F_GETFL) = 0x2 (flags O_RDWR)
[pid 16141] fcntl(17, F_SETFL, O_RDWR|O_NONBLOCK) = 0
[pid 16141] connect(17, {sa_family=AF_INET6, sin6_port=htons(27421), sin6_flowinfo=htonl(0), inet_pton(AF_INET6, "2620:52:3:1:0:246e:9693:128c", &sin6_addr), sin6_scope_id=0}, 28) = -1 EINPROGRESS (Operation now in progress)
[pid 16141] rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
[pid 16141] select(18, [3], [17], [17], {tv_sec=10, tv_usec=0}) = 1 (out [17], left {tv_sec=9, tv_usec=880751})
[pid 16141] fcntl(17, F_SETFL, O_RDWR) = 0
[pid 16141] getsockopt(17, SOL_SOCKET, SO_ERROR, [EACCES], [8->4]) = 0
[pid 16141] write(2, ""..., 41In procedure connect*: Permission denied
) = 41
[pid 16141] write(2, ""..., 97failed to download "/tmp/guix-file.X4QpoI" from "ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz"
) = 97

This error is 100% reproducible every time on my laptop.

But:

$ ping6 2620:52:3:1:0:246e:9693:128c
PING 2620:52:3:1:0:246e:9693:128c (2620:52:3:1:0:246e:9693:128c): 56 data bytes
64 bytes from server2.sourceware.org: icmp_seq=0 ttl=49 time=119,991 ms
$ telnet 2620:52:3:1:0:246e:9693:128c ftp
Trying 2620:52:3:1:0:246e:9693:128c...
Connected to 2620:52:3:1:0:246e:9693:128c.
Escape character is '^]'.
220 Welcome to sourceware FTP service.
$ ncftpget ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz
This one succeeds, but it also uses IPv4 and doesn't use the same IPv6 address 2620:52:3:1:0:246e:9693:128c from above.
-----BEGIN PGP SIGNATURE-----

iQEzBAEBCgAdFiEEds7GsXJ0tGXALbPZ5xo1VCwwuqUFAl89CmYACgkQ5xo1VCww
uqVMewgAmB6mSgQsxIZdmE/zCgehdnH8viLS3MQz5EvxM5h3fGxlqkqseaQ9bkBT
c7+lnQXN6rp/S7EhQr7dagpfax8YsTMqpBfhyQMnB5pY055qDcsgzkh7VjCTJ5/D
rx/yBQq54mmjvuiSJH6nQtIAzZJTDuWkYLpGULdhSont7eBvaK87jN7F1/LGz6za
+nTpRat6qID7JoHyrklnWW/MQbm+afJstoexeEqa498tmWZelwEM78v9rj/8lCQ+
RUjrIZXOnnl71ALhGg6JYTRm8LHjF+yO92gQI9wdEATrwo7aPo1FebfyeGmuHKXg
dVrDHajoIt9MYCYcwBiR6P8KuaTKnA==
=9Zqb
-----END PGP SIGNATURE-----


T
T
Tobias Geerinckx-Rice wrote on 19 Aug 2020 14:18
(name . Danny Milosavljevic)(address . dannym@scratchpost.org)
87v9hex211.fsf@nckx
Danny,

As you noticed LVM2, on master now defaults to HTTPS instead of
FTP. However, that doesn't actually solve or fix this bug.

FWIW FTP WFM:
~/guix master λ guix download
ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz

Starting download of /tmp/guix-file.J33OOi
From ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz...
LVM2.2.03.10.tgz 2.4MiB
1.2MiB/s 00:02 [##################] 100.0%
/gnu/store/r9g36syz9yhgyx5mjnq6xvdhmfxg8aw4-LVM2.2.03.10.tgz
1l0fkn9abrgk5mfn6jfh9qhdr86b59l1c5pk6lp8jh0491d69las
Danny Milosavljevic ???
Toggle quote (2 lines)
> I get the following weird error on

Does ‘Permission denied’ coming from getsockopt look odd to anyone
else? I'm not a network person, but it does not sound like an
error coming from the network.

Kind regards,

T G-R
-----BEGIN PGP SIGNATURE-----

iHUEARYKAB0WIQT12iAyS4c9C3o4dnINsP+IT1VteQUCXz0YegAKCRANsP+IT1Vt
eRlsAPwNqya94RaKGClAN1nhzEFZi+m5phpaOaUPRW7/LWKuKAEAvs3yUImjj5iQ
XFWGdIO4Wdx+6r1kyEiQx60zLwoTQwI=
=wI6a
-----END PGP SIGNATURE-----

L
L
Ludovic Courtès wrote on 28 Aug 2020 15:14
(name . Tobias Geerinckx-Rice via Bug reports for GNU Guix)(address . bug-guix@gnu.org)
87bliurjz1.fsf@gnu.org
Hi,

Tobias Geerinckx-Rice via Bug reports for GNU Guix <bug-guix@gnu.org>
skribis:

Toggle quote (11 lines)
> FWIW FTP WFM:
>
> ~/guix master λ guix download
> ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz
>
> Starting download of /tmp/guix-file.J33OOi
> From ftp://sourceware.org/pub/lvm2/LVM2.2.03.10.tgz...
> LVM2.2.03.10.tgz 2.4MiB 1.2MiB/s 00:02 [##################] 100.0%
> /gnu/store/r9g36syz9yhgyx5mjnq6xvdhmfxg8aw4-LVM2.2.03.10.tgz
> 1l0fkn9abrgk5mfn6jfh9qhdr86b59l1c5pk6lp8jh0491d69las

I get ETIMEDOUT:

Toggle snippet (30 lines)
fstat(16, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0
read(16, "220 Welcome to sourceware FTP se"..., 4096) = 40
write(16, "USER anonymous\r\n", 16) = 16
read(16, "331 Please specify the password."..., 4096) = 34
write(16, "PASS guix@example.com\r\n", 23) = 23
read(16, "230 Login successful.\r\n", 4096) = 23
write(16, "TYPE I\r\n", 8) = 8
read(16, "200 Switching to Binary mode.\r\n", 4096) = 31
write(16, "SIZE /pub/lvm2/LVM2.2.03.10.tgz\r"..., 33) = 33
read(16, "213 2510568\r\n", 4096) = 13
write(16, "CWD /\r\n", 7) = 7
read(16, "250 Directory successfully chang"..., 4096) = 37
write(16, "CWD pub\r\n", 9) = 9
read(16, "250 Directory successfully chang"..., 4096) = 37
write(16, "CWD lvm2\r\n", 10) = 10
read(16, "250 Directory successfully chang"..., 4096) = 37
write(16, "TYPE I\r\n", 8) = 8
read(16, "200 Switching to Binary mode.\r\n", 4096) = 31
write(16, "PASV\r\n", 6) = 6
read(16, "227 Entering Passive Mode (0,0,0"..., 4096) = 46
socket(AF_INET6, SOCK_STREAM, IPPROTO_TCP) = 17
fcntl(17, F_GETFL) = 0x2 (flags O_RDWR)
fcntl(17, F_SETFL, O_RDWR|O_NONBLOCK) = 0
connect(17, {sa_family=AF_INET6, sin6_port=htons(49263), sin6_flowinfo=htonl(0), inet_pton(AF_INET6, "2620:52:3:1:0:246e:9693:128c", &sin6_addr), sin6_scope_id=0}, 28) = -1 EINPROGRESS (Operacio nun fari?as)
rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0
select(18, [3], [17], [17], {tv_sec=10, tv_usec=0}) = 0 (Timeout)
write(2, "In procedure connect*: Konekto t"..., 52In procedure connect*: Konekto transpasis limtempon
) = 52

Could it be that ‘ftp-pasv’ is not connecting to the right address, or
some IPv6 issue somewhere on the way?

Toggle quote (7 lines)
> Danny Milosavljevic ???
>> I get the following weird error on
>
> Does ‘Permission denied’ coming from getsockopt look odd to anyone
> else? I'm not a network person, but it does not sound like an error
> coming from the network.

Yes, that one looks very weird. Danny?

Ludo’.
?