Сообразил простой скрипт для проверки обрывов в локальной сети
for((;;)); do
ping 192.168.161.100 -c 1 &> /dev/null
if [[ $? != 0 ]]; then
date
dmesg | tail >> dmesg.log
echo -e "\n\n" >> dmesg.log
journalctl | tail >> journal.log
echo -e "\n\n" >> journal.log
fi
sleep 1
done
За полдня было три сработки с периодичностью около часа:
Пн 13 мар 2023 10:03:28 MSK
Пн 13 мар 2023 11:01:04 MSK
Пн 13 мар 2023 12:32:03 MSK
journalctl совсем ничего интересного не показывает, а лог dmesg имеет следующий вид (при всех трех обрывах содержимое не менялось):
[73797.363306] usb 1-6.2: New USB device strings: Mfr=4, Product=40, SerialNumber=0
[73797.363307] usb 1-6.2: Product: Wired Mouse
[73797.363308] usb 1-6.2: Manufacturer: KYE SYSTEMS CORP.
[73797.368540] input: KYE SYSTEMS CORP. Wired Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-6/1-6.2/1-6.2:1.0/0003:0458:0186.0016/input/input33
[73797.368589] hid-generic 0003:0458:0186.0016: input,hidraw5: USB HID v1.11 Mouse [KYE SYSTEMS CORP. Wired Mouse] on usb-0000:00:14.0-6.2/input0
[73797.368645] usbhid 1-6.2:1.1: couldn't find an input interrupt endpoint
[91611.589670] perf: interrupt took too long (3972 > 3962), lowering kernel.perf_event_max_sample_rate to 50250
[110658.673157] audit: type=1400 audit(1678654808.634:29): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=171486 comm="cupsd" capability=12 capname="net_admin"
[110658.691980] audit: type=1400 audit(1678654808.654:30): apparmor="DENIED" operation="capable" profile="/usr/sbin/cups-browsed" pid=171516 comm="cups-browsed" capability=23 capname="sys_nice"
[145438.306107] perf: interrupt took too long (4970 > 4965), lowering kernel.perf_event_max_sample_rate to 40000
Караулю полный затык. Может быть при нем какие-то данные все-таки попадут в указанные выше логи.
Пользователь добавил сообщение 13 Марта 2023, 14:37:06:
Случился затык.
dmesg:
[159598.194193] usb 1-6-port2: disabled by hub (EMI?), re-enabling...
[159598.194629] usb 1-6.2: USB disconnect, device number 24
[159598.480922] usb 1-6.2: new low-speed USB device number 25 using xhci_hcd
[159598.586761] usb 1-6.2: New USB device found, idVendor=0458, idProduct=0186, bcdDevice=24.58
[159598.586774] usb 1-6.2: New USB device strings: Mfr=4, Product=40, SerialNumber=0
[159598.586779] usb 1-6.2: Product: Wired Mouse
[159598.586782] usb 1-6.2: Manufacturer: KYE SYSTEMS CORP.
[159598.592904] input: KYE SYSTEMS CORP. Wired Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-6/1-6.2/1-6.2:1.0/0003:0458:0186.0017/input/input34
[159598.593180] hid-generic 0003:0458:0186.0017: input,hidraw5: USB HID v1.11 Mouse [KYE SYSTEMS CORP. Wired Mouse] on usb-0000:00:14.0-6.2/input0
[159598.593284] usbhid 1-6.2:1.1: couldn't find an input interrupt endpoint
В journalctl вроде как ничего полезного. В лог попал только перезапуск networking, до этого - праздные сообщения, не касающиеся сети:
мар 13 14:27:11 mainframe systemd[1]: Starting Hostname Service...
мар 13 14:27:11 mainframe dbus-daemon[1279]: [system] Successfully activated service 'org.freedesktop.hostname1'
мар 13 14:27:11 mainframe systemd[1]: Started Hostname Service.
мар 13 14:27:41 mainframe systemd[1]: systemd-hostnamed.service: Deactivated successfully.
мар 13 14:28:36 mainframe systemd[1]: Stopping Raise network interfaces...
мар 13 14:28:36 mainframe ifdown[245544]: /etc/network/if-down.d/resolved: 12: mystatedir: not found
мар 13 14:28:36 mainframe dhclient[245552]: Killed old client process
мар 13 14:28:36 mainframe ifdown[245552]: Killed old client process
мар 13 14:28:36 mainframe NetworkManager[1280]: <info> [1678706916.4031] manager: NetworkManager state is now DISCONNECTED
мар 13 14:28:37 mainframe dhclient[245552]: Internet Systems Consortium DHCP Client 4.4.1
мар 13 14:28:37 mainframe ifdown[245552]: Internet Systems Consortium DHCP Client 4.4.1
мар 13 14:28:37 mainframe ifdown[245552]: Copyright 2004-2018 Internet Systems Consortium.
мар 13 14:28:37 mainframe ifdown[245552]: All rights reserved.
мар 13 14:28:37 mainframe ifdown[245552]: For info, please visit https://www.isc.org/software/dhcp/
мар 13 14:28:37 mainframe dhclient[245552]: Copyright 2004-2018 Internet Systems Consortium.
мар 13 14:28:37 mainframe dhclient[245552]: All rights reserved.
мар 13 14:28:37 mainframe dhclient[245552]: For info, please visit https://www.isc.org/software/dhcp/
мар 13 14:28:37 mainframe dhclient[245552]:
мар 13 14:28:37 mainframe dhclient[245552]: Listening on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe ifdown[245552]: Listening on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe ifdown[245552]: Sending on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe ifdown[245552]: Sending on Socket/fallback
мар 13 14:28:37 mainframe dhclient[245552]: Sending on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe dhclient[245552]: Sending on Socket/fallback
мар 13 14:28:37 mainframe dhclient[245552]: DHCPRELEASE of 192.168.161.252 on enp4s0 to 192.168.161.100 port 67 (xid=0x3970a85a)
мар 13 14:28:37 mainframe ifdown[245552]: DHCPRELEASE of 192.168.161.252 on enp4s0 to 192.168.161.100 port 67 (xid=0x3970a85a)
мар 13 14:28:37 mainframe avahi-daemon[1276]: Withdrawing address record for 192.168.161.252 on enp4s0.
мар 13 14:28:37 mainframe avahi-daemon[1276]: Leaving mDNS multicast group on interface enp4s0.IPv4 with address 192.168.161.252.
мар 13 14:28:37 mainframe avahi-daemon[1276]: Interface enp4s0.IPv4 no longer relevant for mDNS.
мар 13 14:28:37 mainframe avahi-daemon[1276]: Interface enp4s0.IPv6 no longer relevant for mDNS.
мар 13 14:28:37 mainframe avahi-daemon[1276]: Leaving mDNS multicast group on interface enp4s0.IPv6 with address fe80::da5e:d3ff:fea5:ecd1.
мар 13 14:28:37 mainframe avahi-daemon[1276]: Withdrawing address record for fe80::da5e:d3ff:fea5:ecd1 on enp4s0.
мар 13 14:28:37 mainframe systemd[1]: networking.service: Deactivated successfully.
мар 13 14:28:37 mainframe systemd[1]: Stopped Raise network interfaces.
мар 13 14:28:37 mainframe systemd[1]: Starting Raise network interfaces...
мар 13 14:28:37 mainframe dhclient[245628]: Internet Systems Consortium DHCP Client 4.4.1
мар 13 14:28:37 mainframe ifup[245628]: Internet Systems Consortium DHCP Client 4.4.1
мар 13 14:28:37 mainframe ifup[245628]: Copyright 2004-2018 Internet Systems Consortium.
мар 13 14:28:37 mainframe ifup[245628]: All rights reserved.
мар 13 14:28:37 mainframe ifup[245628]: For info, please visit https://www.isc.org/software/dhcp/
мар 13 14:28:37 mainframe dhclient[245628]: Copyright 2004-2018 Internet Systems Consortium.
мар 13 14:28:37 mainframe dhclient[245628]: All rights reserved.
мар 13 14:28:37 mainframe dhclient[245628]: For info, please visit https://www.isc.org/software/dhcp/
мар 13 14:28:37 mainframe dhclient[245628]:
мар 13 14:28:37 mainframe NetworkManager[1280]: <info> [1678706917.6276] manager: NetworkManager state is now CONNECTED_GLOBAL
мар 13 14:28:37 mainframe dhclient[245628]: Listening on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe ifup[245628]: Listening on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe ifup[245628]: Sending on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe ifup[245628]: Sending on Socket/fallback
мар 13 14:28:37 mainframe ifup[245628]: DHCPDISCOVER on enp4s0 to 255.255.255.255 port 67 interval 3 (xid=0xa5a10218)
мар 13 14:28:37 mainframe dhclient[245628]: Sending on LPF/enp4s0/d8:5e:d3:a5:ec:d1
мар 13 14:28:37 mainframe dhclient[245628]: Sending on Socket/fallback
мар 13 14:28:37 mainframe dhclient[245628]: DHCPDISCOVER on enp4s0 to 255.255.255.255 port 67 interval 3 (xid=0xa5a10218)
мар 13 14:28:40 mainframe dhclient[245628]: DHCPDISCOVER on enp4s0 to 255.255.255.255 port 67 interval 8 (xid=0xa5a10218)
мар 13 14:28:40 mainframe ifup[245628]: DHCPDISCOVER on enp4s0 to 255.255.255.255 port 67 interval 8 (xid=0xa5a10218)
мар 13 14:28:40 mainframe kernel: igc 0000:04:00.0 enp4s0: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
мар 13 14:28:40 mainframe NetworkManager[1280]: <info> [1678706920.9070] device (enp4s0): carrier: link connected
мар 13 14:28:40 mainframe kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp4s0: link becomes ready
мар 13 14:28:42 mainframe avahi-daemon[1276]: Joining mDNS multicast group on interface enp4s0.IPv6 with address fe80::da5e:d3ff:fea5:ecd1.
мар 13 14:28:42 mainframe avahi-daemon[1276]: New relevant interface enp4s0.IPv6 for mDNS.
мар 13 14:28:42 mainframe avahi-daemon[1276]: Registering new address record for fe80::da5e:d3ff:fea5:ecd1 on enp4s0.*.
мар 13 14:28:48 mainframe dhclient[245628]: DHCPDISCOVER on enp4s0 to 255.255.255.255 port 67 interval 8 (xid=0xa5a10218)
мар 13 14:28:48 mainframe ifup[245628]: DHCPDISCOVER on enp4s0 to 255.255.255.255 port 67 interval 8 (xid=0xa5a10218)
мар 13 14:28:48 mainframe dhclient[245628]: DHCPOFFER of 192.168.161.252 from 192.168.161.100
мар 13 14:28:48 mainframe ifup[245628]: DHCPOFFER of 192.168.161.252 from 192.168.161.100
мар 13 14:28:48 mainframe ifup[245628]: DHCPREQUEST for 192.168.161.252 on enp4s0 to 255.255.255.255 port 67 (xid=0x1802a1a5)
мар 13 14:28:48 mainframe dhclient[245628]: DHCPREQUEST for 192.168.161.252 on enp4s0 to 255.255.255.255 port 67 (xid=0x1802a1a5)
мар 13 14:28:48 mainframe dhclient[245628]: DHCPACK of 192.168.161.252 from 192.168.161.100 (xid=0xa5a10218)
мар 13 14:28:48 mainframe ifup[245628]: DHCPACK of 192.168.161.252 from 192.168.161.100 (xid=0xa5a10218)
мар 13 14:28:48 mainframe avahi-daemon[1276]: Joining mDNS multicast group on interface enp4s0.IPv4 with address 192.168.161.252.
мар 13 14:28:48 mainframe avahi-daemon[1276]: New relevant interface enp4s0.IPv4 for mDNS.
мар 13 14:28:48 mainframe avahi-daemon[1276]: Registering new address record for 192.168.161.252 on enp4s0.IPv4.
мар 13 14:28:48 mainframe systemd-resolved[1268]: enp4s0: Bus client set DNS server list to: 192.168.161.100
мар 13 14:28:48 mainframe dhclient[245628]: bound to 192.168.161.252 -- renewal in 20867 seconds.
мар 13 14:28:48 mainframe ifup[245628]: bound to 192.168.161.252 -- renewal in 20867 seconds.
Не хватает навыков, чтобы понять в какую сторону еще копать логи в момент сетевой ошибки (затыка, когда пропадают все пинги)...
Пользователь добавил сообщение 13 Марта 2023, 16:04:11:
Буду снова ждать затык. Сниму дамп сетевых пакетов wireshark'ом, а также проверю наличие пингов на 127.0.0.1.
Пользователь добавил сообщение 13 Марта 2023, 19:46:04:
Снова пропала сеть. На этот раз не прошло и половины дня. В прошлый раз, как я написал в первом посте, на другой Debian-подобной ОС было аналогичное поведение: сначала раз в сутки-двое, а затем затыки участились.
Проверил пинги во время "затыка" сети: все локальные интерфейсы пингуются, как 127.0.0.1/8, так и 192.168.161.252/24. При этом пара машин в локальной сети являются недоступными, а так как одна из них - маршрутизатор, то и доступа в интернет нет.
Запустил wireshark. Все что он поймал на интерфейсе: ARP запросы с поиском машин пингуемых мной адресов 192.168.161.1 и 192.168.161.100. Прикладываю на всякий случай PCAP-файл, если кто-то захочет увидеть это пассивное поведение сети без единого левого пакетика:
https://paste.i2pd.xyz/?b7e382b8d413e679#94qukKFdntWWzdgAjqwhqtFrHdxz4MNam18fYwKJZuwb.
Как и раньше: только сделал рестарт networking - вся сеть завелась, пинги побежали и прочее. Что за магия, седых волос уже не хватает