Přeskočit na obsah

Xubuntu/Bionic/Problémy/Zatuhnutí

Z Wikiverzity

Xubuntu/Bionic/Problémy/Zatuhnutí

Problém: "Zatuhnutí" notebooku

Info

[editovat]

Projevy

[editovat]
  • Během práce pojednou počítač přestane reagovat na vše - na klávesnice (vlastní i připojenou), myš, trackball
  • Obrazovka "zamrzne" - zobrazuje se stále to samé, ani kursor se nepohybuje
  • Pokus o připojení přes síť přes SSH nefunguje
  • Kontrolní LEDky na klávesnici nereagují ani na NumLock
  • V určitých intervalech (cca 2x za minutu) se na chvíli rozeběhne větrák notebooku, jakoby tam probíhal nějaký náročný proces
  • Odpojení a opětné připojení různých USB - žádná reakce

Pokusy o vypnutí systému

[editovat]
  • Na kombinace kláves Ctrl-Alt-Del ani Ctrl-Alt-Backspace (shození X) nereaguje
  • Na SysRq nereaguje
  • Jediná možnost: Vypnutí notebooku

Předcházející problémy

[editovat]
  • Problém se začal objevovat nějakou dobu po tom, co byla zprovozněna hibernace: Ubuntu/hibernace#18.04 Xubuntu
  • Hibernace zpočátku fungovala, ale občas se po probuzení notebook choval nepředvídatelně
  • Občas se problémy objevovaly i po uspání do paměti
  • Proto jsem namísto hibernace i uspání notebook vypínal
  • V podezření byla swapovací oblast disku (že se možná častým swapováním vyčerpal SSD disk), proto bulo swapování vypnuto

Možné příčiny

[editovat]
  • První podezření padá na HW problém - proto testy pomocí testovacích funkcí firemního BIOSu LENOVO:
    • Kompletní test - OK
    • Důkladný test paměti - OK
    • SMART SSD disku - O.K.

Firmware

[editovat]
  • Při řešení problému s hibernací byla z prostředí Windows10 m.j. provedena aktualizace firmware pomocí Lenovo Vantage Možná došlo k nějakému konfliktu s linuxovým jádrem?

Aplikace

[editovat]
  • K zatuhnutí začlo docházet během práce na Google Chrome
  • Potom ale i během práce s Mozilla Firefox
  • Poslední zamrznutí při psaní e-mailu na Mozilla Thunderbird
  • Při správně fungujícím OS by nemělo zatuhnutí aplikace způsobit zatuhnutí celého systému
  • Nicméně to může nasvědčovat tomu, že zatuhnutí nějak souvisí s problémem s připojením přes WiFi

fvwm

[editovat]
  • Při spuštění i během práce fvwm hlásí nějaké chyby. Mohla by to být příčina problému?

Poslední možností je nějaká chyba v OS či jeho součásti - jádro či moduly. Možná ještě v kombinaci s upradeovanými firmware - viz výše.

Postup řešení

[editovat]

Focal

[editovat]
  • Dosud nevyužité místo na SSD disku bylo rozděleno na dvě partition a do jedné z nich nainstalováno Xubuntu/Focal 20.04 LTE
uname -a
Linux x2 5.11.0-27-generic #29~20.04.1-Ubuntu SMP Wed Aug 11 15:58:17 UTC 2021 x86_64 x86_64 x86_64 GNU/Linux
  • Při práci na této novější verzi se tento problém dosud neprojevil
  • Z tohoto prostředí pak opravujeme root system na 18.04 a zkoumáme problém

2021-08-25 Analýza problému

[editovat]
  • 2021-08-25T13:34 středa došlo k poslednímu zatuhnutí při práci s Mozilla Firefox
  • 2021-08-25T13:42 skončily oživovací pokusy a notebook byl vypnut
/var/log/syslog  2021-08-25 13:34:57.124749904 +0200
…
Aug 25 13:08:00 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:08:00 x2 dbus-daemon[946]: [system] Activating service name='org.blueman.Mechanism' requested by ':1.106' (uid=1000 pid=3908 comm="/usr/bin/python3 /usr/bin/blueman-applet " label="unconfined") (using servic
ehelper)
Aug 25 13:08:00 x2 org.blueman.Mechanism[946]: Unable to init server: Could not connect: Connection refused
Aug 25 13:08:00 x2 org.blueman.Mechanism[946]: Unable to init server: Could not connect: Connection refused
Aug 25 13:08:00 x2 blueman-mechanism: Starting blueman-mechanism
Aug 25 13:08:00 x2 dbus-daemon[946]: [system] Successfully activated service 'org.blueman.Mechanism'
Aug 25 13:08:00 x2 rtkit-daemon[2387]: Supervising 3 threads of 1 processes of 1 users.
Aug 25 13:08:00 x2 rtkit-daemon[2387]: Supervising 3 threads of 1 processes of 1 users.
Aug 25 13:08:00 x2 blueman-mechani[4085]: gtk_icon_theme_get_for_screen: assertion 'GDK_IS_SCREEN (screen)' failed
Aug 25 13:08:00 x2 blueman-mechanism: loading Ppp
Aug 25 13:08:00 x2 blueman-mechanism: loading Network
Aug 25 13:08:00 x2 blueman-mechanism: loading RfKill
Aug 25 13:08:00 x2 blueman-mechanism: loading Rfcomm
Aug 25 13:08:00 x2 rtkit-daemon[2387]: Supervising 3 threads of 1 processes of 1 users.
Aug 25 13:08:00 x2 rtkit-daemon[2387]: message repeated 3 times: [ Supervising 3 threads of 1 processes of 1 users.]
Aug 25 13:08:00 x2 rtkit-daemon[2387]: Successfully made thread 4115 of process 3732 (n/a) owned by '1000' RT at priority 10.
Aug 25 13:08:00 x2 rtkit-daemon[2387]: Supervising 4 threads of 2 processes of 1 users.
Aug 25 13:08:01 x2 rtkit-daemon[2387]: message repeated 4 times: [ Supervising 4 threads of 2 processes of 1 users.]
Aug 25 13:08:01 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:08:01 x2 rtkit-daemon[2387]: Supervising 4 threads of 2 processes of 1 users.
Aug 25 13:08:01 x2 rtkit-daemon[2387]: message repeated 3 times: [ Supervising 4 threads of 2 processes of 1 users.]
Aug 25 13:08:02 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:08:30 x2 wpa_supplicant[968]: message repeated 28 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:08:30 x2 blueman-mechanism: Exiting
Aug 25 13:08:31 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:09:01 x2 wpa_supplicant[968]: message repeated 30 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:09:01 x2 CRON[4359]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Aug 25 13:09:02 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:09:02 x2 systemd[1]: Starting Clean php session files...
Aug 25 13:09:02 x2 systemd[1]: Started Clean php session files.
Aug 25 13:09:03 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:09:26 x2 wpa_supplicant[968]: message repeated 23 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:09:27 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:10:06 x2 wpa_supplicant[968]: message repeated 39 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:10:07 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:10:08 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:11:05 x2 wpa_supplicant[968]: message repeated 57 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:11:05 x2 wpa_supplicant[968]: wlxf4ec3889fa02: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-91 noise=9999 txrate=58500
Aug 25 13:11:06 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:11:29 x2 wpa_supplicant[968]: message repeated 23 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:11:29 x2 wpa_supplicant[968]: wlxf4ec3889fa02: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-93 noise=9999 txrate=14400
Aug 25 13:11:30 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:16:34 x2 wpa_supplicant[968]: message repeated 304 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:16:34 x2 systemd[1]: Starting Cleanup of Temporary Directories...
Aug 25 13:16:34 x2 systemd[1]: Started Cleanup of Temporary Directories.
Aug 25 13:16:35 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:17:01 x2 wpa_supplicant[968]: message repeated 26 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:17:01 x2 CRON[4896]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Aug 25 13:17:02 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:25:59 x2 wpa_supplicant[968]: message repeated 536 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:26:00 x2 indicator-messages-service[3769]: [calBackendLoader] Using Thunderbird's libical backend
Aug 25 13:26:00 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:26:01 x2 indicator-messages-service[3769]: console.debug: "Successfully loaded OpenPGP library librnp.so version 0.14+git20210121.7c8492b4.MZLA from /usr/lib/thunderbird/librnp.so"
Aug 25 13:26:01 x2 indicator-messages-service[3769]: Extension error: Error while loading 'jar:file:///usr/lib/thunderbird/extensions/messagingmenu@mozilla.com.xpi!/manifest.json' (NS_ERROR_FILE_NOT_FOUND) resource://
gre/modules/Extension.jsm:570 :: readJSON/</<@resource://gre/modules/Extension.jsm:570:20
Aug 25 13:26:01 x2 indicator-messages-service[3769]: onStopRequest@resource://gre/modules/NetUtil.jsm:128:18
Aug 25 13:26:01 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:26:01 x2 indicator-messages-service[3769]: console.debug: "Found 0 public keys and 0 secret keys (0 protected, 0 unprotected)"
Aug 25 13:26:01 x2 indicator-messages-service[3769]: console.debug: "Successfully loaded optional OpenPGP library libgpgme.so.11 from system's standard library locations"
Aug 25 13:26:01 x2 indicator-messages-service[3769]: console.debug: "gpgme version: 1.10.0"
Aug 25 13:26:02 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:26:02 x2 indicator-messages-service[3769]: console.debug: "Trying to load /usr/lib/thunderbird/libotr.so.5"
Aug 25 13:26:02 x2 indicator-messages-service[3769]: console.debug: "Trying to load libotr.so.5 from system's standard library locations"
Aug 25 13:26:02 x2 indicator-messages-service[3769]: console.debug: "Successfully loaded OTR library libotr.so.5 from system's standard library locations"
Aug 25 13:26:03 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:26:07 x2 wpa_supplicant[968]: message repeated 4 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:26:08 x2 indicator-messages-service[3769]: JavaScript error: resource:///modules/AddrBookCard.jsm, line 197: NS_ERROR_NOT_AVAILABLE: PreferDisplayName: undefined - not a boolean
Aug 25 13:26:08 x2 indicator-messages-service[3769]: message repeated 25 times: [ JavaScript error: resource:///modules/AddrBookCard.jsm, line 197: NS_ERROR_NOT_AVAILABLE: PreferDisplayName: undefined - not a boolean]
Aug 25 13:26:08 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:26:44 x2 wpa_supplicant[968]: message repeated 36 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:26:44 x2 indicator-messages-service[3769]: JavaScript error: resource:///modules/AddrBookCard.jsm, line 197: NS_ERROR_NOT_AVAILABLE: PreferDisplayName: undefined - not a boolean
Aug 25 13:26:45 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 25 13:31:19 x2 wpa_supplicant[968]: message repeated 273 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 25 13:31:19 x2 rtkit-daemon[2387]: Supervising 4 threads of 2 processes of 1 users.
Aug 25 13:31:19 x2 rtkit-daemon[2387]: Supervising 4 threads of 2 processes of 1 users.
Aug 25 13:31:20 x2 wpa_supplicant[968]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
.........................................................................................................................................................................................................................
.........................................................................................................................................................................................................................
.........................................................................................................................................................................................................................
.......................................................................................................................................................................
…
  • To odpovídá tomu, že Firefox předtím párktát zahlásil, že má problémy s připojením
  • Možný problém by v tom případě mohl být wpa_supplicant, se kterým už v dávné minulosti občas některé problémy bývávaly (rozjel nějaký proces a žral procesor - tomu by odpovídalo i to rozbíhání větráku)
  • /var/log/syslog končí řadou asi 3000 nulových bytes
  • po posledním zápisu 13:31:20 ještě asi 3 minuty syslog zapisoval, ale semé nulové bytes
kern.log 2021-08-25 13:34:57.124749904 +0200
…
Aug 25 13:02:15 x2 kernel: [   42.648528] Bluetooth: RFCOMM TTY layer initialized
Aug 25 13:02:15 x2 kernel: [   42.648534] Bluetooth: RFCOMM socket layer initialized
Aug 25 13:02:15 x2 kernel: [   42.648539] Bluetooth: RFCOMM ver 1.11
Aug 25 13:07:12 x2 kernel: [  339.372632] wlxf4ec3889fa02: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 25 13:07:29 x2 kernel: [  356.298132] wlxf4ec3889fa02: authenticate with c8:5a:9f:e6:20:0f
Aug 25 13:07:29 x2 kernel: [  356.319981] wlxf4ec3889fa02: send auth to c8:5a:9f:e6:20:0f (try 1/3)
Aug 25 13:07:29 x2 kernel: [  356.321467] wlxf4ec3889fa02: authenticated
Aug 25 13:07:29 x2 kernel: [  356.323865] wlxf4ec3889fa02: associate with c8:5a:9f:e6:20:0f (try 1/3)
Aug 25 13:07:29 x2 kernel: [  356.327516] wlxf4ec3889fa02: RX AssocResp from c8:5a:9f:e6:20:0f (capab=0x431 status=0 aid=2)
Aug 25 13:07:29 x2 kernel: [  356.331150] wlxf4ec3889fa02: associated
.........................................................................................................................................................................................................................
.........................................................................................................................................................................................................................
.........................................................................................................................................................................................................................
…

Tak do kern.log se před inkriminovaným okamžikem nezapsalo nic. Kupodivu i do něj se na konci začaly zapisovat nulové bytes, i když asi jen necelý 1000.

2021-08-31 Zatuhnutí při uspávání

[editovat]

Popis problému

[editovat]

V úterý 2021-08-31 dopoledne kolem 9. hodiny po přípravě sazby Hesel jsem kliknul na uspání a v tu chvíli opět počítač okamžitě "zatuhnul":

  • Veškerý pohyb na obrazovce ustane, ale obrazovka přitom stále svítí
  • Nereaguje na klávesnici ani na myš
  • Externí USB klávesnice ani myš nesvítí
  • LEDka na knoflíku pro zapnutí-vypnutí celého notebooku nesvítí
  • Nereaguje ani na interní klávesnici a touchpad
  • Ani na různé kombinace Ctrl-Alt-Del, Ctrl-Alt-Backspace ani Alt-SysRq-REISUB

(Za normálních okolností uspání vždycky pár sekund trvá a na obrazovce se přitom něco děje.)

  • Jediná možnost - vypnout notebook natvrdo dlouhm podržením knoflíku On/Off

Analýza problému

[editovat]

Nabootuji Xubuntu/Focal 20.04 LTE z /dev/nvme0n1p6 a potom z něj kontroluji problematický Xubuntu/Bionic 18.04 LTE:

root:

sudo e2fsck /dev/nvme0n1p6
e2fsck 1.45.5 (07-Jan-2020)
XENIAL_24G: obnovuje se žurnál
Mažu osiřelý iuzel 1067658 (uid=1000, gid=1000, práva=0100664, velikost=32768)
Mažu osiřelý iuzel 1067655 (uid=1000, gid=1000, práva=0100600, velikost=562408)
Mažu osiřelý iuzel 1071139 (uid=1000, gid=1000, práva=0100664, velikost=19631)
Mažu osiřelý iuzel 14 (uid=0, gid=0, práva=0100666, velikost=0)
Mažu osiřelý iuzel 159 (uid=1000, gid=1000, práva=0100600, velikost=20458)
Mažu osiřelý iuzel 1048184 (uid=1000, gid=1000, práva=0100664, velikost=19631)
Mažu osiřelý iuzel 1061063 (uid=1000, gid=1000, práva=0100664, velikost=32768)
Mažu osiřelý iuzel 1045513 (uid=1000, gid=1000, práva=0100600, velikost=561644)
Mažu osiřelý iuzel 1051710 (uid=1000, gid=1000, práva=0100664, velikost=19631)
Mažu osiřelý iuzel 1048789 (uid=1000, gid=1000, práva=0100664, velikost=19631)
Mažu osiřelý iuzel 64 (uid=121, gid=127, práva=0100600, velikost=0)
Mažu osiřelý iuzel 63 (uid=121, gid=127, práva=0100600, velikost=0)
Mažu osiřelý iuzel 62 (uid=121, gid=127, práva=0100600, velikost=0)
Mažu osiřelý iuzel 61 (uid=121, gid=127, práva=0100600, velikost=0)
Mažu osiřelý iuzel 59 (uid=121, gid=127, práva=0100600, velikost=0)
Nastavuje se počet volných iuzlů na 996761 (byl 996170)
Nastavuje se počet volných bloků na 448490 (byl 450383)
XENIAL_24G: čistý, 455719/1452480 souborů, 5352470/5800960 bloků

DATA:

sudo e2fsck /dev/nvme0n1p7
e2fsck 1.45.5 (07-Jan-2020)
Betsy+DATA_24G: obnovuje se žurnál
Mažu osiřelý iuzel 851047 (uid=1000, gid=1000, práva=0100664, velikost=1500)
Nastavuje se počet volných iuzlů na 1181355 (byl 1181474)
Nastavuje se počet volných bloků na 645977 (byl 651618)
Betsy+DATA_24G: čistý, 271125/1452480 souborů, 5154983/5800960 bloků

syslog, kern.log

sudo mount -t auto /dev/nvme0n1p6 /nvme0n1p6
less /nvme0n1p6/var/log/syslog
…
Aug 31 08:47:09 x2 indicator-messages-service[2393]: JavaScript error: resource:///modules/AddrBookCard.jsm, line 197: NS_ERROR_NOT_AVAILABLE: PreferDisplayName: undefined - not a boolean
Aug 31 08:47:09 x2 indicator-messages-service[2393]: message repeated 3 times: [ JavaScript error: resource:///modules/AddrBookCard.jsm, line 197: NS_ERROR_NOT_AVAILABLE: PreferDisplayName: undefined - not a boolean]
Aug 31 08:47:26 x2 indicator-messages-service[2393]: JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 554: uncaught exception: 2147500036
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1785] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1786] device (wlp59s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 31 08:55:35 x2 whoopsie[1501]: [08:55:35] offline
Aug 31 08:55:35 x2 wpa_supplicant[960]: nl80211: deinit ifname=p2p-dev-wlp59s0 disabled_11b_rates=0
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1821] device (A4:4B:D5:32:61:E1): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 31 08:55:35 x2 ModemManager[832]: [/dev/cdc-wdm0] channel destroyed
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1823] device (38:78:62:54:7F:CA): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1826] device (cdc-wdm0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'external')
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1828] manager: NetworkManager state is now ASLEEP
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1849] device (wlxf4ec3889fa02): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Aug 31 08:55:35 x2 dbus-daemon[885]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.13' (uid=0 pid=959 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.1876] device (wlxf4ec3889fa02): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Aug 31 08:55:35 x2 avahi-daemon[936]: Withdrawing address record for fe80::173e:a0c5:8e6:6574 on wlxf4ec3889fa02.
Aug 31 08:55:35 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlxf4ec3889fa02.IPv6 with address fe80::173e:a0c5:8e6:6574.
Aug 31 08:55:35 x2 avahi-daemon[936]: Interface wlxf4ec3889fa02.IPv6 no longer relevant for mDNS.
Aug 31 08:55:35 x2 systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 31 08:55:35 x2 dbus-daemon[885]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 31 08:55:35 x2 systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 31 08:55:35 x2 nm-dispatcher: req:1 'connectivity-change': new request (1 scripts)
Aug 31 08:55:35 x2 nm-dispatcher: req:1 'connectivity-change': start running ordered scripts...
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.2212] dhcp4 (wlxf4ec3889fa02): canceled DHCP transaction, DHCP client pid 17602
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.2213] dhcp4 (wlxf4ec3889fa02): state changed bound -> done
Aug 31 08:55:35 x2 avahi-daemon[936]: Withdrawing address record for 10.0.0.10 on wlxf4ec3889fa02.
Aug 31 08:55:35 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlxf4ec3889fa02.IPv4 with address 10.0.0.10.
Aug 31 08:55:35 x2 avahi-daemon[936]: Interface wlxf4ec3889fa02.IPv4 no longer relevant for mDNS.
Aug 31 08:55:35 x2 whoopsie[1501]: [08:55:35] Cannot reach: https://daisy.ubuntu.com
Aug 31 08:55:35 x2 nm-dispatcher: req:2 'down' [wlxf4ec3889fa02]: new request (1 scripts)
Aug 31 08:55:35 x2 nm-dispatcher: req:2 'down' [wlxf4ec3889fa02]: start running ordered scripts...
Aug 31 08:55:35 x2 NetworkManager[959]: <info>  [1630392935.2735] device (wlxf4ec3889fa02): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 31 08:55:35 x2 kernel: [53947.335625] wlxf4ec3889fa02: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 31 08:55:35 x2 wpa_supplicant[960]: nl80211: deinit ifname=wlp59s0 disabled_11b_rates=0
Aug 31 08:55:35 x2 wpa_supplicant[960]: wlxf4ec3889fa02: CTRL-EVENT-DISCONNECTED bssid=c8:5a:9f:e6:20:0f reason=3 locally_generated=1
Aug 31 08:55:35 x2 systemd[1]: Reached target Sleep.
Aug 31 08:55:35 x2 systemd[1]: Starting Suspend...
Aug 31 08:55:35 x2 kernel: [53947.507109] PM: suspend entry (deep)
Aug 31 08:55:35 x2 systemd-sleep[23232]: Suspending system...
less /nvme0n1p6/var/log/kern.log
…
Aug 31 06:59:25 x2 kernel: [46978.091431] IPv6: ADDRCONF(NETDEV_UP): wlp59s0: link is not ready
Aug 31 08:55:35 x2 kernel: [53947.335625] wlxf4ec3889fa02: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 31 08:55:35 x2 kernel: [53947.507109] PM: suspend entry (deep)

Minulé uspání:

Podívám se, kdy jsem naposledy uspával systém, a jak to probíhalo tenkrát:

cd /nvme0n1p6/var/log
grep Suspending syslog
sudo gunzip -k syslog.?.gz
grep Suspending syslog.?
syslog.1:Aug 30 23:38:13 x2 systemd-sleep[16648]: Suspending system...
syslog.1:Aug 31 06:58:38 x2 kernel: [46923.316789] Suspending console(s) (use no_console_suspend to debug)
Binární soubor syslog.4 odpovídá
syslog.5:Aug 21 13:59:33 x2 systemd-sleep[27344]: Suspending system...
syslog.5:Aug 21 18:47:21 x2 kernel: [73605.804603] Suspending console(s) (use no_console_suspend to debug)
syslog.6:Aug 20 23:57:58 x2 systemd-sleep[19848]: Suspending system...
syslog.6:Aug 21 09:08:03 x2 kernel: [56110.463196] Suspending console(s) (use no_console_suspend to debug)
grep suspend kern.log
Aug 30 23:38:13 x2 kernel: [46923.300055] PM: suspend entry (deep)
Aug 31 06:58:38 x2 kernel: [46923.316789] Suspending console(s) (use no_console_suspend to debug)
Aug 31 06:58:38 x2 kernel: [46930.682203] PM: suspend exit
Aug 31 08:55:35 x2 kernel: [53947.507109] PM: suspend entry (deep)

Tak tedy jak to probíhalo předtím, tj. Aug 30 23:38:13

less syslog.1
…
Aug 30 23:36:19 x2 wpa_supplicant[960]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 30 23:37:38 x2 wpa_supplicant[960]: message repeated 79 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 30 23:37:38 x2 indicator-messages-service[2393]: JavaScript error: resource://gre/modules/DeferredTask.jsm, line 215: Error: Unable to arm timer, the object has been finalized.
Aug 30 23:37:38 x2 indicator-messages-service[2393]: Extension error: Data is not ready. resource://gre/modules/JSONFile.jsm:173 :: get data@resource://gre/modules/JSONFile.jsm:173:13
Aug 30 23:37:38 x2 indicator-messages-service[2393]: _get@resource://gre/modules/ExtensionPermissions.jsm:74:17
Aug 30 23:37:38 x2 indicator-messages-service[2393]: observe@resource://gre/modules/AsyncShutdown.jsm:554:16
Aug 30 23:37:38 x2 indicator-messages-service[2393]: JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 554: uncaught exception: 2147500036
Aug 30 23:37:39 x2 wpa_supplicant[960]: wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1
Aug 30 23:38:13 x2 wpa_supplicant[960]: message repeated 34 times: [ wlp59s0: CTRL-EVENT-SCAN-FAILED ret=-95 retry=1]
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6076] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6078] device (38:78:62:54:7F:CA): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6080] device (A4:4B:D5:32:61:E1): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6082] device (cdc-wdm0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'external')
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6084] manager: NetworkManager state is now ASLEEP
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6091] device (wlp59s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 ModemManager[832]: [/dev/cdc-wdm0] channel destroyed
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6119] device (wlxf4ec3889fa02): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 whoopsie[1501]: [23:38:13] offline
Aug 30 23:38:13 x2 dbus-daemon[885]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.13' (uid=0 pid=959 comm="/usr/sbin/NetworkManager --no-daemon " label="unconf
ined")
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6149] device (wlp59s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 dnsmasq[1412]: exiting on receipt of SIGTERM
Aug 30 23:38:13 x2 avahi-daemon[936]: Withdrawing address record for fe80::6172:834c:e803:5f69 on wlp59s0.
Aug 30 23:38:13 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlp59s0.IPv6 with address fe80::6172:834c:e803:5f69.
Aug 30 23:38:13 x2 systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 30 23:38:13 x2 dbus-daemon[885]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 30 23:38:13 x2 systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 30 23:38:13 x2 nm-dispatcher: req:1 'connectivity-change': new request (1 scripts)
Aug 30 23:38:13 x2 nm-dispatcher: req:1 'connectivity-change': start running ordered scripts...
Aug 30 23:38:13 x2 wpa_supplicant[960]: wlp59s0: interface state ENABLED->DISABLED
Aug 30 23:38:13 x2 wpa_supplicant[960]: wlp59s0: AP-DISABLED
Aug 30 23:38:13 x2 wpa_supplicant[960]: wlp59s0: CTRL-EVENT-DISCONNECTED bssid=e0:9d:31:dc:72:10 reason=3 locally_generated=1
Aug 30 23:38:13 x2 kernel: [46922.967119] IPv6: ADDRCONF(NETDEV_UP): wlp59s0: link is not ready
Aug 30 23:38:13 x2 avahi-daemon[936]: Interface wlp59s0.IPv6 no longer relevant for mDNS.
Aug 30 23:38:13 x2 avahi-daemon[936]: Interface wlp59s0.IPv4 no longer relevant for mDNS.
Aug 30 23:38:13 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlp59s0.IPv4 with address 10.42.0.1.
Aug 30 23:38:13 x2 avahi-daemon[936]: Withdrawing address record for 10.42.0.1 on wlp59s0.
Aug 30 23:38:13 x2 avahi-daemon[936]: Joining mDNS multicast group on interface wlp59s0.IPv4 with address 10.42.0.1.
Aug 30 23:38:13 x2 avahi-daemon[936]: New relevant interface wlp59s0.IPv4 for mDNS.
Aug 30 23:38:13 x2 avahi-daemon[936]: Registering new address record for 10.42.0.1 on wlp59s0.IPv4.
Aug 30 23:38:13 x2 avahi-daemon[936]: Withdrawing address record for 10.42.0.1 on wlp59s0.
Aug 30 23:38:13 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlp59s0.IPv4 with address 10.42.0.1.
Aug 30 23:38:13 x2 avahi-daemon[936]: Interface wlp59s0.IPv4 no longer relevant for mDNS.
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6345] device (wlxf4ec3889fa02): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 avahi-daemon[936]: Withdrawing address record for fe80::173e:a0c5:8e6:6574 on wlxf4ec3889fa02.
Aug 30 23:38:13 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlxf4ec3889fa02.IPv6 with address fe80::173e:a0c5:8e6:6574.
Aug 30 23:38:13 x2 avahi-daemon[936]: Interface wlxf4ec3889fa02.IPv6 no longer relevant for mDNS.
Aug 30 23:38:13 x2 nm-dispatcher: req:2 'down' [wlp59s0]: new request (1 scripts)
Aug 30 23:38:13 x2 nm-dispatcher: req:2 'down' [wlp59s0]: start running ordered scripts...
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6671] dhcp4 (wlxf4ec3889fa02): canceled DHCP transaction, DHCP client pid 1980
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6671] dhcp4 (wlxf4ec3889fa02): state changed bound -> done
Aug 30 23:38:13 x2 avahi-daemon[936]: Withdrawing address record for 10.0.0.10 on wlxf4ec3889fa02.
Aug 30 23:38:13 x2 avahi-daemon[936]: Leaving mDNS multicast group on interface wlxf4ec3889fa02.IPv4 with address 10.0.0.10.
Aug 30 23:38:13 x2 avahi-daemon[936]: Interface wlxf4ec3889fa02.IPv4 no longer relevant for mDNS.
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6700] device (wlp59s0): supplicant interface state: completed -> disconnected
Aug 30 23:38:13 x2 nm-dispatcher: req:3 'down' [wlxf4ec3889fa02]: new request (1 scripts)
Aug 30 23:38:13 x2 whoopsie[1501]: [23:38:13] Cannot reach: https://daisy.ubuntu.com
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6706] device (wlp59s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6742] Executing: /sbin/iptables --table nat --delete POSTROUTING --source 10.42.0.0/255.255.255.0 ! --destination 10.42.0.0/255.255.255.0 --jump MASQUERADE
Aug 30 23:38:13 x2 nm-dispatcher: req:3 'down' [wlxf4ec3889fa02]: start running ordered scripts...
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.6764] Executing: /sbin/iptables --table filter --delete FORWARD --destination 10.42.0.0/255.255.255.0 --out-interface wlp59s0 --match state --state ESTABLISHED,RELATED --jump ACCEPT
Aug 30 23:38:13 x2 kernel: [46923.012211] wlxf4ec3889fa02: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 30 23:38:13 x2 wpa_supplicant[960]: wlxf4ec3889fa02: CTRL-EVENT-DISCONNECTED bssid=c8:5a:9f:e6:20:0f reason=3 locally_generated=1
Aug 30 23:38:13 x2 wpa_supplicant[960]: nl80211: deinit ifname=p2p-dev-wlp59s0 disabled_11b_rates=0
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.7965] Executing: /sbin/iptables --table filter --delete FORWARD --source 10.42.0.0/255.255.255.0 --in-interface wlp59s0 --jump ACCEPT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8174] Executing: /sbin/iptables --table filter --delete FORWARD --in-interface wlp59s0 --out-interface wlp59s0 --jump ACCEPT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8237] Executing: /sbin/iptables --table filter --delete FORWARD --out-interface wlp59s0 --jump REJECT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8297] Executing: /sbin/iptables --table filter --delete FORWARD --in-interface wlp59s0 --jump REJECT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8357] Executing: /sbin/iptables --table filter --delete INPUT --in-interface wlp59s0 --protocol udp --destination-port 67 --jump ACCEPT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8427] Executing: /sbin/iptables --table filter --delete INPUT --in-interface wlp59s0 --protocol tcp --destination-port 67 --jump ACCEPT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8494] Executing: /sbin/iptables --table filter --delete INPUT --in-interface wlp59s0 --protocol udp --destination-port 53 --jump ACCEPT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8565] Executing: /sbin/iptables --table filter --delete INPUT --in-interface wlp59s0 --protocol tcp --destination-port 53 --jump ACCEPT
Aug 30 23:38:13 x2 NetworkManager[959]: <info>  [1630359493.8651] device (wlxf4ec3889fa02): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Aug 30 23:38:13 x2 wpa_supplicant[960]: nl80211: deinit ifname=wlp59s0 disabled_11b_rates=0
Aug 30 23:38:13 x2 wpa_supplicant[960]: wlxf4ec3889fa02: CTRL-EVENT-SCAN-FAILED ret=-100
Aug 30 23:38:13 x2 wpa_supplicant[960]: wlxf4ec3889fa02: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 30 23:38:13 x2 systemd[1]: Reached target Sleep.
Aug 30 23:38:13 x2 systemd[1]: Starting Suspend...
Aug 30 23:38:13 x2 systemd-sleep[16648]: Suspending system...
Aug 30 23:38:13 x2 kernel: [46923.300055] PM: suspend entry (deep)
less kern.log
…
Aug 30 23:25:01 x2 kernel: [46130.671226] audit: type=1400 audit(1630358701.336:99): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=3072 comm="cupsd" capability=12  capname="net_admin"
Aug 30 23:38:13 x2 kernel: [46922.967119] IPv6: ADDRCONF(NETDEV_UP): wlp59s0: link is not ready
Aug 30 23:38:13 x2 kernel: [46923.012211] wlxf4ec3889fa02: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Aug 30 23:38:13 x2 kernel: [46923.300055] PM: suspend entry (deep)

Síť

[editovat]

Různé indicie ukazovaly na nějaký problém se sítí. Přijímám WiFi přes externí TP-LINK WiFi/adaptér/USB/TL-WN7200ND. Občas se stává, že se zpomalí síť prakticky k nepoužití; síla signálu není příliš excelentní, ale rychlost downloadu dosahuje cca 30 Mbps, což je celkem slušné – WiFi router, vzdálený asi 40 metrů, přijímá LTE omezenou na 50 Mbps. Ale někdy ten přenos klesne na několik kbps a je to prakticky nepoužitelné. Před chvílí opět.

Tak tedy:

/var/log$ less syslog
…
Sep  1 09:16:44 x2 kernel: [  561.282163] IPv6: ADDRCONF(NETDEV_UP): wlp59s0: link is not ready
Sep  1 09:18:02 x2 kernel: [  639.147023] WARNING: CPU: 3 PID: 964 at /build/linux-HN7Wi9/linux-4.15.0/net/wireless/util.c:1005 cfg80211_calculate_bitrate+0x176/0x190 [cfg80211]
Sep  1 09:18:02 x2 kernel: [  639.147028] Modules linked in: rfcomm ccm pci_stub vboxpci(OE) vboxnetadp(OE) vboxnetflt(OE) vboxdrv(OE) ipt_MASQUERADE nf_nat_masquerade_ipv4 xt_conntrack ipt_REJECT nf_reject_ipv4 xt_tcpudp iptable_filter nf_nat_h323 nf_conntrack_h323 nf_nat_pptp nf_nat_proto_gre nf_conntrack_pptp nf_conntrack_proto_gre nf_nat_tftp nf_conntrack_tftp nf_nat_sip nf_conntrack_sip nf_nat_irc nf_conntrack_irc nf_nat_ftp nf_conntrack_ftp iptable_nat nf_conntrack_ipv4 nf_defrag_ipv4 nf_nat_ipv4 nf_nat nf_conntrack libcrc32c cmac snd_hda_codec_hdmi snd_hda_codec_realtek snd_hda_codec_generic bnep binfmt_misc nls_iso8859_1 snd_soc_skl wmi_bmof intel_rapl snd_soc_skl_ipc intel_wmi_thunderbolt snd_hda_ext_core x86_pkg_temp_thermal snd_soc_sst_dsp intel_powerclamp snd_soc_sst_ipc coretemp snd_soc_acpi kvm_intel arc4 snd_soc_core
Sep  1 09:18:02 x2 kernel: [  639.147076]  kvm irqbypass snd_compress ac97_bus crct10dif_pclmul crc32_pclmul snd_pcm_dmaengine ghash_clmulni_intel pcbc aesni_intel snd_hda_intel aes_x86_64 crypto_simd snd_hda_codec glue_helper cryptd uvcvideo intel_cstate intel_rapl_perf snd_hda_core rt2800usb videobuf2_vmalloc snd_hwdep snd_seq_midi videobuf2_memops rt2x00usb btusb videobuf2_v4l2 rt2800lib btrtl videobuf2_core serio_raw rt2x00lib iwlmvm thinkpad_acpi snd_seq_midi_event btbcm cdc_mbim snd_pcm cdc_wdm i915 nvram iwlwifi cdc_ncm videodev btintel mac80211 joydev snd_rawmidi usbnet input_leds bluetooth mii cdc_acm media snd_seq ecdh_generic drm_kms_helper cfg80211 snd_seq_device drm snd_timer ucsi_acpi i2c_algo_bit shpchp snd fb_sys_fops syscopyarea mei_me typec_ucsi processor_thermal_device sysfillrect int3403_thermal mei sysimgblt
Sep  1 09:18:02 x2 kernel: [  639.147134]  intel_pch_thermal intel_soc_dts_iosf typec wmi int340x_thermal_zone soundcore acpi_pad int3400_thermal video acpi_thermal_rel mac_hid sch_fq_codel cuse parport_pc ppdev lp parport ip_tables x_tables autofs4 hid_microsoft hid_generic usbhid hid e1000e ptp nvme uas psmouse pps_core nvme_core thunderbolt usb_storage
Sep  1 09:18:02 x2 kernel: [  639.147169] CPU: 3 PID: 964 Comm: NetworkManager Tainted: G           OE    4.15.0-151-generic #157-Ubuntu
Sep  1 09:18:02 x2 kernel: [  639.147172] Hardware name: LENOVO 20KF001QMC/20KF001QMC, BIOS N20ET57W (1.42 ) 04/16/2021
Sep  1 09:18:02 x2 kernel: [  639.147209] RIP: 0010:cfg80211_calculate_bitrate+0x176/0x190 [cfg80211]
Sep  1 09:18:02 x2 kernel: [  639.147212] RSP: 0018:ffffb94e81907628 EFLAGS: 00010212
Sep  1 09:18:02 x2 kernel: [  639.147216] RAX: 0000000000000020 RBX: ffff9bc0600dbd00 RCX: 0000000000000001
Sep  1 09:18:02 x2 kernel: [  639.147219] RDX: 0000000000000000 RSI: 0000000000000000 RDI: ffffb94e8190774e
Sep  1 09:18:02 x2 kernel: [  639.147221] RBP: ffffb94e81907630 R08: 0000000000000004 R09: ffff9bc04703f0a0
Sep  1 09:18:02 x2 kernel: [  639.147224] R10: 0000000000000e60 R11: ffffffffc04d6000 R12: ffffb94e8190774e
Sep  1 09:18:02 x2 kernel: [  639.147226] R13: ffff9bc04703f09c R14: ffff9bc04703f030 R15: ffff9bc06555c108
Sep  1 09:18:02 x2 kernel: [  639.147230] FS:  00007f26f4813fc0(0000) GS:ffff9bc0724c0000(0000) knlGS:0000000000000000
Sep  1 09:18:02 x2 kernel: [  639.147233] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Sep  1 09:18:02 x2 kernel: [  639.147235] CR2: 00007f0eb8808000 CR3: 000000024bcae005 CR4: 00000000003606e0
Sep  1 09:18:02 x2 kernel: [  639.147238] Call Trace:
Sep  1 09:18:02 x2 kernel: [  639.147252]  ? nla_put+0x36/0x40
Sep  1 09:18:02 x2 kernel: [  639.147296]  nl80211_put_sta_rate+0x5d/0x240 [cfg80211]
Sep  1 09:18:02 x2 kernel: [  639.147338]  nl80211_send_station.isra.66+0x34f/0xc50 [cfg80211]
Sep  1 09:18:02 x2 kernel: [  639.147375]  nl80211_get_station+0x1df/0x250 [cfg80211]
Sep  1 09:18:02 x2 kernel: [  639.147397]  genl_family_rcv_msg+0x1fe/0x3f0
Sep  1 09:18:02 x2 kernel: [  639.147404]  ? free_one_page+0x76/0x400
Sep  1 09:18:02 x2 kernel: [  639.147413]  genl_rcv_msg+0x4c/0x90
Sep  1 09:18:02 x2 kernel: [  639.147419]  ? genl_family_rcv_msg+0x3f0/0x3f0
Sep  1 09:18:02 x2 kernel: [  639.147426]  netlink_rcv_skb+0x54/0x130
Sep  1 09:18:02 x2 kernel: [  639.147433]  genl_rcv+0x28/0x40
Sep  1 09:18:02 x2 kernel: [  639.147439]  netlink_unicast+0x19e/0x240
Sep  1 09:18:02 x2 kernel: [  639.147445]  netlink_sendmsg+0x2d1/0x3d0
Sep  1 09:18:02 x2 kernel: [  639.147452]  sock_sendmsg+0x3e/0x50
Sep  1 09:18:02 x2 kernel: [  639.147456]  ___sys_sendmsg+0x2a0/0x2f0
Sep  1 09:18:02 x2 kernel: [  639.147467]  ? compat_poll_select_copy_remaining+0x130/0x130
Sep  1 09:18:02 x2 kernel: [  639.147474]  ? compat_poll_select_copy_remaining+0x130/0x130
Sep  1 09:18:02 x2 kernel: [  639.147480]  ? compat_poll_select_copy_remaining+0x130/0x130
Sep  1 09:18:02 x2 kernel: [  639.147486]  __sys_sendmsg+0x54/0x90
Sep  1 09:18:02 x2 kernel: [  639.147490]  ? __sys_sendmsg+0x54/0x90
Sep  1 09:18:02 x2 kernel: [  639.147496]  SyS_sendmsg+0x12/0x20
Sep  1 09:18:02 x2 kernel: [  639.147503]  do_syscall_64+0x73/0x130
Sep  1 09:18:02 x2 kernel: [  639.147510]  entry_SYSCALL_64_after_hwframe+0x41/0xa6
Sep  1 09:18:02 x2 kernel: [  639.147513] RIP: 0033:0x7f26f1ff96f7
Sep  1 09:18:02 x2 kernel: [  639.147516] RSP: 002b:00007ffdec56bb90 EFLAGS: 00000293 ORIG_RAX: 000000000000002e
Sep  1 09:18:02 x2 kernel: [  639.147520] RAX: ffffffffffffffda RBX: 0000000000000010 RCX: 00007f26f1ff96f7
Sep  1 09:18:02 x2 kernel: [  639.147523] RDX: 0000000000000000 RSI: 00007ffdec56bbf0 RDI: 0000000000000010
Sep  1 09:18:02 x2 kernel: [  639.147525] RBP: 00007ffdec56bbf0 R08: 0000000000000000 R09: 00007f26f1fe1c40
Sep  1 09:18:02 x2 kernel: [  639.147527] R10: 0000000000000007 R11: 0000000000000293 R12: 0000000000000000
Sep  1 09:18:02 x2 kernel: [  639.147530] R13: 00007ffdec56bbf0 R14: 00007ffdec56bd90 R15: 00000000fffffff4
Sep  1 09:18:02 x2 kernel: [  639.147533] Code: c7 60 ec 4c c0 89 45 fc c6 05 ae bd 06 00 01 e8 41 58 01 f2 0f 0b 8b 45 fc c9 c3 0f 0b 31 c0 c9 c3 8d 14 52 01 d2 e9 0f ff ff ff <0f> 0b 31 c0 c9 c3 b8 01 00 00 00 e9 63 ff ff ff b8 02 00 00 00 
Sep  1 09:18:02 x2 kernel: [  639.147583] ---[ end trace 8038bd0fab22bcce ]---
…

Tak tedy proces 964 nějak zlobí, vypadá to na nějaký problém s moduly jádra, procesor vypisuje registry.

ps -auxfw | less
…
root       963  0.0  0.1  45596  8264 ?        Ss   09:07   0:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant
root       964  0.1  0.2 483664 18464 ?        Ssl  09:07   0:01 /usr/sbin/NetworkManager --no-daemon

Odpojil jsem tedy TP-LINK WiFi/adaptér/USB/TL-WN7200ND, který mi visí na oknu, aby měl lepší signál, a přepnul jsem na interní WiFi adaptér v notebooku, a síť se opět rozjela.

2021-09-02

[editovat]

Cca 09:35

I když jsem neměl připojený TP-LINK WiFi/adaptér/USB/TL-WN7200ND, zase začla pomalu chcípat síť, až zatuhnul celý notebook. Ještě mě napadlo, zkusit při bootování nějaké starší jádro.

Zase opravuji z Xubuntu/Focal

sudo e2fsck /dev/nvme0n1p6

e2fsck 1.45.5 (07-Jan-2020) XENIAL_24G: obnovuje se žurnál Mažu osiřelý iuzel 1405 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 1403 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 1396 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 1392 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 1299 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 497 (uid=1000, gid=1000, práva=0100600, velikost=9387) Mažu osiřelý iuzel 173 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 381 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 183 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 360 (uid=1000, gid=1000, práva=0100600, velikost=1064058) Mažu osiřelý iuzel 305 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 535 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 363 (uid=1000, gid=1000, práva=0100600, velikost=792075) Mažu osiřelý iuzel 1134452 (uid=1000, gid=1000, práva=040700, velikost=4096) Mažu osiřelý iuzel 1103582 (uid=1000, gid=1000, práva=0100600, velikost=4194304) Mažu osiřelý iuzel 212 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 1120514 (uid=1000, gid=1000, práva=0100600, velikost=262144) Mažu osiřelý iuzel 914 (uid=1000, gid=1000, práva=0100600, velikost=20458) Mažu osiřelý iuzel 210 (uid=1000, gid=1000, práva=0100600, velikost=0) Mažu osiřelý iuzel 1069907 (uid=1000, gid=1000, práva=0100664, velikost=19755) Mažu osiřelý iuzel 14 (uid=0, gid=0, práva=0100666, velikost=0) Mažu osiřelý iuzel 1072391 (uid=1000, gid=1000, práva=0100664, velikost=19755) Mažu osiřelý iuzel 1069302 (uid=1000, gid=1000, práva=0100664, velikost=32768) Mažu osiřelý iuzel 1067441 (uid=1000, gid=1000, práva=0100600, velikost=329016) Mažu osiřelý iuzel 1047741 (uid=1000, gid=1000, práva=0100664, velikost=19631) Mažu osiřelý iuzel 1066458 (uid=1000, gid=1000, práva=0100664, velikost=19631) Mažu osiřelý iuzel 62 (uid=121, gid=127, práva=0100600, velikost=0) Mažu osiřelý iuzel 61 (uid=121, gid=127, práva=0100600, velikost=0) Mažu osiřelý iuzel 59 (uid=121, gid=127, práva=0100600, velikost=0) Mažu osiřelý iuzel 58 (uid=121, gid=127, práva=0100600, velikost=0) Mažu osiřelý iuzel 55 (uid=121, gid=127, práva=0100600, velikost=0) Nastavuje se počet volných iuzlů na 995045 (byl 996786) Nastavuje se počet volných bloků na 445698 (byl 449554) XENIAL_24G: čistý, 457435/1452480 souborů, 5355262/5800960 bloků

less /nvme0n1p6/var/log/syslog

… Sep 2 09:26:06 x2 hp[7911]: message repeated 7 times: [ prnt/backend/hp.c 616: ERROR: 41900 media-empty-error; will retry in 30 seconds...] Sep 2 09:26:14 x2 rtkit-daemon[2179]: Supervising 1 threads of 1 processes of 1 users. Sep 2 09:26:14 x2 rtkit-daemon[2179]: Supervising 1 threads of 1 processes of 1 users. Sep 2 09:26:36 x2 hp[7911]: prnt/backend/hp.c 616: ERROR: 41900 media-empty-error; will retry in 30 seconds... Sep 2 09:27:36 x2 hp[7911]: message repeated 2 times: [ prnt/backend/hp.c 616: ERROR: 41900 media-empty-error; will retry in 30 seconds...] Sep 2 09:27:57 x2 rtkit-daemon[2179]: Supervising 1 threads of 1 processes of 1 users. Sep 2 09:27:57 x2 rtkit-daemon[2179]: Supervising 1 threads of 1 processes of 1 users. Sep 2 09:28:06 x2 hp[7911]: prnt/backend/hp.c 616: ERROR: 41900 media-empty-error; will retry in 30 seconds... Sep 2 09:31:17 x2 kernel: [ 9952.740903] perf: interrupt took too long (2502 > 2500), lowering kernel.perf_event_max_sample_rate to 79750 Sep 2 09:35:06 x2 hp[7911]: message repeated 14 times: [ prnt/backend/hp.c 616: ERROR: 41900 media-empty-error; will retry in 30 seconds...] Sep 2 09:35:16 x2 rtkit-daemon[2179]: Supervising 1 threads of 1 processes of 1 users. Sep 2 09:35:16 x2 rtkit-daemon[2179]: Supervising 1 threads of 1 processes of 1 users. Sep 2 09:35:36 x2 hp[7911]: prnt/backend/hp.c 616: ERROR: 41900 media-empty-error; will retry in 30 seconds...

Souviselo s tím, že jsem chtěl tisknout a neměl tiskárnu v USB. Jinak nic.

Tak nevím :-(

2021-09-11

[editovat]

K zatuhnutí zatím nedošlo, ale:

  • Včera 2021-09-10 jsem normálně pracoval, skončil jsem uspáním do paměti
  • Dnes 07:34:52 - chci ho probudit tím, že stisknu ESC, a nic, ani pohyb myší ho nevzbudí
  • LED na talčítku on/off normálně bliká, jako že spí
  • USB klávesnice ani myš nesvítí, po vysunutí a zasunutí jejich USB krátce bliknou a zase zhasnou
  • probudit se dá krátkým stisknunítm On/Off
  • na začátku vše jakoby OK, jenže nejde spustit Thunderbird
  • jenže nejde spustit ani terminál, s hláškou:
Nepodařilo se nastavit výchozí emulátor terminálu
  • nejde spustit nic
  • kliknu na "Vypnout", 10 minut to pak ještě čeká:
A stop job is running for MySQL Community server
  • nakonec se vypne
  • nobootuji Focal z /dev/nvme0n1p10
sudo e2fsck /dev/nvme0n1p6
e2fsck 1.45.5 (07-Jan-2020)
XENIAL_24G: obnovuje se žurnál
XENIAL_24G obsahuje systém souborů s chybami, kontrola vynucena.
Průchod 1: Kontrolují se iuzly, bloky a velikosti
Odstraněný iuzel 14 má nulový dtime.  Opravit<a>? ano
Nalezeny iuzly, které byly součástí poškozeného spojového seznamu osiřelých.  Opravit<a>? ano
Iuzel 55 byl součástí seznamu osiřelých iuzlů.  OPRAVENO.
Iuzel 58 byl součástí seznamu osiřelých iuzlů.  OPRAVENO.
Iuzel 59 byl součástí seznamu osiřelých iuzlů.  OPRAVENO.
Iuzel 61 byl součástí seznamu osiřelých iuzlů.  OPRAVENO.
Iuzel 62 byl součástí seznamu osiřelých iuzlů.  OPRAVENO.
Iuzel 266278 projde kontrolami, ale kontrolní součet iuzlu neodpovídá.  Opravit<a>? ano
Iuzel 266279 projde kontrolami, ale kontrolní součet iuzlu neodpovídá.  Opravit<a>? ano
Iuzel 266284 projde kontrolami, ale kontrolní součet iuzlu neodpovídá.  Opravit<a>? ano
V iuzlu 266285 je i_blocks 32, mělo by být 0.  Opravit<a>? ano
Iuzel 1101044 byl součástí seznamu osiřelých iuzlů.  OPRAVENO.
Iuzel 1106960 projde kontrolami, ale kontrolní součet iuzlu neodpovídá.  Opravit<a>? ano
Průchod 2: Kontroluje se struktura adresářů
Iuzel 266285 (/usr/lib/x86_64-linux-gnu/libxcb-render-util.so.0.0.0) má špatný mód (030635).
Vymazat<a>? ano
Iuzel 266279 (/usr/lib/x86_64-linux-gnu/libxcb-image.so.0) má špatný mód (030635).
Vymazat<a>? ano
Průchod 3: Kontroluje se dosažitelnost adresářů
Průchod 4: Kontrolují se počty odkazů
Průchod 5: Kontrolují se souhrnné informace skupin
Rozdíly v bitmapě bloků:  -(656564--656567) -(1783414--1783418)
Opravit („v“ zapne „ano“ pro vše) <a>? ano
Počet volných bloků ve skupině č. 20 špatně (1354, spočteno=1358).
Opravit („v“ zapne „ano“ pro vše) <a>? ano
Počet volných bloků ve skupině č. 54 špatně (5089, spočteno=5094).
Opravit („v“ zapne „ano“ pro vše) <a>? ano
Počet volných bloků špatně (503912, spočteno=501228).
Opravit („v“ zapne „ano“ pro vše) <a>? ano
Rozdíly v bitmapě iuzlů:  -14 -55 -(58--59) -(61--62) -1101044
Opravit<a>? ano
Počet volných iuzlů ve skupině č. 0 špatně (4471, spočteno=4477).
Opravit<a>? ano
Počet volných iuzlů ve skupině č. 134 špatně (0, spočteno=1).
Opravit<a>? ano
Počet volných iuzlů špatně (997908, spočteno=997884).
Opravit<a>? ano

XENIAL_24G: ***** SYSTÉM SOUBORŮ BYL ZMĚNĚN *****
XENIAL_24G: 454596/1452480 souborů (1,1 % nesouvislých), 5299732/5800960 bloků

Takže zase nakopnutý BIONIC. Jak zkontolovat integritu toho SSD disku?