ubuntuusers.de

Seit heute morgen startet mein Lenovo-Desktop X220 nur noch unvollständig:

Nach dem Einschalten gelange ich ins Grub-Auswahlmenü und kann dort auch verschiedene Kernel/Betriebssysteme auswählen und starten. Ubuntu (bzw. systemd) zeigt mir die üblichen Startmeldungen, bleibt aber dann irgendwann hängen und wechselt nicht in die Anmeldemaske von GDM3. Ich kann dann mit Strg+Alt+F2 auf ein anderes Terminal wechseln, mich dort im Textmodus anmelden und fast normal arbeiten. „Fast“ bedeutet, dass nach einigen Sekunden der Prompt aufhört zu blinken und keine Eingabe mehr angenommen wird. Wenn ich dann von TTY2 erneut auf TTY2 „wechsle“ (!), kann ich wieder einige Sekunden normal arbeiten. Dieses Spiel kann ich statt mit TTY2 auch mit TTY3 - TTY6 spielen.

Dieses Verhalten ist reproduzierbar und unabhängig von der Kernelversion. Der Rechner scheint bis auf GDM3/GUI normal zu arbeiten. Ich kann auch seinen SSH-Server problemlos erreichen, mich einloggen und dann normal arbeiten ohne dass mir alle paar Sekunden der Prompt gestohlen wird.

GDM läuft:

$ pgrep -lf gdm
993 gdm3
2107 gdm-session-wor
2126 gdm-wayland-ses
2130 gnome-session-b

Es besteht auch kein Speichermangel:

$ df -h
Dateisystem    Größe Benutzt Verf. Verw% Eingehängt auf
udev            3,9G       0  3,9G    0% /dev
tmpfs           786M    2,8M  784M    1% /run
/dev/sda4        24G     18G  4,8G   79% /
tmpfs           3,9G       0  3,9G    0% /dev/shm
tmpfs           5,0M    4,0K  5,0M    1% /run/lock
tmpfs           3,9G       0  3,9G    0% /sys/fs/cgroup
/dev/sda7        63G     45G   15G   75% /media/Datengrab
/dev/sda5        94G     37G   53G   42% /home/klaus
/dev/loop0      133M    133M     0  100% /snap/skype/41
tmpfs           786M       0  786M    0% /run/user/1000
tmpfs           786M    4,0K  786M    1% /run/user/120

$ LANG= free
              total        used        free      shared  buff/cache   available
Mem:        8047356      163788     7587592        2860      295976     7646748
Swap:       8257532           0     8257532

Systemd hat kein Problem:

$ systemctl --failed
0 loaded units listed. 

Hat jemand eine Idee, was dieses bizarre Verhalten verursacht bzw. wie man es heilen kann?

Schalte doch mal testweise GDM beim Start aus, melde dich gleich per TTY an und starte GDM per Hand. Gibt es da keine Meldungen statt GDM per Hand starten die Oberfläche ohne Displaymanager mittels

startx

starten.

tomtomtom schrieb:

Schalte doch mal testweise GDM beim Start aus […]

Danke für den Tipp. gdm ist definitiv Teil des Problems, wobei noch unklar ist, ob er selbst der böse Bube oder ob er Opfer eines noch böseren Buben ist. Hier ein Ausschnitt aus dem Systemlog, welcher sich ständig wiederholt. Dabei wird nur die Bezeichnung der Session hochgezählt:

Jun 21 06:43:41 lieselotte systemd[1]: Created slice User Slice of gdm.
Jun 21 06:43:41 lieselotte systemd[1]: Started Session c13 of user gdm.
Jun 21 06:44:01 lieselotte gdm3: Child process -1603 was already dead.
Jun 21 06:44:01 lieselotte gdm3: Child process 1586 was already dead.
Jun 21 06:44:01 lieselotte gdm3: Unable to kill session worker process
Jun 21 06:44:01 lieselotte systemd[1]: Removed slice User Slice of gdm.

Das war der Stand heute am frühen Morgen, als ich die weitere Untersuchung des Problems unterbrechen musste und bevor ich den Laptop ausgeschaltet und von Stromnetz getrennt habe.

Jetzt, am Mittag, bootet er ganz normal …

tomtomtom schrieb:

Schalte doch mal testweise GDM beim Start aus

Dumme Frage: Wie macht man das denn? Ich habe es versucht, indem ich dem Kernel die Parameter systemd.mask=gdm.service systemd.mask=gdm3.service mitgegeben habe. Das hat den Start des GDM aber nicht verhindert.

Soweit ich weiß wird die Unit für den Display-Manager generell über den Alias display-manager.service verwaltet. Ansonsten könnte man auch mit systemd.unit=multi-user.target angeben, dass man nur bis zum multi-user.target statt bis zum graphical.target booten will.

kB schrieb:

Dumme Frage: Wie macht man das denn? Ich habe es versucht, indem ich dem Kernel die Parameter systemd.mask=gdm.service systemd.mask=gdm3.service mitgegeben habe. Das hat den Start des GDM aber nicht verhindert.

Also normalerweise™ macht man das mit

sudo systemctl disable gdm

bzw.

sudo systemctl disable gdm3

Hab aber gerade kein Ubuntu verfügbar, um das auszuprobieren (ich sag lieber nicht, wo ich gerade bin). 😛

seahawk1986 schrieb:

Soweit ich weiß wird die Unit für den Display-Manager generell über den Alias display-manager.service verwaltet.

In der Tat ist das ein weiteres Synonym für GDM! Siehe:

$ ls -l /{lib,etc}/systemd/* | grep gdm
lrwxrwxrwx 1 root root   32 Mär  4 13:18 display-manager.service -> /lib/systemd/system/gdm3.service
lrwxrwxrwx 1 root root   11 Feb 19 09:33 gdm3.service -> gdm.service
-rw-r--r-- 1 root root 1007 Feb 19 09:33 gdm.service

Ich hätte jedoch erwartet, dass die Maskierung von gdm.service so etwas still legt.

Ansonsten könnte man auch mit systemd.unit=multi-user.target angeben, dass man nur bis zum multi-user.target statt bis zum graphical.target booten will.

Das funktioniert und ich kann dann gemäß dem Vorschlag von tomtomtom mit

startx

den Desktop ohne GDM starten. Der manuelle Start von GDM führt dagegen nicht weiter – Rechner hängt.

Nach einer Nachtschicht für memtest weiß ich, das es wohl nicht der Arbeitsspeicher ist.

Es passiert folgendes, aber nicht immer (!):

Am Ende des Bootvorgangs/Systemstarts wird GDM3 von systemd gestartet. GDM3 startet den gdm-session-worker und weitere Tasks. Das misslingt manchmal. GDM3 probiert es dann immer wieder, indem er neue Sessions eröffnet. Bei meiner ursprünglichen Meldung wiederholte sich das endlos, in diesem Fall hat er bereits bei der ersten Wiederholung Erfolg:

$ pgrep -af gdm
966 /usr/sbin/gdm3
1278 gdm-session-worker [pam/gdm-launch-environment]
1284 /usr/lib/gdm3/gdm-wayland-session gnome-session --autostart /usr/share/gdm/greeter/autostart
1287 /usr/lib/gnome-session/gnome-session-binary --autostart /usr/share/gdm/greeter/autostart
1467 gdm-session-worker [pam/gdm-password]
1477 /usr/lib/gdm3/gdm-x-session --run-script gnome-session
1479 /usr/lib/xorg/Xorg vt3 -displayfd 3 -auth /run/user/1000/gdm/Xauthority -background none -noreset -keeptty -verbose 3

$ journalctl -b | grep -i gdm | grep -v gdm-x-session
Jun 22 07:36:02 lieselotte gdm-launch-environment][974]: pam_unix(gdm-launch-environment:session): session opened for user gdm by (uid=0)
Jun 22 07:36:02 lieselotte systemd[1]: Created slice User Slice of gdm.
Jun 22 07:36:02 lieselotte systemd-logind[761]: New session c1 of user gdm.
Jun 22 07:36:02 lieselotte systemd[1]: Started Session c1 of user gdm.
Jun 22 07:36:02 lieselotte systemd[1010]: pam_unix(systemd-user:session): session opened for user gdm by (uid=0)
Jun 22 07:38:33 lieselotte gdm-launch-environment][974]: pam_unix(gdm-launch-environment:session): session closed for user gdm
Jun 22 07:38:33 lieselotte gdm3[966]: Child process 974 was already dead.
Jun 22 07:38:33 lieselotte gdm3[966]: Unable to kill session worker process
Jun 22 07:38:33 lieselotte gdm-launch-environment][1278]: pam_unix(gdm-launch-environment:session): session opened for user gdm by (uid=0)
Jun 22 07:38:33 lieselotte systemd-logind[761]: New session c2 of user gdm.
Jun 22 07:38:33 lieselotte systemd[1]: Started Session c2 of user gdm.
Jun 22 07:39:03 lieselotte gdm-password][1467]: pam_unix(gdm-password:session): session opened for user klaus by (uid=0)

Außerdem bekomme ich für diesen Start auch Fehler von Xorg, die mit GDM3 gar nichts zu tun haben können:

$ journalctl -b | grep -i '(EE)'
Jun 22 07:38:33 lieselotte org.gnome.Shell.desktop[1242]: (EE)
Jun 22 07:38:33 lieselotte org.gnome.Shell.desktop[1242]: (EE) Error sending request: Broken pipe
Jun 22 07:38:33 lieselotte org.gnome.Shell.desktop[1242]: (EE)
Jun 22 07:39:03 lieselotte /usr/lib/gdm3/gdm-x-session[1477]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Jun 22 07:39:03 lieselotte /usr/lib/gdm3/gdm-x-session[1477]: (EE) open /dev/fb0: Permission denied

Diese Effekte führen zu zwei ärgerlichen Kunstpausen:

1. Nach dem Start von GDM3 dauert es eine Weile, bis seine Anmeldemaske erscheint. Dieser Effekt war, wenn ich mich recht erinnere, immer da. gestern war diese Kunstpause nur zum ersten Mal unendlich lang.

2. Nach der Anmeldung über GDM3 dauert es manchmal unnatürlich lange, bis der Desktop des Benutzers erscheint. Das dokumentiert sich über die Xorg-Fehler. Offenbar sind auch hier manchmal mehrere Versuche erforderlich, bis das grafische System fehlerfrei startet. Auch dies hatte ich schon früher beobachtet.

Jedenfalls vermute ich nun, dass es Probleme mit der Grafik geben muss, die aber nicht immer auftreten. Ich benutze:

$ sudo lshw -C display
[sudo] Passwort für klaus: 
  *-display                 
       Beschreibung: VGA compatible controller
       Produkt: 2nd Generation Core Processor Family Integrated Graphics Controller
       Hersteller: Intel Corporation
       Physische ID: 2
       Bus-Informationen: pci@0000:00:02.0
       Version: 09
       Breite: 64 bits
       Takt: 33MHz
       Fähigkeiten: msi pm vga_controller bus_master cap_list rom
       Konfiguration: driver=i915 latency=0
       Ressourcen: irq:30 memory:f0000000-f03fffff memory:e0000000-efffffff ioport:5000(Größe=64) memory:c0000-dffff

Zur Grafikeinheit finde ich aber im Systemlog nichts Aufregendes:

$ journalctl -b | grep -e i915 -e drm -e vblank
Jun 22 07:35:55 lieselotte kernel: [drm] Replacing VGA console driver
Jun 22 07:35:55 lieselotte kernel: [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
Jun 22 07:35:55 lieselotte kernel: [drm] Driver supports precise vblank timestamp query.
Jun 22 07:35:55 lieselotte kernel: i915 0000:00:02.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=io+mem:owns=io+mem
Jun 22 07:35:55 lieselotte kernel: [drm] Initialized i915 1.6.0 20180719 for 0000:00:02.0 on minor 0
Jun 22 07:35:55 lieselotte kernel: snd_hda_intel 0000:00:1b.0: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915])
Jun 22 07:35:55 lieselotte kernel: fbcon: inteldrmfb (fb0) is primary device
Jun 22 07:35:56 lieselotte kernel: i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
Jun 22 07:39:03 lieselotte /usr/lib/gdm3/gdm-x-session[1477]: (II) xfree86: Adding drm device (/dev/dri/card0)

Das liest sich fast so, als ob der X-Server mit Benutzerrechten gestartet wird und dabei etwas mit der Rechtevergabe für das Device nicht klappt.

• was sagt df -h? Nicht, dass die GDM-Session daran scheitert, dass Nutzer wie gdm aufgrund von knappem Speicherplatz nichts mehr schreiben dürfen (ext-Dateisysteme reservieren standardmäßig 5% für root)

• sieht man im ungefilterten Syslog eventuell noch mehr Informationen während der Start-Versuche von GDM?

seahawk1986 schrieb:

Das liest sich fast so, als ob der X-Server mit Benutzerrechten gestartet

Jedenfalls läuft er jetzt so:

$ pgrep -af X
1304 /usr/bin/Xwayland :1024 -rootless -terminate -accessx -core -listen 4 -listen 5 -displayfd 6
1479 /usr/lib/xorg/Xorg vt3 -displayfd 3 -auth /run/user/1000/gdm/Xauthority -background none -noreset -keeptty -verbose 3
$ ps u 1304 1479
USER       PID %CPU %MEM    VSZ   RSS TTY      STAT START   TIME COMMAND
gdm       1304  0.0  0.6 615344 50944 tty1     Sl+  07:38   0:00 /usr/bin/Xwayland :1024 -rootless -terminate -accessx -core -listen 4 -listen 5 -displayfd 6
klaus     1479  4.2  0.8 530440 69284 tty3     Sl+  07:39   4:52 /usr/lib/xorg/Xorg vt3 -displayfd 3 -auth /run/user/1000/gdm/Xauthority -background none -noreset -keeptty -verbose 3

wird und dabei etwas mit der Rechtevergabe für das Device nicht klappt.

Dazu müsste/sollte ich etwas im Systemlog finden. Ich suche noch.

• was sagt df -h? […]

Im wesentlichen das aus meinem Eröffnungspost. Allerdings habe ich jetzt unter / 79% statt 74% frei, weil ich einige alte Kernel entfernt habe.

• sieht man im ungefilterten Syslog eventuell noch mehr Informationen während der Start-Versuche von GDM?

Ich arbeite daran.

seahawk1986 schrieb:

[…]

• sieht man im ungefilterten Syslog eventuell noch mehr Informationen während der Start-Versuche von GDM?

Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: '''kerneloops.service: Found left-over process 942 (kerneloops) in control group while starting unit. Ignoring'''
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: '''This usually indicates unclean termination of a previous run, or service implementation deficiencies.'''
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started /etc/rc.local Compatibility.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Starting GNOME Display Manager...
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Starting Hold until boot process finishes up...
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Tool to automatically collect and submit kernel crash signatures.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Hold until boot process finishes up.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Starting Set console scheme...
Sat 2019-06-22 07:36:02 CEST lieselotte whoopsie[938]: [07:36:02] Using lock path: /var/lock/whoopsie/lock
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Set console scheme.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Created slice system-getty.slice.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started GNOME Display Manager.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Samba NMB Daemon.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Starting Samba SMB Daemon...
Sat 2019-06-22 07:36:02 CEST lieselotte nmbd[937]: [2019/06/22 07:36:02.697558,  0] ../lib/util/become_daemon.c:124(daemon_ready)
Sat 2019-06-22 07:36:02 CEST lieselotte nmbd[937]:   STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Sat 2019-06-22 07:36:02 CEST lieselotte whoopsie[938]: [07:36:02] offline
Sat 2019-06-22 07:36:02 CEST lieselotte systemd-resolved[587]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying trans
Sat 2019-06-22 07:36:02 CEST lieselotte gdm-launch-environment][974]: pam_unix(gdm-launch-environment:session): session opened for user gdm by (uid=0)
Sat 2019-06-22 07:36:02 CEST lieselotte smbd[981]: [2019/06/22 07:36:02.757424,  0] ../lib/util/become_daemon.c:124(daemon_ready)
Sat 2019-06-22 07:36:02 CEST lieselotte smbd[981]:   STATUS=daemon 'smbd' finished starting up and ready to serve connections
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Samba SMB Daemon.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Reached target Multi-User System.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Reached target Graphical Interface.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Stop ureadahead data collection 45s after completed startup.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Starting Update UTMP about System Runlevel Changes...
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Created slice User Slice of gdm.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd-logind[761]: New session c1 of user gdm.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Update UTMP about System Runlevel Changes.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started Session c1 of user gdm.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Reloading OpenBSD Secure Shell server.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Starting User Manager for UID 120...
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: pam_unix(systemd-user:session): session opened for user gdm by (uid=0)
Sat 2019-06-22 07:36:02 CEST lieselotte sshd[890]: Received SIGHUP; restarting.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Reloaded OpenBSD Secure Shell server.
Sat 2019-06-22 07:36:02 CEST lieselotte sshd[890]: Server listening on :: port 22.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd-resolved[587]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying trans
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Started Pending report trigger for Ubuntu Report.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on GnuPG cryptographic agent and passphrase cache.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Starting D-Bus User Message Bus Socket.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Reached target Timers.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on Sound System.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on GnuPG network certificate management daemon.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Reached target Paths.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Listening on D-Bus User Message Bus Socket.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Reached target Sockets.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Reached target Basic System.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Reached target Default.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Startup finished in 40ms.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Started User Manager for UID 120.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Startup finished in 1.883s (kernel) + 8.330s (userspace) = 10.213s.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1010]: Started D-Bus User Message Bus.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Reloading OpenBSD Secure Shell server.
Sat 2019-06-22 07:36:02 CEST lieselotte sshd[890]: Received SIGHUP; restarting.
Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: Reloaded OpenBSD Secure Shell server.
Sat 2019-06-22 07:36:02 CEST lieselotte sshd[890]: Server listening on :: port 22.
Sat 2019-06-22 07:36:02 CEST lieselotte NetworkManager[830]: <info>  [1561181762.9304] manager: NetworkManager state is now CONNECTED_GLOBAL
Sat 2019-06-22 07:36:02 CEST lieselotte nm-dispatcher[880]: req:3 'connectivity-change': new request (1 scripts)
Sat 2019-06-22 07:36:02 CEST lieselotte nm-dispatcher[880]: req:3 'connectivity-change': start running ordered scripts...
Sat 2019-06-22 07:36:02 CEST lieselotte whoopsie[938]: [07:36:02] The default IPv4 route is: /org/freedesktop/NetworkManager/ActiveConnection/1
Sat 2019-06-22 07:36:02 CEST lieselotte whoopsie[938]: [07:36:02] Not a paid data plan: /org/freedesktop/NetworkManager/ActiveConnection/1
Sat 2019-06-22 07:36:02 CEST lieselotte whoopsie[938]: [07:36:02] Found usable connection: /org/freedesktop/NetworkManager/ActiveConnection/1
Sat 2019-06-22 07:36:04 CEST lieselotte whoopsie[938]: [07:36:04] online
Sat 2019-06-22 07:36:04 CEST lieselotte avahi-daemon[790]: Leaving mDNS multicast group on interface cable.IPv6 with address fe80::f9c:86d5:47c3:9c8e.
Sat 2019-06-22 07:36:04 CEST lieselotte avahi-daemon[790]: Joining mDNS multicast group on interface cable.IPv6 with address 2003:ca:4730:8000:6d76:b3e:3a15:1e
Sat 2019-06-22 07:36:04 CEST lieselotte avahi-daemon[790]: Registering new address record for 2003:ca:4730:8000:6d76:b3e:3a15:1eeb on cable.*.
Sat 2019-06-22 07:36:04 CEST lieselotte avahi-daemon[790]: Withdrawing address record for fe80::f9c:86d5:47c3:9c8e on cable.
Sat 2019-06-22 07:36:04 CEST lieselotte avahi-daemon[790]: Registering new address record for fd00:acdc:babe:0:cc66:2607:befc:7273 on cable.*.
Sat 2019-06-22 07:36:04 CEST lieselotte NetworkManager[830]: <info>  [1561181764.7164] policy: set 'cable' (cable) as default for IPv6 routing and DNS
Sat 2019-06-22 07:36:26 CEST lieselotte systemd-timesyncd[588]: Synchronized to time server [2001:67c:1560:8003::c7]:123 (ntp.ubuntu.com).
Sat 2019-06-22 07:36:48 CEST lieselotte systemd[1]: Starting Stop ureadahead data collection...
Sat 2019-06-22 07:36:48 CEST lieselotte systemd[1]: Started Stop ureadahead data collection.
Sat 2019-06-22 07:36:51 CEST lieselotte systemd[1]: Started Getty on tty2.
Sat 2019-06-22 07:37:00 CEST lieselotte login[1082]: pam_unix(login:session): session opened for user klaus by LOGIN(uid=0)
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1]: Created slice User Slice of klaus.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1]: Starting User Manager for UID 1000...
Sat 2019-06-22 07:37:00 CEST lieselotte systemd-logind[761]: New session 2 of user klaus.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: pam_unix(systemd-user:session): session opened for user klaus by (uid=0)
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1]: Started Session 2 of user klaus.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Started Pending report trigger for Ubuntu Report.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Listening on GnuPG network certificate management daemon.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Starting D-Bus User Message Bus Socket.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Reached target Timers.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Reached target Paths.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Listening on GnuPG cryptographic agent and passphrase cache.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Listening on D-Bus User Message Bus Socket.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Reached target Sockets.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Reached target Basic System.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Reached target Default.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1213]: Startup finished in 41ms.
Sat 2019-06-22 07:37:00 CEST lieselotte systemd[1]: Started User Manager for UID 1000.
Sat 2019-06-22 07:37:03 CEST lieselotte kernel: random: crng init done
Sat 2019-06-22 07:37:03 CEST lieselotte kernel: random: 7 urandom warning(s) missed due to ratelimiting
Sat 2019-06-22 07:37:03 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requeste
Sat 2019-06-22 07:37:03 CEST lieselotte systemd[1]: Starting Daemon for power management...
Sat 2019-06-22 07:37:03 CEST lieselotte dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.UPower'
Sat 2019-06-22 07:37:03 CEST lieselotte systemd[1]: Started Daemon for power management.
Sat 2019-06-22 07:37:04 CEST lieselotte gnome-shell[1242]: '''Failed to apply DRM plane transform 0: Keine Berechtigung'''
Sat 2019-06-22 07:37:04 CEST lieselotte org.gnome.Shell.desktop[1242]: '''wayland-egl: could not open /dev/dri/card0 (Permission denied)'''
Sat 2019-06-22 07:38:33 CEST lieselotte gnome-session[1071]: gnome-session-binary[1071]: WARNING: Application 'org.gnome.Shell.desktop' failed to register befo
Sat 2019-06-22 07:38:33 CEST lieselotte gnome-session[1071]: gnome-session-binary[1071]: CRITICAL: We failed, but the fail whale is dead. Sorry....
Sat 2019-06-22 07:38:33 CEST lieselotte gnome-session-binary[1071]: Unrecoverable failure in required component org.gnome.Shell.desktop
Sat 2019-06-22 07:38:33 CEST lieselotte gnome-session-binary[1071]: '''WARNING: Application 'org.gnome.Shell.desktop' failed to register before timeout'''
Sat 2019-06-22 07:38:33 CEST lieselotte gnome-session-binary[1071]: CRITICAL: We failed, but the fail whale is dead. Sorry....
Sat 2019-06-22 07:38:33 CEST lieselotte gdm-launch-environment][974]: pam_unix(gdm-launch-environment:session): session closed for user gdm
Sat 2019-06-22 07:38:33 CEST lieselotte org.gnome.Shell.desktop[1242]: (EE)
Sat 2019-06-22 07:38:33 CEST lieselotte org.gnome.Shell.desktop[1242]: Fatal server error:
Sat 2019-06-22 07:38:33 CEST lieselotte org.gnome.Shell.desktop[1242]: (EE) Error sending request: Broken pipe
Sat 2019-06-22 07:38:33 CEST lieselotte org.gnome.Shell.desktop[1242]: (EE)
Sat 2019-06-22 07:38:33 CEST lieselotte gdm3[966]: Child process 974 was already dead.
Sat 2019-06-22 07:38:33 CEST lieselotte gdm3[966]: Unable to kill session worker process
Sat 2019-06-22 07:38:33 CEST lieselotte gdm-launch-environment][1278]: pam_unix(gdm-launch-environment:session): session opened for user gdm by (uid=0)
Sat 2019-06-22 07:38:33 CEST lieselotte systemd-logind[761]: New session c2 of user gdm.
Sat 2019-06-22 07:38:33 CEST lieselotte systemd[1]: Started Session c2 of user gdm.
Sat 2019-06-22 07:38:33 CEST lieselotte systemd-logind[761]: Removed session c1.
Sat 2019-06-22 07:38:34 CEST lieselotte org.gnome.Shell.desktop[1293]: glamor: EGL version 1.4 (DRI2):
Sat 2019-06-22 07:38:34 CEST lieselotte dbus-daemon[1065]: [session uid=120 pid=1065] Activating via systemd: service name='org.a11y.Bus' unit='at-spi-dbus-bus
Sat 2019-06-22 07:38:34 CEST lieselotte systemd[1010]: Starting Accessibility services bus...
Sat 2019-06-22 07:38:34 CEST lieselotte dbus-daemon[1065]: [session uid=120 pid=1065] Successfully activated service 'org.a11y.Bus'
Sat 2019-06-22 07:38:34 CEST lieselotte systemd[1010]: Started Accessibility services bus.
Sat 2019-06-22 07:38:34 CEST lieselotte at-spi-bus-launcher[1312]: dbus-daemon[1317]: Activating service name='org.a11y.atspi.Registry' requested by ':1.0' (ui
Sat 2019-06-22 07:38:34 CEST lieselotte at-spi-bus-launcher[1312]: dbus-daemon[1317]: Successfully activated service 'org.a11y.atspi.Registry'
Sat 2019-06-22 07:38:34 CEST lieselotte at-spi-bus-launcher[1312]: SpiRegistry daemon is running with well-known name - org.a11y.atspi.Registry
Sat 2019-06-22 07:38:34 CEST lieselotte systemd[1010]: Starting Sound Service...
Sat 2019-06-22 07:38:34 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.servi
Sat 2019-06-22 07:38:34 CEST lieselotte systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Sat 2019-06-22 07:38:34 CEST lieselotte dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Sat 2019-06-22 07:38:34 CEST lieselotte systemd[1]: Started RealtimeKit Scheduling Policy Service.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Successfully called chroot.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Successfully dropped privileges.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Successfully limited resources.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Running.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Canary thread running.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Watchdog thread running.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte rtkit-daemon[1324]: Supervising 0 threads of 0 processes of 1 users.
Sat 2019-06-22 07:38:34 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by 
Sat 2019-06-22 07:38:34 CEST lieselotte systemd[1010]: Started Sound Service.
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.locale1' unit='dbus-org.freedesktop.lo
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Starting Locale Service...
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[1065]: [session uid=120 pid=1065] Activating service name='org.freedesktop.portal.IBus' requested by ':1.32
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[1065]: [session uid=120 pid=1065] Successfully activated service 'org.freedesktop.portal.IBus'
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.locale1'
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Started Locale Service.
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.GeoClue2' unit='geoclue.service' reque
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Starting Location Lookup Service...
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.bolt' unit='bolt.service' requested by
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Starting Thunderbolt system service...
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.GeoClue2'
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Started Location Lookup Service.
Sat 2019-06-22 07:38:35 CEST lieselotte polkitd(authority=local)[851]: Registered Authentication Agent for unix-session:c2 (system bus name :1.43 [/usr/bin/gno
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.PackageKit' unit='packagekit.service' 
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Starting PackageKit Daemon...
Sat 2019-06-22 07:38:35 CEST lieselotte PackageKit[1359]: daemon start
Sat 2019-06-22 07:38:35 CEST lieselotte spice-vdagent[1364]: Cannot access vdagent virtio channel /dev/virtio-ports/com.redhat.spice.0
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Started PackageKit Daemon.
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: bolt 0.5 starting up.
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: config: loading user config
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: bouncer: initializing polkit
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: udev: initializing udev
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: store: loading devices
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: power: force power support: no
Sat 2019-06-22 07:38:35 CEST lieselotte boltd[1358]: udev: enumerating devices
Sat 2019-06-22 07:38:35 CEST lieselotte dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.bolt'
Sat 2019-06-22 07:38:35 CEST lieselotte systemd[1]: Started Thunderbolt system service.
Sat 2019-06-22 07:38:35 CEST lieselotte gnome-session[1287]: gnome-session-binary[1287]: WARNING: App 'spice-vdagent.desktop' exited with code 1
Sat 2019-06-22 07:38:35 CEST lieselotte gnome-session-binary[1287]: WARNING: App 'spice-vdagent.desktop' exited with code 1
Sat 2019-06-22 07:38:35 CEST lieselotte gnome-shell[1293]: Error looking up permission: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.fre
Sat 2019-06-22 07:38:36 CEST lieselotte gnome-shell[1293]: JS WARNING: [resource:///org/gnome/shell/ui/windowManager.js 1477]: reference to undefined property 
Sat 2019-06-22 07:38:36 CEST lieselotte gnome-session-binary[1287]: Entering running state

Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: '''kerneloops.service: Found left-over process 942 (kerneloops) in control group while starting unit. Ignoring'''

Sieht man da in der Ausgabe von dmesg eventuell noch mehr zu dem Kernel-Oops?

seahawk1986 schrieb:

> Sat 2019-06-22 07:36:02 CEST lieselotte systemd[1]: '''kerneloops.service: Found left-over process 942 (kerneloops) in control group while starting unit. Ignoring'''
> 

Sieht man da in der Ausgabe von dmesg eventuell noch mehr zu dem Kernel-Oops?

Es gibt keinen Kernel-Oops, nur das regulär (wohl bei jedem Linux-System) laufende Programm kerneloops:

$ systemctl --no-pager status kerneloops.service 
● kerneloops.service - Tool to automatically collect and submit kernel crash signatures
   Loaded: loaded (/lib/systemd/system/kerneloops.service; enabled; vendor preset: enabled)
   Active: active (running) since Sun 2019-06-23 08:46:57 CEST; 20min ago
  Process: 941 ExecStart=/usr/sbin/kerneloops $DAEMON_ARGS (code=exited, status=0/SUCCESS)
  Process: 936 ExecStartPre=/usr/sbin/kerneloops --test (code=exited, status=0/SUCCESS)
    Tasks: 2 (limit: 4915)
   CGroup: /system.slice/kerneloops.service
           ├─939 /usr/sbin/kerneloops --test
           └─943 /usr/sbin/kerneloops

Jun 23 08:46:57 lieselotte systemd[1]: Starting Tool to automatically collect and submit kernel crash signatures...
Jun 23 08:46:57 lieselotte systemd[1]: kerneloops.service: Found left-over process 939 (kerneloops) in control group while starting unit. Ignoring.
Jun 23 08:46:57 lieselotte systemd[1]: This usually indicates unclean termination of a previous run, or service implementation deficiencies.
Jun 23 08:46:57 lieselotte systemd[1]: Started Tool to automatically collect and submit kernel crash signatures.
$ 
$ dmesg | grep oops
$ journalctl -b | grep oops
Jun 23 08:46:49 lieselotte audit[651]: AVC apparmor="STATUS" operation="profile_load" profile="unconfined" name="libreoffice-oopslash" pid=651 comm="apparmor_parser"
Jun 23 08:46:57 lieselotte systemd[1]: kerneloops.service: Found left-over process 939 (kerneloops) in control group while starting unit. Ignoring.
Jun 23 08:46:57 lieselotte whoopsie[934]: [08:46:57] Using lock path: /var/lock/whoopsie/lock
Jun 23 08:46:57 lieselotte whoopsie[934]: [08:46:57] offline
Jun 23 08:47:32 lieselotte whoopsie[934]: [08:47:32] Cannot reach: https://daisy.ubuntu.com
Jun 23 08:47:34 lieselotte whoopsie[934]: [08:47:34] Cannot reach: https://daisy.ubuntu.com
Jun 23 08:47:34 lieselotte whoopsie[934]: [08:47:34] Cannot reach: https://daisy.ubuntu.com
Jun 23 08:47:35 lieselotte whoopsie[934]: [08:47:35] Cannot reach: https://daisy.ubuntu.com
Jun 23 08:47:42 lieselotte whoopsie[934]: [08:47:42] Cannot reach: https://daisy.ubuntu.com
Jun 23 08:51:51 lieselotte whoopsie[934]: [08:51:51] The default IPv4 route is: /org/freedesktop/NetworkManager/ActiveConnection/1
Jun 23 08:51:51 lieselotte whoopsie[934]: [08:51:51] Not a paid data plan: /org/freedesktop/NetworkManager/ActiveConnection/1
Jun 23 08:51:51 lieselotte whoopsie[934]: [08:51:51] Found usable connection: /org/freedesktop/NetworkManager/ActiveConnection/1
Jun 23 08:51:51 lieselotte whoopsie[934]: [08:51:51] online

Der einzige oops ist whoopsie, wenn man den als solchen zählen möchte.

Bei den letzten beiden Startvorgängen traten keine Probleme auf. Da ich nichts verändert habe, was nach meiner Einschätzung ursächlich für die eingangs beschriebenen gravierende Systemstörung gewesen sein könnte, ist das für mich unbefriedigend. Ich habe lediglich update/upgrade ausgeführt, einige alte Kernel gelöscht und memtest über Nacht laufen lassen.

Die letzte mir ein wenig verdächtig erscheinende Meldung im Systemlog ist diese:

$ journalctl -b | grep -i -e gdm -e vdagent -e 1102
Jun 24 07:07:19 lieselotte gdm-launch-environment][988]: pam_unix(gdm-launch-environment:session): session opened for user gdm by (uid=0)
Jun 24 07:07:19 lieselotte systemd[1]: Created slice User Slice of gdm.
Jun 24 07:07:19 lieselotte systemd[1077]: pam_unix(systemd-user:session): session opened for user gdm by (uid=0)
Jun 24 07:07:19 lieselotte systemd-logind[743]: New session c1 of user gdm.
Jun 24 07:07:19 lieselotte systemd[1]: Started Session c1 of user gdm.
Jun 24 07:07:54 lieselotte spice-vdagent[1275]: Cannot access vdagent virtio channel /dev/virtio-ports/com.redhat.spice.0
Jun 24 07:07:54 lieselotte gnome-session[1102]: gnome-session-binary[1102]: WARNING: App 'spice-vdagent.desktop' exited with code 1
Jun 24 07:07:54 lieselotte gnome-session-binary[1102]: WARNING: App 'spice-vdagent.desktop' exited with code 1
Jun 24 07:07:54 lieselotte gnome-session-binary[1102]: Entering running state

Der Rechner läuft aber jetzt trotz des Versagen von vdagent problemlos.

Was ist/macht dieser vdagent?