2021-01-28 firmware+suspend

Jak používat klasifikační nálepkuTato stránka je součástí projektu:
Příslušnost: všeobecná

Lenovo ThinkPad X280/Problems – různé problémy s ultrabookem

/2021-01-28_firmware+suspend – Upgrade firmware a ráno nejde probudit

Upgrade firmware

editovat

2021-01-27 středa

editovat
  • Nabootoval jsem Windows 10
  • Pustil jsem Lenovo Vantage
  • Nechal jsem dle návodu nainstalovat všechny aktualizace (předtím byly poslední z března 2020), včetně firmware BIOS
  • Vše proběhlo O.K.
  • Po posledním rebootu naběhly Win 10 O.K.
  • Rebootoval jsem do Ubuntu 18.03
  • Vše fungovalo O.K.
  • Po půlnoci jsem notebook uspal (suspend)

Nejde probudit

editovat

2021-01-28

editovat
  • Ráno jsem chtěl ntb probudit obvyklým způsobem (připojený externí monitor přes HDMI a klávesnice přes USB):
    • zapnout externí monitor
    • otevřít víko s displayem
    • stisknout [Esc]
  • nic se neděje, display stále tmavý
  • bílá LEDka na vypínači svítí – tj. teoreticky ntb běží, ale oba displaye tmavé
  • když vytáhnu a opět zapojím zdroj, tato LEDka zabliká (správně)
  • Zkouším Fn F7 (zapnutí displaye), Fn F6 (zvýšení jasu) – nic se neděje
  • Zkouším odpojit a připojit externí monitor přes HDMI – nic se neděje
  • externí myš i klávesnice přes USB má napájení – OK
  • klávesnice reaguje na NumLock – takže něco tam žije
  • ntb normálně připojení přes WiFi – od DHCP má přidělenu lokální IP 10.0.0.4
  • zkouším se na tuto adresu připojit přes ssh – neúspěšně
  • zkouším na tuto adresu pingnout – žádná odpověď
  • shodím systém pomocí SysRq: Alt-SysRq-REISUB – povede se

Analýza problému

editovat
  • normálně znovu nabootuji Ubuntu – vše O.K.
  • netuším, jestli problém s probuzením nějak souvisí se včerejším upgrade firmware

/var/log/syslog

editovat
  • Jan 28 00:43:11 – normálně jsem uspal
  • mezitím v syslogu nic (ani pokusy o probuzení, ani SysRq)
  • Jan 28 09:18:44 – až po úspěšném rebootu
Jan 28 00:17:01 x2 CRON[6123]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jan 28 00:39:01 x2 CRON[6400]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Jan 28 00:39:17 x2 systemd[1]: Starting Clean php session files...
Jan 28 00:39:17 x2 systemd[1]: Started Clean php session files.
Jan 28 00:39:51 x2 rtkit-daemon[2091]: Supervising 4 threads of 2 processes of 1 users.
Jan 28 00:41:04 x2 rtkit-daemon[2091]: message repeated 7 times: [ Supervising 4 threads of 2 processes of 1 users.]
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1199] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1200] device (A4:4B:D5:32:61:E1): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1203] device (38:78:62:54:7F:CA): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Jan 28 00:43:11 x2 whoopsie[1573]: [00:43:11] offline
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1204] device (cdc-wdm0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'external')
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1206] manager: NetworkManager state is now ASLEEP
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1215] device (wlp59s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Jan 28 00:43:11 x2 ModemManager[935]: [/dev/cdc-wdm0] channel destroyed
Jan 28 00:43:11 x2 dbus-daemon[898]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.12' (uid=0 pid=929 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1282] device (wlp59s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Jan 28 00:43:11 x2 avahi-daemon[897]: Withdrawing address record for fe80::ad68:9ef0:2ee8:f5ab on wlp59s0.
Jan 28 00:43:11 x2 avahi-daemon[897]: Leaving mDNS multicast group on interface wlp59s0.IPv6 with address fe80::ad68:9ef0:2ee8:f5ab.
Jan 28 00:43:11 x2 avahi-daemon[897]: Interface wlp59s0.IPv6 no longer relevant for mDNS.
Jan 28 00:43:11 x2 systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 28 00:43:11 x2 dbus-daemon[898]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 28 00:43:11 x2 systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 28 00:43:11 x2 nm-dispatcher: req:1 'connectivity-change': new request (1 scripts)
Jan 28 00:43:11 x2 nm-dispatcher: req:1 'connectivity-change': start running ordered scripts...
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1608] dhcp4 (wlp59s0): canceled DHCP transaction, DHCP client pid 2622
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1608] dhcp4 (wlp59s0): state changed bound -> done
Jan 28 00:43:11 x2 kernel: [ 6065.365993] wlp59s0: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Jan 28 00:43:11 x2 wpa_supplicant[962]: wlp59s0: CTRL-EVENT-DISCONNECTED bssid=c8:5a:9f:e6:20:0f reason=3 locally_generated=1
Jan 28 00:43:11 x2 avahi-daemon[897]: Withdrawing address record for 10.0.0.4 on wlp59s0.
Jan 28 00:43:11 x2 avahi-daemon[897]: Leaving mDNS multicast group on interface wlp59s0.IPv4 with address 10.0.0.4.
Jan 28 00:43:11 x2 avahi-daemon[897]: Interface wlp59s0.IPv4 no longer relevant for mDNS.
Jan 28 00:43:11 x2 whoopsie[1573]: [00:43:11] Cannot reach: https://daisy.ubuntu.com
Jan 28 00:43:11 x2 nm-dispatcher: req:2 'down' [wlp59s0]: new request (1 scripts)
Jan 28 00:43:11 x2 nm-dispatcher: req:2 'down' [wlp59s0]: start running ordered scripts...
Jan 28 00:43:11 x2 NetworkManager[929]: <warn>  [1611790991.1854] sup-iface[0x55ee6d91b240,wlp59s0]: connection disconnected (reason -3)
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1855] device (wlp59s0): supplicant interface state: completed -> disconnected
Jan 28 00:43:11 x2 NetworkManager[929]: <info>  [1611790991.1860] device (wlp59s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Jan 28 00:43:11 x2 wpa_supplicant[962]: nl80211: deinit ifname=p2p-dev-wlp59s0 disabled_11b_rates=0
Jan 28 00:43:11 x2 systemd[1]: Reached target Sleep.
Jan 28 00:43:11 x2 systemd[1]: Starting Suspend...
Jan 28 00:43:11 x2 kernel: [ 6065.537101] PM: suspend entry (deep)
Jan 28 00:43:11 x2 systemd-sleep[6687]: Suspending system...
Jan 28 09:18:44 x2 systemd-modules-load[300]: Inserted module 'lp'
Jan 28 09:18:44 x2 systemd-modules-load[300]: Inserted module 'ppdev'
Jan 28 09:18:44 x2 systemd-modules-load[300]: Inserted module 'parport_pc'
Jan 28 09:18:44 x2 systemd-modules-load[300]: Inserted module 'cuse'
Jan 28 09:18:44 x2 keyboard-setup.sh[304]: soubor/run/tmpkbd.IOcjXY nelze otevřít
Jan 28 09:18:44 x2 systemd[1]: Starting Flush Journal to Persistent Storage...
Jan 28 09:18:44 x2 systemd[1]: Started udev Coldplug all Devices.
Jan 28 09:18:44 x2 systemd[1]: Started Apply Kernel Variables.
Jan 28 09:18:44 x2 systemd[1]: Started udev Kernel Device Manager.
Jan 28 09:18:44 x2 mtp-probe: checking bus 1, device 3: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3"
Jan 28 09:18:44 x2 systemd-udevd[375]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jan 28 09:18:44 x2 mtp-probe: checking bus 1, device 6: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-8"
Jan 28 09:18:44 x2 systemd-udevd[379]: Error calling EVIOCSKEYCODE on device node '/dev/input/event5' (scan code 0xc022d, key code 103): Invalid argument
Jan 28 09:18:44 x2 mtp-probe: checking bus 1, device 4: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-6"
Jan 28 09:18:44 x2 systemd-udevd[379]: Error calling EVIOCSKEYCODE on device node '/dev/input/event5' (scan code 0xc022e, key code 108): Invalid argument
Jan 28 09:18:44 x2 mtp-probe: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-9"
Jan 28 09:18:44 x2 systemd[1]: Started Flush Journal to Persistent Storage.
Jan 28 09:18:44 x2 mtp-probe: bus: 1, device: 6 was not an MTP device
Jan 28 09:18:44 x2 mtp-probe: bus: 1, device: 4 was not an MTP device
Jan 28 09:18:44 x2 mtp-probe: bus: 1, device: 7 was not an MTP device
Jan 28 09:18:44 x2 mtp-probe: checking bus 1, device 2: "/sys/devices/pci0000:00/0000:00:14.0/usb1/1-2"
Jan 28 09:18:44 x2 mtp-probe: bus: 1, device: 2 was not an MTP device
Jan 28 09:18:44 x2 mtp-probe: checking bus 2, device 2: "/sys/devices/pci0000:00/0000:00:14.0/usb2/2-3"
Jan 28 09:18:44 x2 mtp-probe: bus: 2, device: 2 was not an MTP device
Jan 28 09:18:44 x2 mtp-probe: bus: 1, device: 3 was not an MTP device
Jan 28 09:18:44 x2 systemd[1]: Found device KXG5AZNV256G TOSHIBA Windows.
Jan 28 09:18:44 x2 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jan 28 09:18:44 x2 systemd[1]: Starting Load/Save RF Kill Switch Status...
Jan 28 09:18:44 x2 systemd-udevd[364]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jan 28 09:18:44 x2 systemd[1]: Found device KXG5AZNV256G TOSHIBA SYSTEM.
Jan 28 09:18:44 x2 systemd[1]: Found device KXG5AZNV256G TOSHIBA 5.
Jan 28 09:18:44 x2 systemd[1]: Found device KXG5AZNV256G TOSHIBA 7.
Jan 28 09:18:44 x2 systemd-udevd[374]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jan 28 09:18:44 x2 systemd[1]: Created slice system-systemd\x2dbacklight.slice.
Jan 28 09:18:44 x2 systemd[1]: Starting Load/Save Screen Backlight Brightness of leds:tpacpi::kbd_backlight...
Jan 28 09:18:44 x2 systemd[1]: Activating swap /dev/disk/by-uuid/a38e8d74-eeae-4744-a459-4cd6ebafd151...
Jan 28 09:18:44 x2 systemd-udevd[364]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jan 28 09:18:44 x2 systemd[1]: Started Load/Save Screen Backlight Brightness of leds:tpacpi::kbd_backlight.
Jan 28 09:18:44 x2 systemd[1]: Activated swap /dev/disk/by-uuid/a38e8d74-eeae-4744-a459-4cd6ebafd151.
Jan 28 09:18:44 x2 systemd[1]: Started Load/Save RF Kill Switch Status.
Jan 28 09:18:44 x2 systemd[1]: Reached target Swap.
Jan 28 09:18:44 x2 systemd[1]: Started Set the console keyboard layout.
Jan 28 09:18:44 x2 systemd[1]: Starting Show Plymouth Boot Screen...

/var/log/kern.log

editovat
Jan 27 23:55:14 x2 kernel: [ 3188.408148] audit: type=1400 audit(1611788114.185:114): apparmor="ALLOWED" operation="unlink" profile="libreoffice-soffice" name="/p7/DATAp7/Petr/lf2/2020-21/Zkousky/2021-01-26/.~lock.ZkBF_2021-01-26c+Akustika-Petr.xls#" pid=4141 comm="soffice.bin" requested_mask="d" denied_mask="d" fsuid=1000 ouid=1000
Jan 28 00:43:11 x2 kernel: [ 6065.365993] wlp59s0: deauthenticating from c8:5a:9f:e6:20:0f by local choice (Reason: 3=DEAUTH_LEAVING)
Jan 28 00:43:11 x2 kernel: [ 6065.537101] PM: suspend entry (deep)
Jan 28 09:18:44 x2 kernel: [    0.000000] Linux version 4.15.0-135-generic (buildd@lgw01-amd64-005) (gcc version 7.5.0 (Ubuntu 7.5.0-3ubuntu1~18.04)) #139-Ubuntu SMP Mon Jan 18 17:38:24 UTC 2021 (Ubuntu 4.15.0-135.139-generic 4.15.18)
Jan 28 09:18:44 x2 kernel: [    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.15.0-135-generic root=UUID=0745505d-5f8f-4189-ae47-0b9c99b30c62 ro quiet splash vt.handoff=1
Jan 28 09:18:44 x2 kernel: [    0.000000] KERNEL supported cpus:
Jan 28 09:18:44 x2 kernel: [    0.000000]   Intel GenuineIntel
Jan 28 09:18:44 x2 kernel: [    0.000000]   AMD AuthenticAMD
Jan 28 09:18:44 x2 kernel: [    0.000000]   Centaur CentaurHauls
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: xstate_offset[3]:  832, xstate_sizes[3]:   64
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: xstate_offset[4]:  896, xstate_sizes[4]:   64
Jan 28 09:18:44 x2 kernel: [    0.000000] x86/fpu: Enabled xstate features 0x1f, context size is 960 bytes, using 'compacted' format.
Jan 28 09:18:44 x2 kernel: [    0.000000] e820: BIOS-provided physical RAM map:

Nikde není vidět nic neobvyklého – až na to, že po půlnoci byl systém normálně uspán a ráno narmálně nabootoval. Ale proč nešel probudit ze spánku – to z toho nikde nevidím.

Nevidím nic lepšího, než provést druhý pokus s uspáním.

pgk install dropbear

or

pkg install openssh

Instaloval jsem dropbear

cd
mkdir .ssh
cd .ssh
emacs config
Host xx
   HostName 10.0.0.4
   User ....

Druhé uspání

editovat