Strange networking problem

tlenbit · 2017-09-06 14:56:23

Hello people. I need help with an issue.

It started with strange ~10sec delay and timeout error in pacman:
Browser worked just fine

$sudo pacman -S <anything>
:: Synchronizing package databases...
<DELAY HERE>
error: failed retrieving file 'core.db' from mirror.yandex.ru : Resolving timed out after 10519 milliseconds

I tried to ping mirror and it was the same 10sec delay (same with any domain):

$ ping google.com -v
ping: socket: Permission denied, attempting raw socket...
ping: socket: Permission denied, attempting raw socket...
<DELAY HERE>
PING google.com (74.125.232.225) 56(84) bytes of data.
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=1 ttl=57 time=3.30 ms
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=2 ttl=57 time=3.00 ms
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=3 ttl=57 time=2.96 ms
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=4 ttl=57 time=4.45 ms
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=5 ttl=57 time=3.12 ms
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=6 ttl=57 time=4.67 ms
64 bytes from 74.125.232.225 (74.125.232.225): icmp_seq=7 ttl=57 time=2.94 ms
^C
--- google.com ping statistics ---
7 packets transmitted, 7 received, 0% packet loss, time 6007ms
rtt min/avg/max/mdev = 2.948/3.498/4.679/0.690 ms

IP pinging is OK, executed without any delay.

So I thought it is something wrong with dns, but it works fine:

$ drill google.com
;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 56093
;; flags: qr rd ra ; QUERY: 1, ANSWER: 11, AUTHORITY: 4, ADDITIONAL: 4 
;; QUESTION SECTION:
;; google.com.    IN    A

;; ANSWER SECTION:
google.com.    104    IN    A    173.194.32.164
google.com.    104    IN    A    173.194.32.165
google.com.    104    IN    A    173.194.32.166
google.com.    104    IN    A    173.194.32.167
google.com.    104    IN    A    173.194.32.168
google.com.    104    IN    A    173.194.32.169
google.com.    104    IN    A    173.194.32.174
google.com.    104    IN    A    173.194.32.160
google.com.    104    IN    A    173.194.32.161
google.com.    104    IN    A    173.194.32.162
google.com.    104    IN    A    173.194.32.163

;; AUTHORITY SECTION:
google.com.    145645    IN    NS    ns4.google.com.
google.com.    145645    IN    NS    ns2.google.com.
google.com.    145645    IN    NS    ns3.google.com.
google.com.    145645    IN    NS    ns1.google.com.

;; ADDITIONAL SECTION:
ns1.google.com.    145643    IN    A    216.239.32.10
ns2.google.com.    145643    IN    A    216.239.34.10
ns3.google.com.    145643    IN    A    216.239.36.10
ns4.google.com.    145643    IN    A    216.239.38.10

;; Query time: 2 msec
;; SERVER: 85.89.127.250
;; WHEN: Wed Sep  6 17:22:17 2017
;; MSG SIZE  rcvd: 340

Then I thought it is my routers fault and made a Hotspot on my android phone, everything was OK.
I also booted in windows and there were no issues too.
Another devices in network work ok too.
Same delay when connected to another router, but same Internet provider.

Part of /var/log/pacman.log:
I can't recall exactly, but I think problem appeared on [2017-09-04 21:08]
(Then I successfully tried to connect to Hotspot, run pacman -Syu which broke urxvt,
installed urxvt-pixbuf from AUR. But I think urxvt has nothing to do with my main issue,
because I tried to log in without X, and problem remained)

[2017-09-02 20:08] [PACMAN] Running 'pacman -S pavucontrol'
[2017-09-02 20:08] [ALPM] transaction started
[2017-09-02 20:08] [ALPM] installed sound-theme-freedesktop (0.8-2)
[2017-09-02 20:08] [ALPM] installed libcanberra (0.30+2+gc0620e4-1)
[2017-09-02 20:08] [ALPM] installed libcanberra-pulse (0.30+2+gc0620e4-1)
[2017-09-02 20:08] [ALPM] installed glibmm (2.52.0-1)
[2017-09-02 20:08] [ALPM] installed cairomm (1.12.2-2)
[2017-09-02 20:08] [ALPM] installed pangomm (2.40.1-2)
[2017-09-02 20:08] [ALPM] installed atkmm (2.24.2+1+gf30b47f-2)
[2017-09-02 20:08] [ALPM] installed gtkmm3 (3.22.1-1)
[2017-09-02 20:08] [ALPM] installed pavucontrol (3.0-4)
[2017-09-02 20:08] [ALPM] transaction completed
[2017-09-02 20:08] [ALPM] running 'systemd-update.hook'...
[2017-09-02 20:08] [ALPM] running 'update-desktop-database.hook'...
[2017-09-04 01:49] [PACMAN] Running 'pacman -S cutegram'
[2017-09-04 21:08] [PACMAN] Running 'pacman -S pulseaudio-equalizer'
[2017-09-04 21:08] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:08] [PACMAN] synchronizing package lists
[2017-09-04 21:10] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:10] [PACMAN] synchronizing package lists
[2017-09-04 21:16] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:16] [PACMAN] synchronizing package lists
[2017-09-04 21:22] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:22] [PACMAN] synchronizing package lists
[2017-09-04 21:22] [PACMAN] starting full system upgrade
[2017-09-04 21:23] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:23] [PACMAN] synchronizing package lists
[2017-09-04 21:24] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:24] [PACMAN] synchronizing package lists
[2017-09-04 21:26] [PACMAN] Running 'pacman -Syyu'
[2017-09-04 21:26] [PACMAN] synchronizing package lists
[2017-09-04 21:34] [PACMAN] Running 'pacman -Syu'
[2017-09-04 21:34] [PACMAN] synchronizing package lists
[2017-09-04 21:34] [PACMAN] starting full system upgrade
[2017-09-04 21:39] [ALPM] transaction started
[2017-09-04 21:39] [ALPM] upgraded perl (5.26.0-3 -> 5.26.0-4)
[2017-09-04 21:39] [ALPM] upgraded libldap (2.4.44-5 -> 2.4.45-4)
[2017-09-04 21:39] [ALPM] upgraded burpsuite (1.7.26-1 -> 1.7.27-1)
[2017-09-04 21:39] [ALPM] upgraded cpupower (4.12-1 -> 4.12-3)
[2017-09-04 21:39] [ALPM] upgraded libproxy (0.4.15-4 -> 0.4.15-6)
[2017-09-04 21:39] [ALPM] upgraded perl-error (0.17025-1 -> 0.17025-2)
[2017-09-04 21:39] [ALPM] upgraded postgresql-libs (9.6.4-1 -> 9.6.4-3)
[2017-09-04 21:39] [ALPM] upgraded postgresql (9.6.4-1 -> 9.6.4-3)
[2017-09-04 21:39] [ALPM] upgraded rxvt-unicode-terminfo (9.22-3 -> 9.22-5)
[2017-09-04 21:39] [ALPM] upgraded vim-runtime (8.0.0987-1 -> 8.0.0987-3)
[2017-09-04 21:39] [ALPM] upgraded vim (8.0.0987-1 -> 8.0.0987-3)
[2017-09-04 21:39] [ALPM] upgraded weechat (1.9-2 -> 1.9-4)
[2017-09-04 21:39] [ALPM] transaction completed
[2017-09-04 21:39] [ALPM] running 'detect-old-perl-modules.hook'...
[2017-09-04 21:39] [ALPM] running 'gtk-update-icon-cache.hook'...
[2017-09-04 21:39] [ALPM] running 'systemd-update.hook'...
[2017-09-04 21:46] [PACMAN] Running '/usr/bin/pacman -U /home/cristo/rxvt-unicode-pixbuf/rxvt-unicode-pixbuf-9.22-2-x86_64.pkg.tar.xz'
[2017-09-04 21:46] [ALPM] transaction started
[2017-09-04 21:46] [ALPM] reinstalled rxvt-unicode-pixbuf (9.22-2)
[2017-09-04 21:46] [ALPM] transaction completed
[2017-09-04 21:46] [ALPM] running 'systemd-update.hook'...
[2017-09-04 21:46] [ALPM] running 'update-desktop-database.hook'...
[2017-09-04 21:46] [PACMAN] Running 'pacman -S pulseaudio-equalizer'
[2017-09-04 21:47] [ALPM] transaction started
[2017-09-04 21:47] [ALPM] installed sip (4.19.3-1)
[2017-09-04 21:47] [ALPM] installed python-sip (4.19.3-1)
[2017-09-04 21:47] [ALPM] installed python-dbus-common (1.2.4-2)
[2017-09-04 21:47] [ALPM] installed python-dbus (1.2.4-2)
[2017-09-04 21:47] [ALPM] installed pyqt4-common (4.12.1-1)
[2017-09-04 21:47] [ALPM] installed python-pyqt4 (4.12.1-1)
[2017-09-04 21:47] [ALPM] installed pulseaudio-equalizer (10.0-3)
[2017-09-04 21:47] [ALPM] transaction completed
[2017-09-04 21:47] [ALPM] running 'systemd-update.hook'...

I hope someone will help me, thanks.

I also hope that it is me to blame, because I liked Arch very much so far and it worked like a charm

Last edited by tlenbit (2017-09-06 15:05:26)

brebs · 2017-09-06 15:16:28

See thread.

Check your /etc/nsswitch.conf, especially for "wins".

tlenbit · 2017-09-06 16:00:53

I googled it already and it looks like I don't have any samba packages and "wins" in /etc/nsswitch.conf

~ $ pacman -Qs samba
 ~ $ pacman -Qs smb
local/lm_sensors 3.4.0-2
    Collection of user space tools for general SMBus access and hardware monitoring
 ~ $ cat /etc/nsswitch.conf
# Begin /etc/nsswitch.conf

passwd: compat mymachines systemd
group: compat mymachines systemd
shadow: compat

publickey: files

hosts: files mymachines resolve [!UNAVAIL=return] dns myhostname
networks: files

protocols: files
services: files
ethers: files
rpc: files

netgroup: files

# End /etc/nsswitch.conf

Last edited by tlenbit (2017-09-06 16:01:12)

seth · 2017-09-07 06:18:23

ps aux | grep resolved

(iow, are you using systemd-networkd?)

tlenbit · 2017-09-09 10:58:14

I don't explicitly use systemd-networkd, I use netctl. (Computer is not near at the time, as soon as it will be, I'll run the command above)

Is it a way to reinstall all network-concerned packets? or maybe I should use strace in any way? I tried to strace ping, but didn't understand anything in output, except that "poll" system call takes place while delay

Last edited by tlenbit (2017-09-09 10:59:25)

seth · 2017-09-09 15:03:47

You could check the general package integrity: "pacman -Qkk", but re-installing things wont help by itself (unless you conducted a partial update before - which is unsupported because it causes funny effects)

Try stracing some context to see *what* is being polled.

strace ping google.com 2>&1| grep -E '(open|poll)'

You'll have to do this as root or get an error.

tlenbit · 2017-09-11 13:04:06

I don't use system-networkd and here is strace output

# strace ping google.com 2>&1| grep -E '(open|poll)'
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libcap.so.2", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libidn.so.11", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libcrypto.so.1.1", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libresolv.so.2", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
open("/etc/nsswitch.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/host.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_files.so.2", O_RDONLY|O_CLOEXEC) = 5
open("/etc/hosts", O_RDONLY|O_CLOEXEC)  = 5
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_mymachines.so.2", O_RDONLY|O_CLOEXEC) = 5
open("/run/systemd/machines/google.com", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_resolve.so.2", O_RDONLY|O_CLOEXEC) = 5
open("/sys/fs/kdbus/0-system/bus", O_RDWR|O_NOCTTY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999935000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999810157})
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_dns.so.2", O_RDONLY|O_CLOEXEC) = 5
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 1 ([{fd=5, revents=POLLIN}])
<~5 SEC DELAY HERE>
poll([{fd=5, events=POLLIN}], 1, 4997)  = 0 (Timeout)
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
<~5 SEC DELAY HERE>
poll([{fd=5, events=POLLIN}], 1, 5000)  = 0 (Timeout)
poll([{fd=6, events=POLLOUT}], 1, 0)    = 1 ([{fd=6, revents=POLLOUT}])
poll([{fd=6, events=POLLIN}], 1, 5000)  = 1 ([{fd=6, revents=POLLIN}])
poll([{fd=6, events=POLLOUT}], 1, 4978) = 1 ([{fd=6, revents=POLLOUT}])
poll([{fd=6, events=POLLIN}], 1, 4978)  = 0 (Timeout)
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 1 ([{fd=5, revents=POLLIN}])
poll([{fd=5, events=POLLOUT}], 1, 4977) = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 4977)  = 0 (Timeout)
open("/etc/gai.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/hosts", O_RDONLY|O_CLOEXEC)  = 5
open("/sys/fs/kdbus/0-system/bus", O_RDWR|O_NOCTTY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999902000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999723552})
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 1 ([{fd=5, revents=POLLIN}])
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_myhostname.so.2", O_RDONLY|O_CLOEXEC) = 5
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999914000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999912864})
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999806000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999805348})
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999874000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999873109})
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999755000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999754413})
poll([{fd=3, events=POLLIN|POLLERR}], 1, 975) = 0 (Timeout)
poll([{fd=3, events=POLLIN|POLLERR}], 1, 996) = 0 (Timeout)
poll([{fd=3, events=POLLIN|POLLERR}], 1, 997) = 0 (Timeout)
poll([{fd=3, events=POLLIN|POLLERR}], 1, 997) = 0 (Timeout)

seth · 2017-09-11 13:28:14

I don't think it's polling a symlink, try

strace ping google.com 2>&1| grep -E '(open|poll|socket)'

Also provide the output of "hostnamectl" and your /etc/hosts (if you feel like you need to censor the latter file, please do so explicitly

tlenbit · 2017-09-11 14:22:30

# strace ping google.com 2>&1| grep -E '(open|poll|socket)'
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libcap.so.2", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libidn.so.11", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libcrypto.so.1.1", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libresolv.so.2", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
open("/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
socket(AF_INET, SOCK_DGRAM, IPPROTO_ICMP) = -1 EACCES (Permission denied)
socket(AF_INET, SOCK_RAW, IPPROTO_ICMP) = 3
socket(AF_INET6, SOCK_DGRAM, IPPROTO_ICMPV6) = -1 EACCES (Permission denied)
socket(AF_INET6, SOCK_RAW, IPPROTO_ICMPV6) = 4
socket(AF_UNIX, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 5
connect(5, {sa_family=AF_UNIX, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
socket(AF_UNIX, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 5
connect(5, {sa_family=AF_UNIX, sun_path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
open("/etc/nsswitch.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/host.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/resolv.conf", O_RDONLY|O_CLOEXEC) = 5
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_files.so.2", O_RDONLY|O_CLOEXEC) = 5
open("/etc/hosts", O_RDONLY|O_CLOEXEC)  = 5
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_mymachines.so.2", O_RDONLY|O_CLOEXEC) = 5
open("/run/systemd/machines/google.com", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_resolve.so.2", O_RDONLY|O_CLOEXEC) = 5
open("/sys/fs/kdbus/0-system/bus", O_RDWR|O_NOCTTY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
socket(AF_UNIX, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 5
connect(5, {sa_family=AF_UNIX, sun_path="/var/run/dbus/system_bus_socket"}, 33) = 0
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999879000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999789770})
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_dns.so.2", O_RDONLY|O_CLOEXEC) = 5
socket(AF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 5
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 1 ([{fd=5, revents=POLLIN}])
poll([{fd=5, events=POLLIN}], 1, 4997)  = 0 (Timeout)
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 0 (Timeout)
socket(AF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 6
poll([{fd=6, events=POLLOUT}], 1, 0)    = 1 ([{fd=6, revents=POLLOUT}])
poll([{fd=6, events=POLLIN}], 1, 5000)  = 1 ([{fd=6, revents=POLLIN}])
poll([{fd=6, events=POLLOUT}], 1, 4995) = 1 ([{fd=6, revents=POLLOUT}])
poll([{fd=6, events=POLLIN}], 1, 4995)  = 0 (Timeout)
socket(AF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 5
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 1 ([{fd=5, revents=POLLIN}])
socket(AF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 5
poll([{fd=5, events=POLLOUT}], 1, 4979) = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 4978)  = 0 (Timeout)
open("/etc/gai.conf", O_RDONLY|O_CLOEXEC) = 5
socket(AF_NETLINK, SOCK_RAW, NETLINK_ROUTE) = 5
socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 5
socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 5
open("/etc/hosts", O_RDONLY|O_CLOEXEC)  = 5
open("/sys/fs/kdbus/0-system/bus", O_RDWR|O_NOCTTY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
socket(AF_UNIX, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 5
connect(5, {sa_family=AF_UNIX, sun_path="/var/run/dbus/system_bus_socket"}, 33) = 0
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999852000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999695822})
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=44, tv_nsec=999940000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=44, tv_nsec=999939228})
socket(AF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 5
poll([{fd=5, events=POLLOUT}], 1, 0)    = 1 ([{fd=5, revents=POLLOUT}])
poll([{fd=5, events=POLLIN}], 1, 5000)  = 1 ([{fd=5, revents=POLLIN}])
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 5
open("/usr/lib/libnss_myhostname.so.2", O_RDONLY|O_CLOEXEC) = 5
socket(AF_NETLINK, SOCK_RAW|SOCK_CLOEXEC|SOCK_NONBLOCK, NETLINK_ROUTE) = 5
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999898000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999896260})
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999740000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999738204})
socket(AF_NETLINK, SOCK_RAW|SOCK_CLOEXEC|SOCK_NONBLOCK, NETLINK_ROUTE) = 5
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999864000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999862577})
ppoll([{fd=5, events=POLLIN}], 1, {tv_sec=24, tv_nsec=999739000}, NULL, 8) = 1 ([{fd=5, revents=POLLIN}], left {tv_sec=24, tv_nsec=999738175})
poll([{fd=3, events=POLLIN|POLLERR}], 1, 972) = 0 (Timeout)
poll([{fd=3, events=POLLIN|POLLERR}], 1, 997) = 0 (Timeout)
poll([{fd=3, events=POLLIN|POLLERR}], 1, 982) = 0 (Timeout)
poll([{fd=3, events=POLLIN|POLLERR}], 1, 997) = 0 (Timeout)

# hostnamectl
   Static hostname: hostarch
         Icon name: computer-laptop
           Chassis: laptop
        Machine ID: 5fe56cdd733b49bea8f6eb2f6c3528a8
           Boot ID: b856761c043b4a9a96b01e8adc85c3c3
  Operating System: Arch Linux
            Kernel: Linux 4.12.8-2-ARCH
      Architecture: x86-64

# cat /etc/hosts 
#
# /etc/hosts: static lookup table for host names
#

#<ip-address>	<hostname.domain.org>	<hostname>
127.0.0.1	localhost.localdomain	localhost
::1		localhost.localdomain	localhost

127.0.1.1       hostarch.localdomain    hostarch

# End of file

tlenbit · 2017-09-11 15:56:15

I used wget today and it was "Resolving <somehost>.com" with delay. And, as I already said, ping works normally only with IPs, so maybe this is a dns problem. But drill works ok and browser too (maybe there is some dns caching in it though)

progandy · 2017-09-11 19:12:47

drill is probably using a "raw" socket and sending the nameserver protocol manually.
Ping probably uses the glibc dns resolution with nsswitch, so try this (maybe with strace):

getent ahosts google.com

tlenbit · 2017-09-11 19:29:57

Disabling ipv6 solved issue (added ipv6.disable=1 option in kernel line).

I should have done this primarily like they said in similar issue, but I didn't (because it didn't help that time). I can't get what did I do wrong though and disabling ipv6 seems to be just a temporary fix

Seth, progandy and brebs thank you very much for your effort

Last edited by tlenbit (2017-09-11 19:30:32)

tlenbit · 2017-09-11 19:37:57

Do I understand correctly that it uses same libs like ping?
Anyways, this went without delays

# getent ahosts google.com
173.194.32.166  STREAM google.com
173.194.32.166  DGRAM  
173.194.32.166  RAW    
173.194.32.167  STREAM 
173.194.32.167  DGRAM  
173.194.32.167  RAW    
173.194.32.168  STREAM 
173.194.32.168  DGRAM  
173.194.32.168  RAW    
173.194.32.169  STREAM 
173.194.32.169  DGRAM  
173.194.32.169  RAW    
173.194.32.174  STREAM 
173.194.32.174  DGRAM  
173.194.32.174  RAW    
173.194.32.160  STREAM 
173.194.32.160  DGRAM  
173.194.32.160  RAW    
173.194.32.161  STREAM 
173.194.32.161  DGRAM  
173.194.32.161  RAW    
173.194.32.162  STREAM 
173.194.32.162  DGRAM  
173.194.32.162  RAW    
173.194.32.163  STREAM 
173.194.32.163  DGRAM  
173.194.32.163  RAW    
173.194.32.164  STREAM 
173.194.32.164  DGRAM  
173.194.32.164  RAW    
173.194.32.165  STREAM 
173.194.32.165  DGRAM  
173.194.32.165  RAW

seth · 2017-09-11 20:15:20

Drill and nmap do - unlike ping, ding and nslookup - not link libresolv.

tlenbit wrote:

Disabling ipv6 solved issue (added ipv6.disable=1 option in kernel line).

I assume you do not expect IPv6 support atm.?
But there might be some bug or misconfiguration that's gonna bite you if you ever will.

The delays should not be in the polls (all poll ip4 sockets) - what's the output (w/ IPv6 enabled) of

ip -6 route
ip -4 route
ip route
cat /etc/resolv*

tlenbit · 2017-09-11 21:20:25

No, I do not use ipv6 in any explicit way

// I've meant not "temporary fix", but rather "not satisfying fix" or "crutch" if I may say that. Excuse my english btw, I know it is a bit ugly

/home/cristo # ip -6 route
fe80::/64 dev wlp4s0 proto kernel metric 256 pref medium
 /home/cristo # ip -4 route
default via 192.168.0.1 dev wlp4s0 src 192.168.0.59 metric 303 
192.168.0.0/24 dev wlp4s0 proto kernel scope link src 192.168.0.59 metric 303 
 /home/cristo # ip route
default via 192.168.0.1 dev wlp4s0 src 192.168.0.59 metric 303 
192.168.0.0/24 dev wlp4s0 proto kernel scope link src 192.168.0.59 metric 303 
 /home/cristo # cat /etc/resolv*
# Generated by resolvconf
domain Dlink
nameserver 85.89.127.250
nameserver 8.8.4.4
#
# /etc/resolv.conf
#

#search <yourdomain.tld>
#nameserver <ip>

# End of file
# Configuration for resolvconf(8)
# See resolvconf.conf(5) for details

resolv_conf=/etc/resolv.conf
# If you run a local name server, you should uncomment the below line and
# configure your subscribers configuration files below.
#name_servers=127.0.0.1

Last edited by tlenbit (2017-09-11 21:22:17)

progandy · 2017-09-11 21:27:53

I believe a permission denied error for an ipv6 socket may occur if you only have link-local ipv6 addresses without any global scoped ones.
http://bbissett.blogspot.de/2015/06/ipv … ption.html

In that case, disabling IPv6 is probably one right choice, alternatively you have to assign a correct ipv6 address. If you do not have one, you can try to get your internet provider to assign you an ipv6 or use e.g. 6to4 or teredo. If you wish to use ipv6 only in your home network without ipv6 access to the internet, then you can get one here: http://simpledns.com/private-ipv6.aspx

Edit: This may be the result of ipv6 addresses being preferred over ipv4 even if you do not have an ipv6 address configured. Maybe try to change the preference in /etc/gai.conf. http://sf-alpha.bjgang.org/wordpress/20 … v4-exists/

uncomment this line:
#precedence ::ffff:0:0/96 100
-->
precedence ::ffff:0:0/96 100

Last edited by progandy (2017-09-11 21:38:07)

tlenbit · 2017-09-11 22:42:45

Changing precedence did not change the delays situation

Honestly, I didn't get why permission denied error may have anything to do with this, but if it has, why don't others face the same problem?

Anyways, maybe there is no point in further digging into this problem, I don't know

Arch Linux

#1 2017-09-06 14:56:23

Strange networking problem

#2 2017-09-06 15:16:28

Re: Strange networking problem

#3 2017-09-06 16:00:53

Re: Strange networking problem

#4 2017-09-07 06:18:23

Re: Strange networking problem

#5 2017-09-09 10:58:14

Re: Strange networking problem

#6 2017-09-09 15:03:47

Re: Strange networking problem

#7 2017-09-11 13:04:06

Re: Strange networking problem

#8 2017-09-11 13:28:14

Re: Strange networking problem

#9 2017-09-11 14:22:30

Re: Strange networking problem

#10 2017-09-11 15:56:15

Re: Strange networking problem

#11 2017-09-11 19:12:47

Re: Strange networking problem

#12 2017-09-11 19:29:57

Re: Strange networking problem

#13 2017-09-11 19:37:57

Re: Strange networking problem

#14 2017-09-11 20:15:20

Re: Strange networking problem

#15 2017-09-11 21:20:25

Re: Strange networking problem

#16 2017-09-11 21:27:53

Re: Strange networking problem

#17 2017-09-11 22:42:45

Re: Strange networking problem

Board footer