You are not logged in.

#1 2021-03-23 20:51:48

sshaikh
Member
Registered: 2019-07-03
Posts: 53

Display hangs after resume from sleep, but SSH still works

I'm experiencing some strange resume issues on an Intel n4000 based mini pc running Arch.

When in an i3 session, the pc will suspend as expected, with the monitor turning off.

Hitting a key wakes up the monitor to a blank screen, with the indicator lights on the keyboard frozen.

However (and this is why I'm not sure how to approach the problem), I can still SSH into the box and use the machine via that session, although the shutdown command doesn't seem to shut the pc at this point.

So far I have tried the lts kernel with no change in behaviour. Before I try anything more involved, I was wondering if having SSH access might be useful to diagnose this issue.

Offline

#2 2021-03-23 23:22:03

seth
Member
Registered: 2012-09-03
Posts: 50,924

Re: Display hangs after resume from sleep, but SSH still works

Errors in dmesg/system journal?

Offline

#3 2021-03-24 16:27:39

sshaikh
Member
Registered: 2019-07-03
Posts: 53

Re: Display hangs after resume from sleep, but SSH still works

In my attempted to reproduce the issue I've found the following. I've noticed that entries and behaviour varies depending on whether I run systemctl suspend outside of xorg or allow an i3 session to suspend after some time (the tty doesn't seem to suspend itself automatically).

Here's some errors from Dmesg:

[    3.414930] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[    3.414940] cfg80211: failed to load regulatory.db
[    4.421439] snd_hda_codec_hdmi hdaudioC0D2: Monitor plugged-in, Failed to power up codec ret=[-13]

Journal is more interesting, although I the following only generates if I run systemctl suspend, and not when the computer monitor turns off by itself during an xsession.

Mar 24 14:08:04 desktop systemd[1]: Starting Suspend...
Mar 24 14:08:04 desktop kernel: audit: type=1106 audit(1616594884.088:84): pid=29778 uid=1000 auid=1000 ses=4 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 14:08:04 desktop kernel: audit: type=1104 audit(1616594884.088:85): pid=29778 uid=1000 auid=1000 ses=4 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 14:08:04 desktop systemd-sleep[29781]: Suspending system...
Mar 24 14:08:04 desktop kernel: PM: suspend entry (deep)
Mar 24 14:08:24 desktop kernel: Filesystems sync: 0.025 seconds
Mar 24 14:08:24 desktop kernel: Freezing user space processes ... 
Mar 24 14:08:24 desktop kernel: Freezing of tasks failed after 20.005 seconds (1 tasks refusing to freeze, wq_busy=0):
Mar 24 14:08:24 desktop kernel: task:Xorg            state:D stack:    0 pid:  465 ppid:   464 flags:0x00004004
Mar 24 14:08:24 desktop kernel: Call Trace:
Mar 24 14:08:24 desktop kernel:  __schedule+0x2dd/0x8b0
Mar 24 14:08:24 desktop kernel:  schedule+0x5b/0xc0
Mar 24 14:08:24 desktop kernel:  schedule_preempt_disabled+0x11/0x20
Mar 24 14:08:24 desktop kernel:  __ww_mutex_lock.constprop.0+0x42c/0x810
Mar 24 14:08:24 desktop kernel:  drm_modeset_lock+0x31/0xb0 [drm]
Mar 24 14:08:24 desktop kernel:  glk_force_audio_cdclk+0xa3/0x1a0 [i915]
Mar 24 14:08:24 desktop kernel:  i915_audio_component_get_power+0xe0/0x100 [i915]
Mar 24 14:08:24 desktop kernel:  snd_hdac_display_power+0xd7/0x150 [snd_hda_core]
Mar 24 14:08:24 desktop kernel:  __azx_runtime_resume+0x1d/0xe0 [snd_hda_intel]
Mar 24 14:08:24 desktop kernel:  azx_runtime_resume+0x3d/0xa0 [snd_hda_intel]
Mar 24 14:08:24 desktop kernel:  pci_pm_runtime_resume+0xaa/0xc0
Mar 24 14:08:24 desktop kernel:  ? pci_pm_freeze_noirq+0x100/0x100
Mar 24 14:08:24 desktop kernel:  ? pci_pm_freeze_noirq+0x100/0x100
Mar 24 14:08:24 desktop kernel:  __rpm_callback+0x43/0x230
Mar 24 14:08:24 desktop kernel:  ? pci_pm_freeze_noirq+0x100/0x100
Mar 24 14:08:24 desktop kernel:  rpm_callback+0x1f/0x70
Mar 24 14:08:24 desktop kernel:  rpm_resume+0x5c4/0x810
Mar 24 14:08:24 desktop kernel:  rpm_resume+0x308/0x810
Mar 24 14:08:24 desktop kernel:  __pm_runtime_resume+0x3b/0x60
Mar 24 14:08:24 desktop kernel:  sync_eld_via_acomp+0xe7/0x350 [snd_hda_codec_hdmi]
Mar 24 14:08:24 desktop kernel:  check_presence_and_report+0x57/0x80 [snd_hda_codec_hdmi]
Mar 24 14:08:24 desktop kernel:  intel_audio_codec_enable+0x12a/0x1a0 [i915]
Mar 24 14:08:24 desktop kernel:  intel_enable_ddi+0x450/0x580 [i915]
Mar 24 14:08:24 desktop kernel:  ? fwtable_write32+0x4c/0x240 [i915]
Mar 24 14:08:24 desktop kernel:  intel_encoders_enable+0x80/0xa0 [i915]
Mar 24 14:08:24 desktop kernel:  hsw_crtc_enable+0x1f2/0x760 [i915]
Mar 24 14:08:24 desktop kernel:  intel_enable_crtc+0x59/0x70 [i915]
Mar 24 14:08:24 desktop kernel:  skl_commit_modeset_enables+0x271/0x550 [i915]
Mar 24 14:08:24 desktop kernel:  intel_atomic_commit_tail+0x397/0x13a0 [i915]
Mar 24 14:08:24 desktop kernel:  ? flush_workqueue_prep_pwqs+0x117/0x130
Mar 24 14:08:24 desktop kernel:  ? flush_workqueue+0x19d/0x3f0
Mar 24 14:08:24 desktop kernel:  intel_atomic_commit+0x333/0x3b0 [i915]
Mar 24 14:08:24 desktop kernel:  drm_atomic_connector_commit_dpms+0xda/0x100 [drm]
Mar 24 14:08:24 desktop kernel:  drm_mode_obj_set_property_ioctl+0x196/0x3d0 [drm]
Mar 24 14:08:24 desktop kernel:  ? __schedule+0x2e5/0x8b0
Mar 24 14:08:24 desktop kernel:  ? drm_connector_set_obj_prop+0x90/0x90 [drm]
Mar 24 14:08:24 desktop kernel:  drm_connector_property_set_ioctl+0x39/0x60 [drm]
Mar 24 14:08:24 desktop kernel:  drm_ioctl_kernel+0xb2/0x100 [drm]
Mar 24 14:08:24 desktop kernel:  drm_ioctl+0x215/0x390 [drm]
Mar 24 14:08:24 desktop kernel:  ? drm_connector_set_obj_prop+0x90/0x90 [drm]
Mar 24 14:08:24 desktop kernel:  __x64_sys_ioctl+0x83/0xb0
Mar 24 14:08:24 desktop kernel:  do_syscall_64+0x33/0x40
Mar 24 14:08:24 desktop kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xa9
Mar 24 14:08:24 desktop kernel: RIP: 0033:0x7f01bf975e6b
Mar 24 14:08:24 desktop kernel: RSP: 002b:00007ffd4315fe38 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
Mar 24 14:08:24 desktop kernel: RAX: ffffffffffffffda RBX: 00007ffd4315fe70 RCX: 00007f01bf975e6b
Mar 24 14:08:24 desktop kernel: RDX: 00007ffd4315fe70 RSI: 00000000c01064ab RDI: 000000000000000a
Mar 24 14:08:24 desktop kernel: RBP: 00000000c01064ab R08: 000055a1d0020138 R09: 0000000000000000
Mar 24 14:08:24 desktop kernel: R10: 0000000000000000 R11: 0000000000000246 R12: 000055a1d022bdd0
Mar 24 14:08:24 desktop kernel: R13: 000000000000000a R14: 0000000000000000 R15: 0000000000000000
Mar 24 14:08:24 desktop kernel: 
Mar 24 14:08:24 desktop kernel: OOM killer enabled.
Mar 24 14:08:24 desktop kernel: Restarting tasks ... done.
Mar 24 14:08:24 desktop kernel: thermal thermal_zone2: failed to read out thermal zone (-61)
Mar 24 14:08:24 desktop kernel: video LNXVIDEO:00: Restoring backlight state
Mar 24 14:08:24 desktop kernel: PM: suspend exit
Mar 24 14:08:24 desktop kernel: PM: suspend entry (s2idle)
Mar 24 14:08:44 desktop kernel: Filesystems sync: 0.038 seconds
Mar 24 14:08:44 desktop kernel: Freezing user space processes ... 
Mar 24 14:08:44 desktop kernel: usb 1-5: new high-speed USB device number 9 using xhci_hcd
Mar 24 14:08:44 desktop kernel: usb 1-5: New USB device found, idVendor=0bda, idProduct=5409, bcdDevice= 1.36
Mar 24 14:08:44 desktop kernel: usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Mar 24 14:08:44 desktop kernel: usb 1-5: Product: 3-Port USB 2.1 Hub
Mar 24 14:08:44 desktop kernel: usb 1-5: Manufacturer: Generic
Mar 24 14:08:44 desktop kernel: hub 1-5:1.0: USB hub found
Mar 24 14:08:44 desktop kernel: hub 1-5:1.0: 3 ports detected
Mar 24 14:08:44 desktop kernel: usb 2-5: new SuperSpeed Gen 1 USB device number 3 using xhci_hcd
Mar 24 14:08:44 desktop kernel: usb 2-5: New USB device found, idVendor=0bda, idProduct=0409, bcdDevice= 1.36
Mar 24 14:08:44 desktop kernel: usb 2-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Mar 24 14:08:44 desktop kernel: usb 2-5: Product: 2-Port USB 3.1 Hub
Mar 24 14:08:44 desktop kernel: usb 2-5: Manufacturer: Generic
Mar 24 14:08:44 desktop kernel: hub 2-5:1.0: USB hub found
Mar 24 14:08:44 desktop kernel: hub 2-5:1.0: 2 ports detected
Mar 24 14:08:44 desktop kernel: usb 1-5.1: new high-speed USB device number 10 using xhci_hcd
Mar 24 14:08:44 desktop kernel: 
Mar 24 14:08:44 desktop kernel: Freezing of tasks failed after 20.003 seconds (1 tasks refusing to freeze, wq_busy=0):
Mar 24 14:08:44 desktop kernel: task:Xorg            state:D stack:    0 pid:  465 ppid:   464 flags:0x00004004
Mar 24 14:08:44 desktop kernel: Call Trace:
Mar 24 14:08:44 desktop kernel:  __schedule+0x2dd/0x8b0
Mar 24 14:08:44 desktop kernel:  schedule+0x5b/0xc0
Mar 24 14:08:44 desktop kernel:  schedule_preempt_disabled+0x11/0x20
Mar 24 14:08:44 desktop kernel:  __ww_mutex_lock.constprop.0+0x42c/0x810
Mar 24 14:08:44 desktop kernel:  drm_modeset_lock+0x31/0xb0 [drm]
Mar 24 14:08:44 desktop kernel:  glk_force_audio_cdclk+0xa3/0x1a0 [i915]
Mar 24 14:08:44 desktop kernel:  i915_audio_component_get_power+0xe0/0x100 [i915]
Mar 24 14:08:44 desktop kernel:  snd_hdac_display_power+0xd7/0x150 [snd_hda_core]
Mar 24 14:08:44 desktop kernel:  __azx_runtime_resume+0x1d/0xe0 [snd_hda_intel]
Mar 24 14:08:44 desktop kernel:  azx_runtime_resume+0x3d/0xa0 [snd_hda_intel]
Mar 24 14:08:44 desktop kernel:  pci_pm_runtime_resume+0xaa/0xc0
Mar 24 14:08:44 desktop kernel:  ? pci_pm_freeze_noirq+0x100/0x100
Mar 24 14:08:44 desktop kernel:  ? pci_pm_freeze_noirq+0x100/0x100
Mar 24 14:08:44 desktop kernel:  __rpm_callback+0x43/0x230
Mar 24 14:08:44 desktop kernel:  ? pci_pm_freeze_noirq+0x100/0x100
Mar 24 14:08:44 desktop kernel:  rpm_callback+0x1f/0x70
Mar 24 14:08:44 desktop kernel:  rpm_resume+0x5c4/0x810
Mar 24 14:08:44 desktop kernel:  rpm_resume+0x308/0x810
Mar 24 14:08:44 desktop kernel:  __pm_runtime_resume+0x3b/0x60
Mar 24 14:08:44 desktop kernel:  sync_eld_via_acomp+0xe7/0x350 [snd_hda_codec_hdmi]
Mar 24 14:08:44 desktop kernel:  check_presence_and_report+0x57/0x80 [snd_hda_codec_hdmi]
Mar 24 14:08:44 desktop kernel:  intel_audio_codec_enable+0x12a/0x1a0 [i915]
Mar 24 14:08:44 desktop kernel:  intel_enable_ddi+0x450/0x580 [i915]
Mar 24 14:08:44 desktop kernel:  ? fwtable_write32+0x4c/0x240 [i915]
Mar 24 14:08:44 desktop kernel:  intel_encoders_enable+0x80/0xa0 [i915]
Mar 24 14:08:44 desktop kernel:  hsw_crtc_enable+0x1f2/0x760 [i915]
Mar 24 14:08:44 desktop kernel:  intel_enable_crtc+0x59/0x70 [i915]
Mar 24 14:08:44 desktop kernel:  skl_commit_modeset_enables+0x271/0x550 [i915]
Mar 24 14:08:44 desktop kernel:  intel_atomic_commit_tail+0x397/0x13a0 [i915]
Mar 24 14:08:44 desktop kernel:  ? flush_workqueue_prep_pwqs+0x117/0x130
Mar 24 14:08:44 desktop kernel:  ? flush_workqueue+0x19d/0x3f0
Mar 24 14:08:44 desktop kernel:  intel_atomic_commit+0x333/0x3b0 [i915]
Mar 24 14:08:44 desktop kernel:  drm_atomic_connector_commit_dpms+0xda/0x100 [drm]
Mar 24 14:08:44 desktop kernel:  drm_mode_obj_set_property_ioctl+0x196/0x3d0 [drm]
Mar 24 14:08:44 desktop kernel:  ? __schedule+0x2e5/0x8b0
Mar 24 14:08:44 desktop kernel:  ? drm_connector_set_obj_prop+0x90/0x90 [drm]
Mar 24 14:08:44 desktop kernel:  drm_connector_property_set_ioctl+0x39/0x60 [drm]
Mar 24 14:08:44 desktop kernel:  drm_ioctl_kernel+0xb2/0x100 [drm]
Mar 24 14:08:44 desktop kernel:  drm_ioctl+0x215/0x390 [drm]
Mar 24 14:08:44 desktop kernel:  ? drm_connector_set_obj_prop+0x90/0x90 [drm]
Mar 24 14:08:44 desktop kernel:  __x64_sys_ioctl+0x83/0xb0
Mar 24 14:08:44 desktop kernel:  do_syscall_64+0x33/0x40
Mar 24 14:08:44 desktop kernel:  entry_SYSCALL_64_after_hwframe+0x44/0xa9
Mar 24 14:08:44 desktop kernel: RIP: 0033:0x7f01bf975e6b
Mar 24 14:08:44 desktop kernel: RSP: 002b:00007ffd4315fe38 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
Mar 24 14:08:44 desktop kernel: RAX: ffffffffffffffda RBX: 00007ffd4315fe70 RCX: 00007f01bf975e6b
Mar 24 14:08:44 desktop kernel: RDX: 00007ffd4315fe70 RSI: 00000000c01064ab RDI: 000000000000000a
Mar 24 14:08:44 desktop kernel: RBP: 00000000c01064ab R08: 000055a1d0020138 R09: 0000000000000000
Mar 24 14:08:44 desktop kernel: R10: 0000000000000000 R11: 0000000000000246 R12: 000055a1d022bdd0
Mar 24 14:08:44 desktop kernel: R13: 000000000000000a R14: 0000000000000000 R15: 0000000000000000
Mar 24 14:08:44 desktop kernel: 
Mar 24 14:08:44 desktop kernel: OOM killer enabled.
Mar 24 14:08:44 desktop kernel: Restarting tasks ... done.
Mar 24 14:08:44 desktop kernel: thermal thermal_zone2: failed to read out thermal zone (-61)
Mar 24 14:08:44 desktop kernel: video LNXVIDEO:00: Restoring backlight state
Mar 24 14:08:44 desktop kernel: PM: suspend exit
Mar 24 14:08:44 desktop kernel: audit: type=1130 audit(1616594924.188:86): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Mar 24 14:08:44 desktop audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Mar 24 14:08:44 desktop systemd-sleep[29781]: Failed to suspend system. System resumed again: Device or resource busy
Mar 24 14:08:44 desktop systemd[1]: systemd-suspend.service: Main process exited, code=exited, status=1/FAILURE
Mar 24 14:08:44 desktop systemd[1]: systemd-suspend.service: Failed with result 'exit-code'.
Mar 24 14:08:44 desktop systemd[1]: Failed to start Suspend.
Mar 24 14:08:44 desktop systemd[1]: Dependency failed for Suspend.
Mar 24 14:08:44 desktop systemd[1]: suspend.target: Job suspend.target/start failed with result 'dependency'.
Mar 24 14:08:44 desktop systemd[1]: Stopped target Sleep.
Mar 24 14:08:44 desktop systemd-logind[317]: Operation 'sleep' finished.

Below I've tried to show the relevant entries during the various sleep and wake up events, but see no errors during that time. These will include me logging in via ssh to generate the logs. Interestingly it seems I am able to ssh in after the monitor turns off which suggests the computer isn't actually suspending.

Dmesg on automatic suspend (monitor turns off):

[   64.898967] audit: type=1106 audit(1616600187.446:57): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[   64.899182] audit: type=1104 audit(1616600187.450:58): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
[  698.996443] audit: type=1109 audit(1616600821.546:59): pid=4004 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:bad_ident grantors=? acct="?" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=failed'
[  712.538724] audit: type=1100 audit(1616600835.086:60): pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
[  712.542019] audit: type=1101 audit(1616600835.090:61): pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
[  712.544167] audit: type=1103 audit(1616600835.093:62): pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
[  712.544353] audit: type=1006 audit(1616600835.093:63): pid=4094 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=3 res=1
[  712.544363] audit: type=1300 audit(1616600835.093:63): arch=c000003e syscall=1 success=yes exit=4 a0=3 a1=7ffe1fcf1730 a2=4 a3=3e8 items=0 ppid=341 pid=4094 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=3 comm="sshd" exe="/usr/bin/sshd" key=(null)
[  712.544370] audit: type=1327 audit(1616600835.093:63): proctitle=737368643A207368616B205B707269765D
[  712.563130] audit: type=1105 audit(1616600835.113:64): pid=4094 uid=0 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
[  712.566260] audit: type=1103 audit(1616600835.116:65): pid=4096 uid=0 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
[  713.344673] audit: type=1109 audit(1616600835.893:66): pid=4063 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:bad_ident grantors=? acct="?" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=failed'
[  742.512968] audit: type=1100 audit(1616600865.063:67): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  742.514801] audit: type=1101 audit(1616600865.063:68): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  742.515864] audit: type=1110 audit(1616600865.066:69): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  742.516171] audit: type=1105 audit(1616600865.066:70): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

Dmesg on wakeup (monitor turns on with a black screen, keyboard locks):

[  742.536314] audit: type=1106 audit(1616600865.086:71): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  742.536462] audit: type=1104 audit(1616600865.086:72): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  760.180229] audit: type=1101 audit(1616600882.730:73): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  760.181385] audit: type=1110 audit(1616600882.730:74): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  760.181715] audit: type=1105 audit(1616600882.730:75): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  760.755181] audit: type=1106 audit(1616600883.303:76): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  760.755542] audit: type=1104 audit(1616600883.306:77): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  793.014030] audit: type=1101 audit(1616600915.563:78): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  793.014968] audit: type=1110 audit(1616600915.563:79): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
[  793.015278] audit: type=1105 audit(1616600915.563:80): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

journalctl --system on suspend:

Mar 24 15:36:22 desktop audit[941]: USER_END pid=941 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:22 desktop audit[941]: CRED_DISP pid=941 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:22 desktop kernel: audit: type=1106 audit(1616600182.086:52): pid=941 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:22 desktop kernel: audit: type=1104 audit(1616600182.086:53): pid=941 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop dbus-daemon[318]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.11' (uid=0 pid=1020 comm="sudo dmesg ")
Mar 24 15:36:27 desktop dbus-daemon[318]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:36:27 desktop audit[1020]: USER_ACCT pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop audit[1020]: CRED_REFR pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop audit[1020]: USER_START pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop kernel: audit: type=1101 audit(1616600187.396:54): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop kernel: audit: type=1110 audit(1616600187.396:55): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop kernel: audit: type=1105 audit(1616600187.396:56): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop audit[1020]: USER_END pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop audit[1020]: CRED_DISP pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop kernel: audit: type=1106 audit(1616600187.446:57): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:36:27 desktop kernel: audit: type=1104 audit(1616600187.450:58): pid=1020 uid=1000 auid=1000 ses=1 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/0 res=success'
Mar 24 15:42:15 desktop systemd[1]: cifs-nas-arch\x2drepos.mount: Succeeded.
Mar 24 15:47:01 desktop sshd[4004]: Connection closed by authenticating user archuser x.x.x.x port 51462 [preauth]
Mar 24 15:47:01 desktop audit[4004]: USER_ERR pid=4004 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:bad_ident grantors=? acct="?" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=failed'
Mar 24 15:47:01 desktop kernel: audit: type=1109 audit(1616600821.546:59): pid=4004 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:bad_ident grantors=? acct="?" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=failed'
Mar 24 15:47:15 desktop audit[4094]: USER_AUTH pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop kernel: audit: type=1100 audit(1616600835.086:60): pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:authentication grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop dbus-daemon[318]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.12' (uid=0 pid=4094 comm="sshd: archuser [priv]   ")
Mar 24 15:47:15 desktop dbus-daemon[318]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:47:15 desktop sshd[4094]: pam_systemd_home(sshd:account): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:47:15 desktop kernel: audit: type=1101 audit(1616600835.090:61): pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop audit[4094]: USER_ACCT pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop sshd[4094]: Accepted password for archuser from x.x.x.x port 51466 ssh2
Mar 24 15:47:15 desktop audit[4094]: CRED_ACQ pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop audit[4094]: SYSCALL arch=c000003e syscall=1 success=yes exit=4 a0=3 a1=7ffe1fcf1730 a2=4 a3=3e8 items=0 ppid=341 pid=4094 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=3 comm="sshd" exe="/usr/bin/sshd" key=(null)
Mar 24 15:47:15 desktop audit: PROCTITLE proctitle=737368643A207368616B205B707269765D
Mar 24 15:47:15 desktop sshd[4094]: pam_unix(sshd:session): session opened for user archuser(uid=1000) by (uid=0)
Mar 24 15:47:15 desktop kernel: audit: type=1103 audit(1616600835.093:62): pid=4094 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:setcred grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop kernel: audit: type=1006 audit(1616600835.093:63): pid=4094 uid=0 old-auid=4294967295 auid=1000 tty=(none) old-ses=4294967295 ses=3 res=1
Mar 24 15:47:15 desktop kernel: audit: type=1300 audit(1616600835.093:63): arch=c000003e syscall=1 success=yes exit=4 a0=3 a1=7ffe1fcf1730 a2=4 a3=3e8 items=0 ppid=341 pid=4094 auid=1000 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=3 comm="sshd" exe="/usr/bin/sshd" key=(null)
Mar 24 15:47:15 desktop kernel: audit: type=1327 audit(1616600835.093:63): proctitle=737368643A207368616B205B707269765D
Mar 24 15:47:15 desktop systemd-logind[320]: New session 3 of user archuser.
Mar 24 15:47:15 desktop systemd[1]: Started Session 3 of user archuser.
Mar 24 15:47:15 desktop sshd[4094]: pam_env(sshd:session): deprecated reading of user environment enabled
Mar 24 15:47:15 desktop audit[4094]: USER_START pid=4094 uid=0 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop kernel: audit: type=1105 audit(1616600835.113:64): pid=4094 uid=0 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_loginuid,pam_keyinit,pam_limits,pam_unix,pam_permit,pam_mail,pam_systemd,pam_env acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop audit[4096]: CRED_ACQ pid=4096 uid=0 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop kernel: audit: type=1103 audit(1616600835.116:65): pid=4096 uid=0 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_shells,pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=success'
Mar 24 15:47:15 desktop sshd[4063]: Connection closed by authenticating user archuser x.x.x.x port 51464 [preauth]
Mar 24 15:47:15 desktop kernel: audit: type=1109 audit(1616600835.893:66): pid=4063 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:bad_ident grantors=? acct="?" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=failed'
Mar 24 15:47:15 desktop audit[4063]: USER_ERR pid=4063 uid=0 auid=4294967295 ses=4294967295 msg='op=PAM:bad_ident grantors=? acct="?" exe="/usr/bin/sshd" hostname=x.x.x.x addr=x.x.x.x terminal=ssh res=failed'
Mar 24 15:47:45 desktop audit[4362]: USER_AUTH pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop dbus-daemon[318]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.13' (uid=0 pid=4362 comm="sudo dmesg ")
Mar 24 15:47:45 desktop dbus-daemon[318]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:47:45 desktop audit[4362]: USER_ACCT pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop kernel: audit: type=1100 audit(1616600865.063:67): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop kernel: audit: type=1101 audit(1616600865.063:68): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop kernel: audit: type=1110 audit(1616600865.066:69): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop kernel: audit: type=1105 audit(1616600865.066:70): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop kernel: audit: type=1106 audit(1616600865.086:71): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop kernel: audit: type=1104 audit(1616600865.086:72): pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop audit[4362]: CRED_REFR pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop audit[4362]: USER_START pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop audit[4362]: USER_END pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:47:45 desktop audit[4362]: CRED_DISP pid=4362 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:02 desktop dbus-daemon[318]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.14' (uid=0 pid=4505 comm="sudo journalctl --system ")
Mar 24 15:48:02 desktop dbus-daemon[318]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:48:02 desktop audit[4505]: USER_ACCT pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:02 desktop audit[4505]: CRED_REFR pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:02 desktop kernel: audit: type=1101 audit(1616600882.730:73): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:02 desktop kernel: audit: type=1110 audit(1616600882.730:74): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:02 desktop kernel: audit: type=1105 audit(1616600882.730:75): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:02 desktop audit[4505]: USER_START pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

journalctl --system on wakeup:

Mar 24 15:48:03 desktop audit[4505]: USER_END pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:03 desktop kernel: audit: type=1106 audit(1616600883.303:76): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:03 desktop kernel: audit: type=1104 audit(1616600883.306:77): pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:03 desktop audit[4505]: CRED_DISP pid=4505 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop dbus-daemon[318]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.15' (uid=0 pid=4711 comm="sudo dmesg ")
Mar 24 15:48:35 desktop dbus-daemon[318]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:48:35 desktop audit[4711]: USER_ACCT pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop audit[4711]: CRED_REFR pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop kernel: audit: type=1101 audit(1616600915.563:78): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop kernel: audit: type=1110 audit(1616600915.563:79): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop kernel: audit: type=1105 audit(1616600915.563:80): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop audit[4711]: USER_START pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop audit[4711]: USER_END pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop audit[4711]: CRED_DISP pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop kernel: audit: type=1106 audit(1616600915.586:81): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:35 desktop kernel: audit: type=1104 audit(1616600915.586:82): pid=4711 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:46 desktop dbus-daemon[318]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.16' (uid=0 pid=4810 comm="sudo journalctl --system ")
Mar 24 15:48:46 desktop dbus-daemon[318]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Mar 24 15:48:46 desktop audit[4810]: USER_ACCT pid=4810 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:46 desktop audit[4810]: CRED_REFR pid=4810 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:46 desktop audit[4810]: USER_START pid=4810 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:46 desktop kernel: audit: type=1101 audit(1616600926.640:83): pid=4810 uid=1000 auid=1000 ses=3 msg='op=PAM:accounting grantors=pam_unix,pam_permit,pam_time acct="archuser" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:46 desktop kernel: audit: type=1110 audit(1616600926.640:84): pid=4810 uid=1000 auid=1000 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'
Mar 24 15:48:46 desktop kernel: audit: type=1105 audit(1616600926.640:85): pid=4810 uid=1000 auid=1000 ses=3 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/1 res=success'

Offline

#4 2021-03-25 13:16:38

seth
Member
Registered: 2012-09-03
Posts: 50,924

Re: Display hangs after resume from sleep, but SSH still works

only generates if I run systemctl suspend, and not when the computer monitor turns off by itself during an xsession

Is the latter supposed to indicate a problem besides the one in the OP (which seemed S3 only)?

Mar 24 14:08:24 desktop kernel:  intel_enable_crtc+0x59/0x70 [i915]

A crash here sounds really bad - and like it would cause the perceived symptoms.

The output is connected via HDMI?
Can you try a vga connection?

Offline

#5 2021-03-25 14:35:23

sshaikh
Member
Registered: 2019-07-03
Posts: 53

Re: Display hangs after resume from sleep, but SSH still works

I offered the information about systemd auto suspend as another data point. To clarify:

Case 1: boot computer to tty (I don't use a display manager). The system doesn't sleep automatically so I tried to "sumulate" it using systemd as I thought it was the same thing. I cannot ssh, and hitting a key wakes monitor to blank screen.

Case 2: boot and run startx to load i3. Wait 5 mins or so until the monitor turns off. While in this state, I can still ssh in via another device. Hitting a key will wake monitor to a blank screen.

Case 3: boot and run startx to load i3. Use systemd suspend to force a sleep. I see the same behaviour as in case 1.

The journal produces different messages in all three cases, but nevertheless I suspect the underlying issue is the same.

Unfortunately my monitor only has HDMI and DP inputs. Even more unfortunately, the computer only has HDMI, although it does have two so I may try the second port. I can also try with a different HDMI monitor/TV.

I'll report back with the results.

Offline

#6 2021-03-25 14:54:45

sshaikh
Member
Registered: 2019-07-03
Posts: 53

Re: Display hangs after resume from sleep, but SSH still works

Searching for intel_enable_crtc returns a few relevant hits - same symptoms and similar hardware, so plenty to look through. I'll report back my findings.

Offline

#7 2021-03-25 15:17:46

seth
Member
Registered: 2012-09-03
Posts: 50,924

Re: Display hangs after resume from sleep, but SSH still works

(2) is just DPMS while (1) & (3) are S3 resume issues - the system doesn't wake up (hence you can't ssh either)

https://wiki.archlinux.org/index.php/Intel_graphics - try

i915.enable_dc=0 i915.enable_psr=0 intel_idle.max_cstate=1 i915.enable_fbc=0 iommu=soft

For general S3 troubleshooting see
https://01.org/blogs/rzhang/2015/best-p … ate-issues

ceterum censeo: 3rd link in my signature

Offline

#8 2021-03-25 18:16:48

sshaikh
Member
Registered: 2019-07-03
Posts: 53

Re: Display hangs after resume from sleep, but SSH still works

Unfortunately those kernel params didn't help.

Fastboot is disabled.

Using latest kernel as of today (5.11.9).

I'm now going through the search hits.

Offline

#9 2021-03-25 19:00:32

sshaikh
Member
Registered: 2019-07-03
Posts: 53

Re: Display hangs after resume from sleep, but SSH still works

The two hdmi related kernel parameters in the following post fixes Case 2 (DPMS):

https://bbs.archlinux.org/viewtopic.php … 1#p1958671

I still cannot suspend but this is progress.

The related bugs I found are:

https://bugs.archlinux.org/task/69190
https://gitlab.freedesktop.org/drm/intel/-/issues/2623
https://gitlab.freedesktop.org/drm/intel/-/issues/2883

Thanks for pointing out the intel_enable_crtc crash!

Offline

Board footer

Powered by FluxBB