Graphical login glitchy after large update

Iruy · 6 March 2021 18:52

This is probably my fault somewhat as I’ve been putting off updating for a couple of weeks, and as a result I had a lot of packages to update when I did. I updated with sudo pacman -Syyu, and also updated to Kernel 5.11.2-1. Because I updated a lot of stuff at once, some things stopped working, which is normal, if this happens I usually just reboot and all the new packages that start on boot kick in properly, then everything works just fine. But now when I try to login on tty1, the screen freezes. I can still move my cursor, but the clock will not change, so I know it’s frozen. I can still login on tty2 however, and upon realizing this I tried running sudo pacman-mirrors -f && sudo pacman -Syyu, assuming something had broken during the update. The only thing that happened from that was a chromium update.

I did find a consistent workaround upon restarting, that leads me to believe something is bugged with how KDE logs in graphically. If I log in to my account on tty2 FIRST, then switch back to tty1 to log in, it works every time. I can then logout of tty2 once logged in graphically, as my lock screen is unaffected by this. But this is somewhat irritating. I can only guess this works because a command line login brings some variable into the correct value in memory, which then allows the KDE login to use that value as well and complete the login.

Running w on tty2 whilst tty1 is trying to login (when the workaround is not in place) only reveals the instance on tty2 running, so tty1 is not able to register as logged in without the workaround.

The expected output of w if I was logged in would be along the lines of:

 18:16:03 up 34 min,  3 users,  load average: 0.54, 0.64, 0.79
USER     TTY        LOGIN@   IDLE   JCPU   PCPU WHAT
iruy     tty1      17:43   33:50   1:41   0.08s /usr/bin/startplasma-x11
iruy     pts/0     17:43   32:38   0.00s  2.58s /bin/kded5
iruy     pts/1     17:43    0.00s 12:58   0.00s w

I’ve also tried booting from other kernels, specifically versions 5.10.19-1 and 5.9.16-1, to find the issue repeatable there, which leads me to believe this has something to do with KDE

Any ideas on what I can do to login normally again? Also please tell me what other diagnostics I can run here as I don’t know much about KDE’s file structure or how to debug it. Thanks

EDIT1: as requested, here is the output of inxi --admin --verbosity=7 --filter --no-host --width:

System:
  Kernel: 5.11.2-1-MANJARO x86_64 bits: 64 compiler: gcc v: 10.2.1 
  parameters: BOOT_IMAGE=/boot/vmlinuz-5.11-x86_64 
  root=UUID=2f6e3549-2f3d-4442-938f-2120289fb600 rw quiet 
  cryptdevice=UUID=138f8dfd-f6fa-4f2d-85fa-34c771af156f:luks-138f8dfd-f6fa-4f2d-85fa-34c771af156f 
  root=/dev/mapper/luks-138f8dfd-f6fa-4f2d-85fa-34c771af156f 
  resume=/dev/mapper/luks-138f8dfd-f6fa-4f2d-85fa-34c771af156f 
  Desktop: KDE Plasma 5.21.2 tk: Qt 5.15.2 wm: kwin_x11 dm: SDDM 
  Distro: Manjaro Linux 
Machine:
  Type: Desktop Mobo: ASRock model: B75 Pro3-M serial: <filter> 
  BIOS: American Megatrends v: P1.70 date: 03/02/2013 
Memory:
  RAM: total: 15.58 GiB used: 2.33 GiB (14.9%) 
  RAM Report: permissions: Unable to run dmidecode. Root privileges required. 
CPU:
  Info: Quad Core model: Intel Core i7-3770 bits: 64 type: MT MCP 
  arch: Ivy Bridge family: 6 model-id: 3A (58) stepping: 9 microcode: 21 
  L2 cache: 8 MiB bogomips: 54297 
  Speed: 2231 MHz min/max: 1600/3900 MHz Core speeds (MHz): 1: 2231 2: 1705 
  3: 1825 4: 1596 5: 1733 6: 2918 7: 1810 8: 1623 
  Flags: acpi aes aperfmperf apic arat arch_perfmon avx bts clflush cmov 
  constant_tsc cpuid cpuid_fault cx16 cx8 de ds_cpl dtes64 dtherm dts epb ept 
  erms est f16c flexpriority flush_l1d fpu fsgsbase fxsr ht ibpb ibrs ida 
  lahf_lm lm mca mce md_clear mmx monitor msr mtrr nonstop_tsc nopl nx pae pat 
  pbe pcid pclmulqdq pdcm pebs pge pln pni popcnt pse pse36 pti pts rdrand 
  rdtscp rep_good sep smep smx ss ssbd sse sse2 sse4_1 sse4_2 ssse3 stibp 
  syscall tm tm2 tpr_shadow tsc tsc_deadline_timer vme vmx vnmi vpid x2apic 
  xsave xsaveopt xtopology xtpr 
  Vulnerabilities: Type: itlb_multihit status: KVM: VMX disabled 
  Type: l1tf 
  mitigation: PTE Inversion; VMX: conditional cache flushes, SMT vulnerable 
  Type: mds mitigation: Clear CPU buffers; SMT vulnerable 
  Type: meltdown mitigation: PTI 
  Type: spec_store_bypass 
  mitigation: Speculative Store Bypass disabled via prctl and seccomp 
  Type: spectre_v1 
  mitigation: usercopy/swapgs barriers and __user pointer sanitization 
  Type: spectre_v2 mitigation: Full generic retpoline, IBPB: conditional, 
  IBRS_FW, STIBP: conditional, RSB filling 
  Type: srbds status: Vulnerable: No microcode 
  Type: tsx_async_abort status: Not affected 
Graphics:
  Device-1: AMD Ellesmere [Radeon RX 470/480/570/570X/580/580X/590] 
  driver: amdgpu v: kernel bus ID: 01:00.0 chip ID: 1002:67df class ID: 0300 
  Display: x11 server: X.Org 1.20.10 compositor: kwin_x11 driver: 
  loaded: amdgpu,ati unloaded: modesetting alternate: fbdev,vesa 
  display ID: :0 screens: 1 
  Screen-1: 0 s-res: 1920x1080 s-dpi: 96 s-size: 508x285mm (20.0x11.2") 
  s-diag: 582mm (22.9") 
  Monitor-1: HDMI-A-0 res: 1920x1080 hz: 60 dpi: 94 
  size: 521x293mm (20.5x11.5") diag: 598mm (23.5") 
  OpenGL: renderer: Radeon RX 580 Series (POLARIS10 DRM 3.40.0 
  5.11.2-1-MANJARO LLVM 11.1.0) 
  v: 4.6 Mesa 20.3.4 direct render: Yes 
Audio:
  Device-1: Intel 7 Series/C216 Family High Definition Audio vendor: ASRock 
  driver: snd_hda_intel v: kernel bus ID: 00:1b.0 chip ID: 8086:1e20 
  class ID: 0403 
  Device-2: AMD Ellesmere HDMI Audio [Radeon RX 470/480 / 570/580/590] 
  driver: snd_hda_intel v: kernel bus ID: 01:00.1 chip ID: 1002:aaf0 
  class ID: 0403 
  Device-3: Huawei HiCamera type: USB driver: snd-usb-audio,uvcvideo 
  bus ID: 3-2:2 chip ID: 12d1:4321 class ID: 0102 serial: <filter> 
  Sound Server: ALSA v: k5.11.2-1-MANJARO 
Network:
  Device-1: Broadcom BCM43217 802.11b/g/n driver: bcma-pci-bridge v: N/A 
  modules: bcma port: e000 bus ID: 02:00.0 chip ID: 14e4:43a9 class ID: 0280 
  Device-2: Realtek RTL8111/8168/8411 PCI Express Gigabit Ethernet 
  vendor: ASRock driver: r8168 v: 8.048.03-NAPI modules: r8169 port: c000 
  bus ID: 04:00.0 chip ID: 10ec:8168 class ID: 0200 
  IF: enp4s0 state: up speed: 1000 Mbps duplex: full mac: <filter> 
  IP v4: <filter> type: dynamic noprefixroute scope: global 
  broadcast: <filter> 
  IP v6: <filter> type: dynamic noprefixroute scope: global 
  IP v6: <filter> type: noprefixroute scope: link 
  IF-ID-1: tun0 state: unknown speed: 10 Mbps duplex: full mac: N/A 
  IP v4: <filter> scope: global 
  IP v6: <filter> scope: global 
  IP v6: <filter> virtual: stable-privacy scope: link 
  IF-ID-2: wlp2s0b1 state: down mac: <filter> 
  WAN IP: <filter> 
Bluetooth:
  Device-1: Cambridge Silicon Radio Bluetooth Dongle (HCI mode) type: USB 
  driver: btusb v: 0.8 bus ID: 3-4:3 chip ID: 0a12:0001 class ID: e001 
  Message: Required tool hciconfig not installed. Check --recommends 
RAID:
  Message: No RAID data was found. 
Drives:
  Local Storage: total: 1.02 TiB used: 857.06 GiB (82.1%) 
  SMART Message: Unable to run smartctl. Root privileges required. 
  ID-1: /dev/sda maj-min: 8:0 vendor: Western Digital model: WD10EZEX-00KUWA0 
  size: 931.51 GiB block size: physical: 4096 B logical: 512 B speed: 3.0 Gb/s 
  serial: <filter> rev: 1H15 scheme: MBR 
  ID-2: /dev/sdb maj-min: 8:16 vendor: Kingston model: SV300S37A120G 
  size: 111.79 GiB block size: physical: 512 B logical: 512 B speed: 3.0 Gb/s 
  rotation: SSD serial: <filter> rev: BBF0 scheme: MBR 
  Optical-1: /dev/sr0 vendor: TSSTcorp model: CDDVDW SH-224DB rev: SB01 
  dev-links: cdrom 
  Features: speed: 48 multisession: yes audio: yes dvd: yes 
  rw: cd-r,cd-rw,dvd-r,dvd-ram state: running 
Partition:
  ID-1: / raw size: 914.34 GiB size: 898.99 GiB (98.32%) 
  used: 762.53 GiB (84.8%) fs: ext4 dev: /dev/dm-0 maj-min: 254:0 
  mapped: luks-138f8dfd-f6fa-4f2d-85fa-34c771af156f label: N/A 
  uuid: 2f6e3549-2f3d-4442-938f-2120289fb600 
  ID-2: /run/media/iruy/Windows 7 SSD raw size: 111.69 GiB 
  size: 111.69 GiB (100.00%) used: 94.53 GiB (84.6%) fs: ntfs dev: /dev/sdb2 
  maj-min: 8:18 label: Windows 7 SSD uuid: 80AA1B7DAA1B6EC2 
Swap:
  Kernel: swappiness: 60 (default) cache pressure: 100 (default) 
  ID-1: swap-1 type: partition size: 17.17 GiB used: 780 KiB (0.0%) 
  priority: -2 dev: /dev/dm-1 maj-min: 254:1 
  mapped: luks-6a2e9553-47cf-40b1-895b-c2a75f062e65 label: N/A 
  uuid: 93fa1d6b-f973-4e36-9f22-c94c62d6dc2b 
Unmounted:
  ID-1: /dev/sdb1 maj-min: 8:17 size: 100 MiB fs: ntfs label: N/A 
  uuid: 534133E4177CFF07 
USB:
  Hub-1: 1-0:1 info: Full speed (or root) Hub ports: 2 rev: 2.0 
  speed: 480 Mb/s chip ID: 1d6b:0002 class ID: 0900 
  Hub-2: 1-1:2 info: Intel Integrated Rate Matching Hub ports: 6 rev: 2.0 
  speed: 480 Mb/s chip ID: 8087:0024 class ID: 0900 
  Device-1: 1-1.5:3 info: Gaming KB Gaming KB type: Keyboard 
  driver: hid-generic,usbhid interfaces: 2 rev: 1.1 speed: 12 Mb/s 
  chip ID: 258a:001a class ID: 0301 
  Device-2: 1-1.6:4 info: SteelSeries ApS SteelSeries Rival 3 type: Mouse,HID 
  driver: hid-generic,usbhid interfaces: 4 rev: 2.0 speed: 12 Mb/s 
  chip ID: 1038:1824 class ID: 0300 
  Hub-3: 2-0:1 info: Full speed (or root) Hub ports: 2 rev: 2.0 
  speed: 480 Mb/s chip ID: 1d6b:0002 class ID: 0900 
  Hub-4: 2-1:2 info: Intel Integrated Rate Matching Hub ports: 6 rev: 2.0 
  speed: 480 Mb/s chip ID: 8087:0024 class ID: 0900 
  Hub-5: 3-0:1 info: Full speed (or root) Hub ports: 4 rev: 2.0 
  speed: 480 Mb/s chip ID: 1d6b:0002 class ID: 0900 
  Device-1: 3-2:2 info: Huawei HiCamera type: Video,Audio 
  driver: snd-usb-audio,uvcvideo interfaces: 4 rev: 2.0 speed: 480 Mb/s 
  chip ID: 12d1:4321 class ID: 0102 serial: <filter> 
  Device-2: 3-4:3 info: Cambridge Silicon Radio Bluetooth Dongle (HCI mode) 
  type: Bluetooth driver: btusb interfaces: 2 rev: 2.0 speed: 12 Mb/s 
  chip ID: 0a12:0001 class ID: e001 
  Hub-6: 4-0:1 info: Full speed (or root) Hub ports: 4 rev: 3.0 speed: 5 Gb/s 
  chip ID: 1d6b:0003 class ID: 0900 
Sensors:
  System Temperatures: cpu: 29.0 C mobo: N/A gpu: amdgpu temp: 24.0 C 
  Fan Speeds (RPM): N/A gpu: amdgpu fan: 1136 
Info:
  Processes: 290 Uptime: 1h 26m wakeups: 0 Init: systemd v: 247 Compilers: 
  gcc: 10.2.0 clang: 11.1.0 Packages: 1609 pacman: 1597 lib: 468 flatpak: 12 
  Shell: Bash v: 5.1.0 running in: yakuake inxi: 3.3.01

EDIT2: It appears that the UID (973) of ‘Simple Desktop Display Manager’ closes for some reason unless tty2 is logged into first, as demonstrated by running journalctl --boot=0 --system --quiet --lines=50 on tty2 after intentionally triggering the crash:

Mar 12 14:48:16 manjaro.local systemd[1048]: Reached target Exit the Session.
Mar 12 14:48:16 manjaro.local systemd[1049]: pam_warn(systemd-user:setcred): function=[pam_sm_setcred] flags=0x8004 service=[systemd-user] terminal=[] user=[sddm] ruser=[<unknown>] rhost=[<unknown>]
Mar 12 14:48:16 manjaro.local systemd[1]: user@973.service: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Manager for UID 973.
Mar 12 14:48:16 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local kernel: audit: type=1131 audit(1615560496.478:87): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local systemd[1]: Stopping User Runtime Directory /run/user/973...
Mar 12 14:48:16 manjaro.local kernel: audit: type=1131 audit(1615560496.485:88): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local systemd[1]: run-user-973.mount: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: user-runtime-dir@973.service: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Runtime Directory /run/user/973.
Mar 12 14:48:16 manjaro.local systemd[1]: Removed slice User Slice of UID 973.
Mar 12 14:48:20 manjaro.local systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Mar 12 14:48:20 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:20 manjaro.local kernel: audit: type=1131 audit(1615560500.985:89): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:29 manjaro.local systemd[1]: Started Getty on tty2.
Mar 12 14:48:29 manjaro.local audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=getty@tty2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:29 manjaro.local kernel: audit: type=1130 audit(1615560509.175:90): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=getty@tty2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:33 manjaro.local systemd-timesyncd[777]: Initial synchronization to time server [2a00:2381:19c6::200]:123 (2.manjaro.pool.ntp.org).
Mar 12 14:48:35 manjaro.local audit[1244]: USER_AUTH pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local dbus-daemon[783]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.27' (uid=0 pid=1244 comm="/bin/login -p --      ")
Mar 12 14:48:35 manjaro.local dbus-daemon[783]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 12 14:48:35 manjaro.local audit[1244]: USER_ACCT pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local login[1244]: pam_systemd_home(login:account): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Mar 12 14:48:35 manjaro.local kernel: audit: type=1100 audit(1615560515.721:91): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1101 audit(1615560515.721:92): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: CRED_ACQ pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: SYSCALL arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe9ada5700 a2=4 a3=3e8 items=0 ppid=1 pid=1244 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty2 ses=4 comm="login" exe="/usr/bin/login" key=(null)
Mar 12 14:48:35 manjaro.local audit: PROCTITLE proctitle=2F62696E2F6C6F67696E002D70002D2D0020202020
Mar 12 14:48:35 manjaro.local login[1244]: pam_unix(login:session): session opened for user iruy(uid=1000) by LOGIN(uid=0)
Mar 12 14:48:35 manjaro.local kernel: audit: type=1103 audit(1615560515.725:93): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1006 audit(1615560515.725:94): pid=1244 uid=0 old-auid=4294967295 auid=1000 tty=tty2 old-ses=4294967295 ses=4 res=1
Mar 12 14:48:35 manjaro.local kernel: audit: type=1300 audit(1615560515.725:94): arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe9ada5700 a2=4 a3=3e8 items=0 ppid=1 pid=1244 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty2 ses=4 comm="login" exe="/usr/bin/login" key=(null)
Mar 12 14:48:35 manjaro.local kernel: audit: type=1327 audit(1615560515.725:94): proctitle=2F62696E2F6C6F67696E002D70002D2D0020202020
Mar 12 14:48:35 manjaro.local systemd-logind[786]: New session 4 of user iruy.
Mar 12 14:48:35 manjaro.local systemd[1]: Started Session 4 of user iruy.
Mar 12 14:48:35 manjaro.local login[1244]: pam_env(login:session): deprecated reading of user environment enabled
Mar 12 14:48:35 manjaro.local audit[1244]: USER_START pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: CRED_REFR pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1105 audit(1615560515.745:95): pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1110 audit(1615560515.745:96): pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local login[1244]: LOGIN ON tty2 BY iruy
Mar 12 14:48:40 manjaro.local systemd[1]: systemd-hostnamed.service: Succeeded.
Mar 12 14:48:40 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:40 manjaro.local kernel: audit: type=1131 audit(1615560520.975:97): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:41 manjaro.local audit: BPF prog-id=16 op=UNLOAD
Mar 12 14:48:41 manjaro.local audit: BPF prog-id=15 op=UNLOAD
Mar 12 14:48:41 manjaro.local kernel: audit: type=1334 audit(1615560521.135:98): prog-id=16 op=UNLOAD
Mar 12 14:48:41 manjaro.local kernel: audit: type=1334 audit(1615560521.135:99): prog-id=15 op=UNLOAD

The line that stands out for me here is: Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Manager for UID 973. A quick check of /etc/passwd reveals UID 973 to belong to “Simple Desktop Display Manager”, which would make sense if the desktop is unable to start.

Output of cat /etc/passwd | grep 973:
sddm:x:973:973:Simple Desktop Display Manager:/var/lib/sddm:/sbin/nologin

So, the question seems to be: “What is causing the display manager to crash that is fixed by FIRST logging into command line of tty2?” If someone can help me figure this out then I believe the problem can be resolved

Iruy · 7 March 2021 15:42

I have updated the original post to include the output of inxi --admin --verbosity=7 --filter --no-host --width.

Fabby · 7 March 2021 17:08

You seem to be on an AMD GPU and their drivers are built into the kernel.

Try in the following order:

Does this also happen when you create a new user and log in there? (E.G. iruy2)
As you seem to have tried 5.9, 5.10 and 5.11 already, did you try 5.4 LTS (Long Term Support) already?
If none of the above work, please also provide the output of journalctl --boot=0 --system --quiet --lines=50 after logging into TTY2.

Iruy · 10 March 2021 20:16

New user “iruy2” gets booted back to the screen you see whilst booting (With [OK] started x service e.t.c) for half a second then ends up back at the login screen.
Same occurance on 5.4 LTS
Output of journalctl --boot=0 --system --quiet --lines=50 after logging into TTY2 is as follows:

Mar 10 20:08:22 manjaro audit[1274]: SYSCALL arch=c000003e syscall=321 success=yes exit=8 a0=5 a1=7fffd89fdb00 a2=70 a3=7fffd89fdb00 items=0 ppid=1 pid=1274 auid=1000 uid=1000 gid=1000 euid=1000 suid=1000 fsuid=1000 egid=1000 sgid=1000 fsgid=1000 tty=(none) ses=3 comm="systemd" exe="/usr/lib/systemd/systemd" key=(null)
Mar 10 20:08:22 manjaro audit: PROCTITLE proctitle="(systemd)"
Mar 10 20:08:22 manjaro audit: BPF prog-id=14 op=UNLOAD
Mar 10 20:08:22 manjaro NetworkManager[846]: <info>  [1615406902.7702] device (enp4s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 10 20:08:22 manjaro NetworkManager[846]: <info>  [1615406902.7714] device (enp4s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 10 20:08:22 manjaro NetworkManager[846]: <info>  [1615406902.7717] dhcp4 (enp4s0): activation: beginning transaction (timeout in 45 seconds)
Mar 10 20:08:22 manjaro avahi-daemon[842]: Joining mDNS multicast group on interface enp4s0.IPv6 with address fe80::a8ba:93f7:1c6a:7abf.
Mar 10 20:08:22 manjaro avahi-daemon[842]: New relevant interface enp4s0.IPv6 for mDNS.
Mar 10 20:08:22 manjaro avahi-daemon[842]: Registering new address record for fe80::a8ba:93f7:1c6a:7abf on enp4s0.*.
Mar 10 20:08:23 manjaro audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 10 20:08:23 manjaro audit[1272]: USER_START pid=1272 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro addr=? terminal=/dev/tty2 res=success'
Mar 10 20:08:23 manjaro audit[1272]: CRED_REFR pid=1272 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro addr=? terminal=/dev/tty2 res=success'
Mar 10 20:08:23 manjaro login[1272]: pam_env(login:session): deprecated reading of user environment enabled
Mar 10 20:08:23 manjaro login[1272]: LOGIN ON tty2 BY iruy
Mar 10 20:08:23 manjaro systemd[1]: Started User Manager for UID 1000.
Mar 10 20:08:23 manjaro systemd[1]: Started Session 2 of user iruy.
Mar 10 20:08:26 manjaro systemd[1]: systemd-hostnamed.service: Succeeded.
Mar 10 20:08:26 manjaro audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 10 20:08:26 manjaro audit: BPF prog-id=10 op=UNLOAD
Mar 10 20:08:26 manjaro audit: BPF prog-id=9 op=UNLOAD
Mar 10 20:08:26 manjaro kernel: r8168: enp4s0: link up
Mar 10 20:08:26 manjaro kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp4s0: link becomes ready
Mar 10 20:08:26 manjaro NetworkManager[846]: <info>  [1615406906.8414] device (enp4s0): carrier: link connected
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.2661] dhcp4 (enp4s0): state changed unknown -> bound, address=192.168.1.115
Mar 10 20:08:27 manjaro avahi-daemon[842]: Joining mDNS multicast group on interface enp4s0.IPv4 with address 192.168.1.115.
Mar 10 20:08:27 manjaro avahi-daemon[842]: New relevant interface enp4s0.IPv4 for mDNS.
Mar 10 20:08:27 manjaro avahi-daemon[842]: Registering new address record for 192.168.1.115 on enp4s0.IPv4.
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.2680] device (enp4s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 10 20:08:27 manjaro dbus-daemon[845]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.5' (uid=0 pid=846 comm="/usr/bin/NetworkManager --no-daemon ")
Mar 10 20:08:27 manjaro systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 10 20:08:27 manjaro dbus-daemon[845]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 10 20:08:27 manjaro systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 10 20:08:27 manjaro audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.3152] device (enp4s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.3157] device (enp4s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.3167] manager: NetworkManager state is now CONNECTED_LOCAL
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.3189] manager: NetworkManager state is now CONNECTED_SITE
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.3192] policy: set 'Ethernet' (enp4s0) as default for IPv4 routing and DNS
Mar 10 20:08:27 manjaro dbus-daemon[845]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.5' (uid=0 pid=846 comm="/usr/bin/NetworkManager --no-daemon ")
Mar 10 20:08:27 manjaro dbus-daemon[845]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.3836] device (enp4s0): Activation: successful, device activated.
Mar 10 20:08:27 manjaro NetworkManager[846]: <info>  [1615406907.5435] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 10 20:08:28 manjaro NetworkManager[846]: <info>  [1615406908.2596] policy: set-hostname: set hostname to 'manjaro.local' (from address lookup)
Mar 10 20:08:28 manjaro dbus-daemon[845]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=846 comm="/usr/bin/NetworkManager --no-daemon ")
Mar 10 20:08:28 manjaro audit: BPF prog-id=15 op=LOAD
Mar 10 20:08:28 manjaro audit: BPF prog-id=16 op=LOAD
Mar 10 20:08:28 manjaro systemd[1]: Starting Hostname Service...
Mar 10 20:08:28 manjaro kernel: kauditd_printk_skb: 14 callbacks suppressed
Mar 10 20:08:28 manjaro kernel: audit: type=1334 audit(1615406908.259:68): prog-id=15 op=LOAD
Mar 10 20:08:28 manjaro kernel: audit: type=1334 audit(1615406908.259:69): prog-id=16 op=LOAD

Fabby · 10 March 2021 21:23

Nothing special there…

tail --lines=30 /var/log/Xorg.0.log

Please?

Iruy · 11 March 2021 20:37

After logging into tty2 and running tail --lines=30 /var/log/Xorg.0.log:

[    39.407] (**) Option "Device" "/dev/input/event4"
[    39.407] (**) Option "_source" "_driver/libinput"
[    39.407] (II) libinput: Gaming KB  Gaming KB  Consumer Control: is a virtual subdevice
[    39.407] (**) Option "config_info" "udev:/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.5/1-1.5:1.1/0003:258A:001A.0002/input/input4/event4"
[    39.407] (II) XINPUT: Adding extended input device "Gaming KB  Gaming KB  Consumer Control" (type: KEYBOARD, id 16)
[    39.407] (**) Option "xkb_model" "pc105"
[    39.407] (**) Option "xkb_layout" "gb"
[    39.408] (**) SteelSeries SteelSeries Rival 3 Consumer Control: Applying InputClass "evdev keyboard catchall"
[    39.408] (**) SteelSeries SteelSeries Rival 3 Consumer Control: Applying InputClass "libinput keyboard catchall"
[    39.408] (**) SteelSeries SteelSeries Rival 3 Consumer Control: Applying InputClass "system-keyboard"
[    39.408] (II) Using input driver 'libinput' for 'SteelSeries SteelSeries Rival 3 Consumer Control'
[    39.408] (**) SteelSeries SteelSeries Rival 3 Consumer Control: always reports core events
[    39.408] (**) Option "Device" "/dev/input/event8"
[    39.408] (**) Option "_source" "_driver/libinput"
[    39.408] (II) libinput: SteelSeries SteelSeries Rival 3 Consumer Control: is a virtual subdevice
[    39.408] (**) Option "config_info" "udev:/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.1/0003:1038:1824.0004/input/input8/event8"
[    39.408] (II) XINPUT: Adding extended input device "SteelSeries SteelSeries Rival 3 Consumer Control" (type: KEYBOARD, id 17)
[    39.408] (**) Option "xkb_model" "pc105"
[    39.408] (**) Option "xkb_layout" "gb"
[    54.568] (II) event1  - Power Button: device removed
[    54.603] (II) event0  - Power Button: device removed
[    54.630] (II) event21 - HiCamera: UVC Camera: device removed
[    54.763] (II) event2  - Gaming KB  Gaming KB : device removed
[    54.830] (II) event3  - Gaming KB  Gaming KB  System Control: device removed
[    54.897] (II) event5  - Gaming KB  Gaming KB  Keyboard: device removed
[    54.963] (II) event6  - SteelSeries SteelSeries Rival 3: device removed
[    55.097] (II) event7  - SteelSeries SteelSeries Rival 3 Keyboard: device removed
[    55.150] (II) event4  - Gaming KB  Gaming KB  Consumer Control: device removed
[    55.203] (II) event8  - SteelSeries SteelSeries Rival 3 Consumer Control: device removed
[    55.230] (II) AIGLX: Suspending AIGLX clients for VT switch

Just seems like hardware initializing to me.

Fabby · 11 March 2021 20:45

Damn! Nothing there neither!

last-ditch effort:

tail --lines=50 ~/.local/share/sddm/xorg-session.log

Iruy · 12 March 2021 15:13

Update: I think I may have misinterpreted your initial instructions a little bit (sorry!). I did those commands whilst logging into tty2 without triggering the glitch, which thinking about it makes no sense. I have repeated these after logging into tty1 as I normally would and freezing first, and the output of journalctl --boot=0 --system --quiet --lines=50 reveals the following:

Mar 12 14:48:16 manjaro.local systemd[1048]: Reached target Exit the Session.
Mar 12 14:48:16 manjaro.local systemd[1049]: pam_warn(systemd-user:setcred): function=[pam_sm_setcred] flags=0x8004 service=[systemd-user] terminal=[] user=[sddm] ruser=[<unknown>] rhost=[<unknown>]
Mar 12 14:48:16 manjaro.local systemd[1]: user@973.service: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Manager for UID 973.
Mar 12 14:48:16 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local kernel: audit: type=1131 audit(1615560496.478:87): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local systemd[1]: Stopping User Runtime Directory /run/user/973...
Mar 12 14:48:16 manjaro.local kernel: audit: type=1131 audit(1615560496.485:88): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local systemd[1]: run-user-973.mount: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: user-runtime-dir@973.service: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Runtime Directory /run/user/973.
Mar 12 14:48:16 manjaro.local systemd[1]: Removed slice User Slice of UID 973.
Mar 12 14:48:20 manjaro.local systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Mar 12 14:48:20 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:20 manjaro.local kernel: audit: type=1131 audit(1615560500.985:89): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:29 manjaro.local systemd[1]: Started Getty on tty2.
Mar 12 14:48:29 manjaro.local audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=getty@tty2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:29 manjaro.local kernel: audit: type=1130 audit(1615560509.175:90): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=getty@tty2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:33 manjaro.local systemd-timesyncd[777]: Initial synchronization to time server [2a00:2381:19c6::200]:123 (2.manjaro.pool.ntp.org).
Mar 12 14:48:35 manjaro.local audit[1244]: USER_AUTH pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local dbus-daemon[783]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.27' (uid=0 pid=1244 comm="/bin/login -p --      ")
Mar 12 14:48:35 manjaro.local dbus-daemon[783]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 12 14:48:35 manjaro.local audit[1244]: USER_ACCT pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local login[1244]: pam_systemd_home(login:account): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Mar 12 14:48:35 manjaro.local kernel: audit: type=1100 audit(1615560515.721:91): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1101 audit(1615560515.721:92): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: CRED_ACQ pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: SYSCALL arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe9ada5700 a2=4 a3=3e8 items=0 ppid=1 pid=1244 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty2 ses=4 comm="login" exe="/usr/bin/login" key=(null)
Mar 12 14:48:35 manjaro.local audit: PROCTITLE proctitle=2F62696E2F6C6F67696E002D70002D2D0020202020
Mar 12 14:48:35 manjaro.local login[1244]: pam_unix(login:session): session opened for user iruy(uid=1000) by LOGIN(uid=0)
Mar 12 14:48:35 manjaro.local kernel: audit: type=1103 audit(1615560515.725:93): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1006 audit(1615560515.725:94): pid=1244 uid=0 old-auid=4294967295 auid=1000 tty=tty2 old-ses=4294967295 ses=4 res=1
Mar 12 14:48:35 manjaro.local kernel: audit: type=1300 audit(1615560515.725:94): arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe9ada5700 a2=4 a3=3e8 items=0 ppid=1 pid=1244 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty2 ses=4 comm="login" exe="/usr/bin/login" key=(null)
Mar 12 14:48:35 manjaro.local kernel: audit: type=1327 audit(1615560515.725:94): proctitle=2F62696E2F6C6F67696E002D70002D2D0020202020
Mar 12 14:48:35 manjaro.local systemd-logind[786]: New session 4 of user iruy.
Mar 12 14:48:35 manjaro.local systemd[1]: Started Session 4 of user iruy.
Mar 12 14:48:35 manjaro.local login[1244]: pam_env(login:session): deprecated reading of user environment enabled
Mar 12 14:48:35 manjaro.local audit[1244]: USER_START pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: CRED_REFR pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1105 audit(1615560515.745:95): pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1110 audit(1615560515.745:96): pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local login[1244]: LOGIN ON tty2 BY iruy
Mar 12 14:48:40 manjaro.local systemd[1]: systemd-hostnamed.service: Succeeded.
Mar 12 14:48:40 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:40 manjaro.local kernel: audit: type=1131 audit(1615560520.975:97): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:41 manjaro.local audit: BPF prog-id=16 op=UNLOAD
Mar 12 14:48:41 manjaro.local audit: BPF prog-id=15 op=UNLOAD
Mar 12 14:48:41 manjaro.local kernel: audit: type=1334 audit(1615560521.135:98): prog-id=16 op=UNLOAD
Mar 12 14:48:41 manjaro.local kernel: audit: type=1334 audit(1615560521.135:99): prog-id=15 op=UNLOAD

The line that stands out for me here is: Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Manager for UID 973. A quick check of /etc/passwd reveals UID 973 to belong to “Simple Desktop Display Manager”, which would make sense if the desktop is unable to start.

Output of cat /etc/passwd | grep 973:
sddm:x:973:973:Simple Desktop Display Manager:/var/lib/sddm:/sbin/nologin

Xorg session logs seemed unchanged by me doing things this way, but I can still post the output of tail --lines=50 ~/.local/share/sddm/xorg-session.log if you’d like

Fabby · 12 March 2021 15:52

I’m at the end of my knowledge anyway, so can’t help you any further…

Iruy · 14 March 2021 01:49

What else can I do then? I only come to forums as a last resort. Do you know anyone else that might be able to help?

Fabby · 14 March 2021 10:22

No I don’t know who else to pull in so I’ve re-classified your issue to the #support:graphic-cards where the graphics people lurk instead of the #support:plasma section

Something we didn’t check is the /home/$USER/.local/share/sddm/xorg-session.log?

Anything interesting there???

Iruy · 20 March 2021 17:55

Output of tail --lines=50 ~/.local/share/sddm/xorg-session.log after causing crash deliberately:

/etc/profile.d/perlbin.sh: line 5: append_path: command not found
/etc/profile.d/perlbin.sh: line 7: append_path: command not found
/etc/profile.d/perlbin.sh: line 9: append_path: command not found
kdeinit5: preparing to launch '/usr/lib/kf5/klauncher'
kdeinit5: Launched KLauncher, pid = 1163, result = 0
Invalid MIT-MAGIC-COOKIE-1 keykdeinit5: Can not connect to the X Server.
kdeinit5: Might not terminate at end of session.
Invalid MIT-MAGIC-COOKIE-1 key

Here Invalid MIT-MAGIC-COOKIE-1 keykdeinit5: Can not connect to the X Server. sticks out to me. If I’m not mistaken this would be the cause of UID 973 (Simple Desktop Display Manager) being stopped as seen in the output of journalctl --boot=0 --system --quiet --lines=50 after causing the crash:

Mar 12 14:48:16 manjaro.local systemd[1048]: Reached target Exit the Session.
Mar 12 14:48:16 manjaro.local systemd[1049]: pam_warn(systemd-user:setcred): function=[pam_sm_setcred] flags=0x8004 service=[systemd-user] terminal=[] user=[sddm] ruser=[<unknown>] rhost=[<unknown>]
Mar 12 14:48:16 manjaro.local systemd[1]: user@973.service: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Manager for UID 973.
Mar 12 14:48:16 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local kernel: audit: type=1131 audit(1615560496.478:87): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local systemd[1]: Stopping User Runtime Directory /run/user/973...
Mar 12 14:48:16 manjaro.local kernel: audit: type=1131 audit(1615560496.485:88): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@973 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:16 manjaro.local systemd[1]: run-user-973.mount: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: user-runtime-dir@973.service: Succeeded.
Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Runtime Directory /run/user/973.
Mar 12 14:48:16 manjaro.local systemd[1]: Removed slice User Slice of UID 973.
Mar 12 14:48:20 manjaro.local systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Mar 12 14:48:20 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:20 manjaro.local kernel: audit: type=1131 audit(1615560500.985:89): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:29 manjaro.local systemd[1]: Started Getty on tty2.
Mar 12 14:48:29 manjaro.local audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=getty@tty2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:29 manjaro.local kernel: audit: type=1130 audit(1615560509.175:90): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=getty@tty2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:33 manjaro.local systemd-timesyncd[777]: Initial synchronization to time server [2a00:2381:19c6::200]:123 (2.manjaro.pool.ntp.org).
Mar 12 14:48:35 manjaro.local audit[1244]: USER_AUTH pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local dbus-daemon[783]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.27' (uid=0 pid=1244 comm="/bin/login -p --      ")
Mar 12 14:48:35 manjaro.local dbus-daemon[783]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 12 14:48:35 manjaro.local audit[1244]: USER_ACCT pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local login[1244]: pam_systemd_home(login:account): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Mar 12 14:48:35 manjaro.local kernel: audit: type=1100 audit(1615560515.721:91): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1101 audit(1615560515.721:92): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: CRED_ACQ pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: SYSCALL arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe9ada5700 a2=4 a3=3e8 items=0 ppid=1 pid=1244 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty2 ses=4 comm="login" exe="/usr/bin/login" key=(null)
Mar 12 14:48:35 manjaro.local audit: PROCTITLE proctitle=2F62696E2F6C6F67696E002D70002D2D0020202020
Mar 12 14:48:35 manjaro.local login[1244]: pam_unix(login:session): session opened for user iruy(uid=1000) by LOGIN(uid=0)
Mar 12 14:48:35 manjaro.local kernel: audit: type=1103 audit(1615560515.725:93): pid=1244 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1006 audit(1615560515.725:94): pid=1244 uid=0 old-auid=4294967295 auid=1000 tty=tty2 old-ses=4294967295 ses=4 res=1
Mar 12 14:48:35 manjaro.local kernel: audit: type=1300 audit(1615560515.725:94): arch=c000003e syscall=1 success=yes exit=4 a0=5 a1=7ffe9ada5700 a2=4 a3=3e8 items=0 ppid=1 pid=1244 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=1000 fsgid=0 tty=tty2 ses=4 comm="login" exe="/usr/bin/login" key=(null)
Mar 12 14:48:35 manjaro.local kernel: audit: type=1327 audit(1615560515.725:94): proctitle=2F62696E2F6C6F67696E002D70002D2D0020202020
Mar 12 14:48:35 manjaro.local systemd-logind[786]: New session 4 of user iruy.
Mar 12 14:48:35 manjaro.local systemd[1]: Started Session 4 of user iruy.
Mar 12 14:48:35 manjaro.local login[1244]: pam_env(login:session): deprecated reading of user environment enabled
Mar 12 14:48:35 manjaro.local audit[1244]: USER_START pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local audit[1244]: CRED_REFR pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1105 audit(1615560515.745:95): pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local kernel: audit: type=1110 audit(1615560515.745:96): pid=1244 uid=0 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_securetty,pam_shells,pam_faillock,pam_permit,pam_faillock acct="iruy" exe="/usr/bin/login" hostname=manjaro.local addr=? terminal=/dev/tty2 res=success'
Mar 12 14:48:35 manjaro.local login[1244]: LOGIN ON tty2 BY iruy
Mar 12 14:48:40 manjaro.local systemd[1]: systemd-hostnamed.service: Succeeded.
Mar 12 14:48:40 manjaro.local audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:40 manjaro.local kernel: audit: type=1131 audit(1615560520.975:97): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 12 14:48:41 manjaro.local audit: BPF prog-id=16 op=UNLOAD
Mar 12 14:48:41 manjaro.local audit: BPF prog-id=15 op=UNLOAD
Mar 12 14:48:41 manjaro.local kernel: audit: type=1334 audit(1615560521.135:98): prog-id=16 op=UNLOAD
Mar 12 14:48:41 manjaro.local kernel: audit: type=1334 audit(1615560521.135:99): prog-id=15 op=UNLOAD

The line that stood out for me there was Mar 12 14:48:16 manjaro.local systemd[1]: Stopped User Manager for UID 973, which corresponds to “Simple Desktop Display Manager”

Fabby · 21 March 2021 09:59

That rings a bell:

What’s the output of:

locate appendpath

if command error:

find / -iname appendpath -print

Iruy · 22 March 2021 19:23

I’ve been saving diagnostic commands you’ve requested as shellscripts so that I can easily run them from tty2 when I cause the glitch, and then using > to write the output. When I ran the shellscript this time, I received a blank file. I was unsure if I did something wrong, so I ran it raw, without writing to a file to see what I was “supposed” to see. However, this did not produce an output either. I logged in using the workaround, and then ran it again, running into a permissions issue (even with sudo). So I switched to root, but STILL got a permissions error (The same one as running with sudo):

[manjaro iruy]# find / -iname appendpath -print
find: ‘/run/user/1000/gvfs’: Permission denied

I have no idea why root would be unable to access a directory or file. Still, I checked /run/user/1000/gvfs, and found I am indeed the owner, but it’s just an empty directory

[iruy@manjaro gvfs]$ ll
total 0
dr-x------ 2 iruy iruy   0 Mar 22 19:13 .
drwx------ 9 iruy iruy 300 Mar 22 19:17 ..

Fabby · 22 March 2021 21:00

Sorry. All out of ideas then…