HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Questions about other topics - please check if your question fits better in another category before posting here
Forum rules
Before you post read how to get help. Topics in this forum are automatically closed 6 months after creation.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Another thought - is it possible to replace the current kernel version of the radeon driver with an earlier version? its really the only bit causing problems. Don't have any idea how to do that or what other kinds of things it might break so there is that.
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

quietlife wrote: Wed Feb 22, 2023 7:16 pm Another thought - is it possible to replace the current kernel version of the radeon driver with an earlier version?
No. The drivers do not work in isolation from the rest of the system. They have to match with the X Server and Mesa being used.
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Too bad. I'll try hibernate like you said and see if that works
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Just thought it would be worth to mention something i noticed over the last week or so.

The longer the computer is left suspended the more likely it is able to resume. Seems as if anything over 8-10 hours and it will either resume normally, or there will be some corruption in the graphics (blockiness of the desktop, mouse pointer a big square etc) and slight delay (10-20 seconds) before becoming usable.

Anything less than this kind of time suspended and the crashing behavior is the same as always and a hard reset is needed.

Weird!
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

quietlife wrote: Tue Mar 14, 2023 8:28 am Just thought it would be worth to mention something i noticed over the last week or so.

The longer the computer is left suspended the more likely it is able to resume. Seems as if anything over 8-10 hours and it will either resume normally, or there will be some corruption in the graphics (blockiness of the desktop, mouse pointer a big square etc) and slight delay (10-20 seconds) before becoming usable.
When that happens, run the following in a terminal after it resumes. Maybe there will be a clue as to what is happening.

Code: Select all

dmesg -T | tail -n 200
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

sure thing SMG. will see what happens in the morning.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

So the laptop managed a perfect resume from suspend this morning after being suspended for maybe 11-12 hours.
Here's the output from the command:

Code: Select all

~$ dmesg -T | tail -n 200
[Wed Mar 15 21:32:02 2023] EXT4-fs (sda7): re-mounted. Opts: errors=remount-ro. Quota mode: none.
[Wed Mar 15 21:32:02 2023] systemd-journald[310]: Received client request to flush runtime journal.
[Wed Mar 15 21:32:02 2023] lp: driver loaded but no devices found
[Wed Mar 15 21:32:02 2023] ppdev: user-space parallel port driver
[Wed Mar 15 21:32:02 2023] systemd-journald[310]: File /var/log/journal/8283e071490343188007b10cb544d01b/system.journal corrupted or uncleanly shut down, renaming and replacing.
[Wed Mar 15 21:32:04 2023] hp_accel: laptop model unknown, using default axes configuration
[Wed Mar 15 21:32:04 2023] lis3lv02d: 8 bits sensor found
[Wed Mar 15 21:32:04 2023] input: ST LIS3LV02DL Accelerometer as /devices/platform/lis3lv02d/input/input10
[Wed Mar 15 21:32:04 2023] k8temp 0000:00:18.3: Temperature readouts might be wrong - check erratum #141
[Wed Mar 15 21:32:05 2023] input: HP WMI hotkeys as /devices/virtual/input/input11
[Wed Mar 15 21:32:05 2023] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[Wed Mar 15 21:32:05 2023] mc: Linux media interface: v0.10
[Wed Mar 15 21:32:05 2023] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[Wed Mar 15 21:32:06 2023] videodev: Linux video capture interface: v2.00
[Wed Mar 15 21:32:06 2023] kvm: disabled by bios
[Wed Mar 15 21:32:06 2023] kvm: disabled by bios
[Wed Mar 15 21:32:06 2023] MCE: In-kernel MCE decoding enabled.
[Wed Mar 15 21:32:06 2023] usb 1-6: Found UVC 1.00 device hp webcam (0603:8124)
[Wed Mar 15 21:32:06 2023] input: hp webcam: hp webcam as /devices/pci0000:00/0000:00:12.2/usb1/1-6/1-6:1.0/input/input12
[Wed Mar 15 21:32:06 2023] usbcore: registered new interface driver uvcvideo
[Wed Mar 15 21:32:06 2023] ath: EEPROM regdomain: 0x69
[Wed Mar 15 21:32:06 2023] ath: EEPROM indicates we should expect a direct regpair map
[Wed Mar 15 21:32:06 2023] ath: Country alpha2 being used: 00
[Wed Mar 15 21:32:06 2023] ath: Regpair used: 0x69
[Wed Mar 15 21:32:06 2023] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[Wed Mar 15 21:32:06 2023] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xffff97ab00660000, irq=17
[Wed Mar 15 21:32:06 2023] ath9k 0000:08:00.0 wlo1: renamed from wlan0
[Wed Mar 15 21:32:06 2023] input: HDA ATI HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:05.1/sound/card1/input13
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0: autoconfig for 92HD81B1C5: line_outs=1 (0xd/0x0/0x0/0x0/0x0) type:speaker
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0:    hp_outs=1 (0xb/0x0/0x0/0x0/0x0)
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0:    mono: mono_out=0x0
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0:    inputs:
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0:      Internal Mic=0x11
[Wed Mar 15 21:32:06 2023] snd_hda_codec_idt hdaudioC0D0:      Mic=0xa
[Wed Mar 15 21:32:07 2023] input: HDA ATI SB Mic as /devices/pci0000:00/0000:00:14.2/sound/card0/input14
[Wed Mar 15 21:32:07 2023] input: HDA ATI SB Headphone as /devices/pci0000:00/0000:00:14.2/sound/card0/input15
[Wed Mar 15 21:32:08 2023] Adding 3926012k swap on /dev/sda6.  Priority:-2 extents:1 across:3926012k FS
[Wed Mar 15 21:32:09 2023] spl: loading out-of-tree module taints kernel.
[Wed Mar 15 21:32:09 2023] znvpair: module license 'CDDL' taints kernel.
[Wed Mar 15 21:32:09 2023] Disabling lock debugging due to kernel taint
[Wed Mar 15 21:32:11 2023] ZFS: Loaded module v2.1.4-0ubuntu0.1, ZFS pool version 5000, ZFS filesystem version 5
[Wed Mar 15 21:32:29 2023] EXT4-fs (sda5): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.468:2): apparmor="STATUS" operation="profile_load" profile="unconfined" name="lsb_release" pid=630 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.564:3): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe" pid=644 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.564:4): apparmor="STATUS" operation="profile_load" profile="unconfined" name="nvidia_modprobe//kmod" pid=644 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.796:5): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=646 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.796:6): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/NetworkManager/nm-dhcp-helper" pid=646 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.796:7): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/lib/connman/scripts/dhclient-script" pid=646 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.796:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/{,usr/}sbin/dhclient" pid=646 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.872:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="/usr/bin/man" pid=647 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.872:10): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_filter" pid=647 comm="apparmor_parser"
[Wed Mar 15 21:32:30 2023] audit: type=1400 audit(1678884353.872:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="man_groff" pid=647 comm="apparmor_parser"
[Wed Mar 15 21:32:37 2023] kauditd_printk_skb: 14 callbacks suppressed
[Wed Mar 15 21:32:37 2023] audit: type=1400 audit(1678884361.176:26): apparmor="DENIED" operation="open" profile="/usr/sbin/ntpd" name="/etc/ssl/openssl.cnf" pid=884 comm="ntpd" requested_mask="r" denied_mask="r" fsuid=0 ouid=0
[Wed Mar 15 21:32:40 2023] RTL8201CP Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC)
[Wed Mar 15 21:32:41 2023] r8169 0000:02:00.0 enp2s0: Link is Down
[Wed Mar 15 21:32:41 2023] audit: type=1400 audit(1678884364.672:27): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=857 comm="cupsd" capability=12  capname="net_admin"
[Wed Mar 15 21:32:47 2023] audit: type=1400 audit(1678884371.112:28): apparmor="DENIED" operation="capable" profile="/usr/sbin/cups-browsed" pid=953 comm="cups-browsed" capability=23  capname="sys_nice"
[Wed Mar 15 21:33:26 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Wed Mar 15 21:33:26 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Wed Mar 15 21:33:26 2023] wlo1: authenticated
[Wed Mar 15 21:33:26 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Wed Mar 15 21:33:26 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Wed Mar 15 21:33:26 2023] wlo1: associated
[Wed Mar 15 21:33:26 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Wed Mar 15 21:43:42 2023] perf: interrupt took too long (2518 > 2500), lowering kernel.perf_event_max_sample_rate to 79250
[Wed Mar 15 21:49:40 2023] perf: interrupt took too long (3159 > 3147), lowering kernel.perf_event_max_sample_rate to 63250
[Wed Mar 15 21:57:25 2023] perf: interrupt took too long (4076 > 3948), lowering kernel.perf_event_max_sample_rate to 49000
[Wed Mar 15 22:05:19 2023] perf: interrupt took too long (5096 > 5095), lowering kernel.perf_event_max_sample_rate to 39000
[Wed Mar 15 22:12:26 2023] perf: interrupt took too long (6437 > 6370), lowering kernel.perf_event_max_sample_rate to 31000
[Wed Mar 15 22:22:44 2023] perf: interrupt took too long (8128 > 8046), lowering kernel.perf_event_max_sample_rate to 24500
[Wed Mar 15 22:36:58 2023] [UFW BLOCK] IN=wlo1 OUT= MAC=00:03:7f:8e:12:b4:5e:7d:5e:05:38:37:08:00 SRC=131.234.220.231 DST=192.168.43.155 LEN=76 TOS=0x00 PREC=0x20 TTL=41 ID=30205 DF PROTO=UDP SPT=123 DPT=123 LEN=56 
[Wed Mar 15 22:46:13 2023] perf: interrupt took too long (10280 > 10160), lowering kernel.perf_event_max_sample_rate to 19250
[Wed Mar 15 23:36:46 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Wed Mar 15 23:36:46 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Wed Mar 15 23:36:46 2023] wlo1: authenticated
[Wed Mar 15 23:36:46 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Wed Mar 15 23:36:46 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Wed Mar 15 23:36:46 2023] wlo1: associated
[Wed Mar 15 23:36:46 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Thu Mar 16 00:18:19 2023] perf: interrupt took too long (13078 > 12850), lowering kernel.perf_event_max_sample_rate to 15250
[Thu Mar 16 00:38:37 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Thu Mar 16 00:38:37 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 00:38:37 2023] wlo1: authenticated
[Thu Mar 16 00:38:37 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 00:38:37 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Thu Mar 16 00:38:37 2023] wlo1: associated
[Thu Mar 16 00:38:37 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Thu Mar 16 01:14:39 2023] [UFW BLOCK] IN=wlo1 OUT= MAC=00:03:7f:8e:12:b4:5e:7d:5e:05:38:37:08:00 SRC=199.182.221.110 DST=192.168.43.155 LEN=76 TOS=0x00 PREC=0x00 TTL=50 ID=312 DF PROTO=UDP SPT=123 DPT=123 LEN=56 
[Thu Mar 16 01:51:16 2023] usb 1-2: new high-speed USB device number 4 using ehci-pci
[Thu Mar 16 01:51:16 2023] usb 1-2: New USB device found, idVendor=0e8d, idProduct=2008, bcdDevice= 2.23
[Thu Mar 16 01:51:16 2023] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[Thu Mar 16 01:51:16 2023] usb 1-2: Product: U304AA
[Thu Mar 16 01:51:16 2023] usb 1-2: Manufacturer: TINNO
[Thu Mar 16 01:51:16 2023] usb 1-2: SerialNumber: GMEYDEWSIBAI4HGM
[Thu Mar 16 01:55:09 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Thu Mar 16 01:55:09 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 01:55:09 2023] wlo1: authenticated
[Thu Mar 16 01:55:09 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 01:55:09 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Thu Mar 16 01:55:09 2023] wlo1: associated
[Thu Mar 16 01:55:09 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Thu Mar 16 02:01:10 2023] perf: interrupt took too long (16352 > 16347), lowering kernel.perf_event_max_sample_rate to 12000
[Thu Mar 16 02:46:47 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Thu Mar 16 02:46:47 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 02:46:47 2023] wlo1: authenticated
[Thu Mar 16 02:46:47 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 02:46:47 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Thu Mar 16 02:46:47 2023] wlo1: associated
[Thu Mar 16 02:46:47 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Thu Mar 16 04:17:21 2023] usb 1-2: USB disconnect, device number 4
[Thu Mar 16 05:28:51 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Thu Mar 16 05:28:51 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 05:28:51 2023] wlo1: authenticated
[Thu Mar 16 05:28:51 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 05:28:51 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Thu Mar 16 05:28:51 2023] wlo1: associated
[Thu Mar 16 05:28:51 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Thu Mar 16 08:26:07 2023] perf: interrupt took too long (20537 > 20440), lowering kernel.perf_event_max_sample_rate to 9500
[Thu Mar 16 08:35:52 2023] wlo1: deauthenticated from 5c:7d:5e:05:38:37 (Reason: 1=UNSPECIFIED)
[Thu Mar 16 08:36:31 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Thu Mar 16 08:36:31 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 08:36:31 2023] wlo1: authenticated
[Thu Mar 16 08:36:31 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 08:36:31 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Thu Mar 16 08:36:31 2023] wlo1: associated
[Thu Mar 16 08:36:32 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
[Thu Mar 16 08:36:42 2023] [UFW BLOCK] IN=wlo1 OUT= MAC=00:03:7f:8e:12:b4:5e:7d:5e:05:38:37:08:00 SRC=104.19.129.108 DST=192.168.43.155 LEN=52 TOS=0x00 PREC=0x00 TTL=122 ID=17 PROTO=TCP SPT=443 DPT=40992 WINDOW=1279 RES=0x00 ACK URGP=0 
[Thu Mar 16 08:54:20 2023] usb 1-2: new high-speed USB device number 5 using ehci-pci
[Thu Mar 16 08:54:21 2023] usb 1-2: New USB device found, idVendor=0e8d, idProduct=2008, bcdDevice= 2.23
[Thu Mar 16 08:54:21 2023] usb 1-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[Thu Mar 16 08:54:21 2023] usb 1-2: Product: U304AA
[Thu Mar 16 08:54:21 2023] usb 1-2: Manufacturer: TINNO
[Thu Mar 16 08:54:21 2023] usb 1-2: SerialNumber: GMEYDEWSIBAI4HGM
[Thu Mar 16 09:02:00 2023] wlo1: deauthenticating from 5c:7d:5e:05:38:37 by local choice (Reason: 3=DEAUTH_LEAVING)
[Thu Mar 16 09:02:01 2023] PM: suspend entry (deep)
[Thu Mar 16 09:02:01 2023] Filesystems sync: 0.446 seconds
[Thu Mar 16 09:02:02 2023] Freezing user space processes ... (elapsed 0.002 seconds) done.
[Thu Mar 16 09:02:02 2023] OOM killer disabled.
[Thu Mar 16 09:02:02 2023] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[Thu Mar 16 09:02:02 2023] printk: Suspending console(s) (use no_console_suspend to debug)
[Thu Mar 16 09:02:02 2023] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[Thu Mar 16 09:02:02 2023] sd 0:0:0:0: [sda] Stopping disk
[Thu Mar 16 09:02:03 2023] ACPI: EC: interrupt blocked
[Thu Mar 16 09:02:03 2023] ACPI: PM: Preparing to enter system sleep state S3
[Thu Mar 16 09:02:03 2023] ACPI: EC: event blocked
[Thu Mar 16 09:02:03 2023] ACPI: EC: EC stopped
[Thu Mar 16 09:02:03 2023] ACPI: PM: Saving platform NVS memory
[Thu Mar 16 09:02:03 2023] Disabling non-boot CPUs ...
[Thu Mar 16 09:02:03 2023] smpboot: CPU 1 is now offline
[Thu Mar 16 09:02:03 2023] ACPI: PM: Low-level resume complete
[Thu Mar 16 09:02:03 2023] ACPI: EC: EC started
[Thu Mar 16 09:02:03 2023] ACPI: PM: Restoring platform NVS memory
[Thu Mar 16 09:02:03 2023] PCI-DMA: Resuming GART IOMMU
[Thu Mar 16 09:02:03 2023] PCI-DMA: Restoring GART aperture settings
[Thu Mar 16 09:02:03 2023] Enabling non-boot CPUs ...
[Thu Mar 16 09:02:03 2023] x86: Booting SMP configuration:
[Thu Mar 16 09:02:03 2023] smpboot: Booting Node 0 Processor 1 APIC 0x1
[Thu Mar 16 09:02:03 2023] process: Switch to broadcast mode on CPU1
[Thu Mar 16 09:02:03 2023] CPU1 is up
[Thu Mar 16 09:02:03 2023] ACPI: PM: Waking up from system sleep state S3
[Thu Mar 16 09:02:03 2023] ACPI: EC: interrupt unblocked
[Thu Mar 16 09:02:03 2023] ACPI: EC: event unblocked
[Thu Mar 16 09:02:03 2023] sd 0:0:0:0: [sda] Starting disk
[Thu Mar 16 09:02:03 2023] [drm] PCIE GART of 512M enabled (table at 0x00000000C0146000).
[Thu Mar 16 09:02:03 2023] radeon 0000:01:05.0: WB enabled
[Thu Mar 16 09:02:03 2023] radeon 0000:01:05.0: fence driver on ring 0 use gpu addr 0x00000000a0000c00
[Thu Mar 16 09:02:03 2023] radeon 0000:01:05.0: fence driver on ring 5 use gpu addr 0x00000000c0056038
[Thu Mar 16 09:02:03 2023] debugfs: File 'radeon_ring_gfx' in directory '0' already present!
[Thu Mar 16 09:02:04 2023] [drm] ring test on 0 succeeded in 0 usecs
[Thu Mar 16 09:02:04 2023] debugfs: File 'radeon_ring_uvd' in directory '0' already present!
[Thu Mar 16 09:02:04 2023] [drm] ring test on 5 succeeded in 1 usecs
[Thu Mar 16 09:02:04 2023] [drm] UVD initialized successfully.
[Thu Mar 16 09:02:04 2023] [drm] ib test on ring 0 succeeded in 0 usecs
[Thu Mar 16 09:02:04 2023] usb 1-6: reset high-speed USB device number 3 using ehci-pci
[Thu Mar 16 09:02:04 2023] [drm] ib test on ring 5 succeeded
[Thu Mar 16 09:02:06 2023] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[Thu Mar 16 09:02:06 2023] ata1.00: ATA Identify Device Log not supported
[Thu Mar 16 09:02:06 2023] ata1.00: ATA Identify Device Log not supported
[Thu Mar 16 09:02:06 2023] ata1.00: configured for UDMA/133
[Thu Mar 16 09:02:09 2023] [drm:atom_op_jump [radeon]] *ERROR* atombios stuck in loop for more than 5secs aborting
[Thu Mar 16 09:02:09 2023] [drm:atom_execute_table_locked [radeon]] *ERROR* atombios stuck executing E692 (len 498, WS 0, PS 4) @ 0xE6D3
[Thu Mar 16 09:02:11 2023] OOM killer enabled.
[Thu Mar 16 09:02:11 2023] Restarting tasks ... 
[Thu Mar 16 09:02:11 2023] usb 1-2: USB disconnect, device number 5
[Thu Mar 16 09:02:11 2023] done.
[Thu Mar 16 09:02:11 2023] video LNXVIDEO:00: Restoring backlight state
[Thu Mar 16 09:02:11 2023] PM: suspend exit
[Thu Mar 16 09:02:12 2023] RTL8201CP Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC)
[Thu Mar 16 09:02:12 2023] r8169 0000:02:00.0 enp2s0: Link is Down
[Thu Mar 16 09:02:14 2023] audit: type=1400 audit(1678968135.396:29): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=17196 comm="cupsd" capability=12  capname="net_admin"
[Thu Mar 16 09:02:14 2023] audit: type=1400 audit(1678968135.576:30): apparmor="DENIED" operation="capable" profile="/usr/sbin/cups-browsed" pid=17197 comm="cups-browsed" capability=23  capname="sys_nice"
[Thu Mar 16 09:02:16 2023] wlo1: authenticate with 5c:7d:5e:05:38:37
[Thu Mar 16 09:02:16 2023] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 09:02:16 2023] wlo1: authenticated
[Thu Mar 16 09:02:16 2023] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
[Thu Mar 16 09:02:16 2023] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
[Thu Mar 16 09:02:16 2023] wlo1: associated
[Thu Mar 16 09:02:17 2023] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

quietlife wrote: Thu Mar 16, 2023 8:06 am So the laptop managed a perfect resume from suspend this morning after being suspended for maybe 11-12 hours.
The data you posted appears to indicate the computer was suspended for about 8 minutes.

(Keep in mind that sometimes if one suspends a lot, the dmesg clock can be off a bit with its relation to actual time.)
The info starts at Wed Mar 15 21:32:02 2023
and ends at Thu Mar 16 09:02:17 2023
Which is span of approximately 11.5 hours.

Based on the messages I normally see around time gaps, the suspension came into place approximately here:

Code: Select all

[Thu Mar 16 08:54:21 2023] usb 1-2: SerialNumber: GMEYDEWSIBAI4HGM
[Thu Mar 16 09:02:00 2023] wlo1: deauthenticating from 5c:7d:5e:05:38:37 by local choice (Reason: 3=DEAUTH_LEAVING)
[Thu Mar 16 09:02:01 2023] PM: suspend entry (deep)
...
[Thu Mar 16 09:02:11 2023] PM: suspend exit
Usually I see a message for wireless going away because that is one of the last steps before a computer goes to sleep. I then look back for a gap in time prior to that message. That is how I came up with the 8 minutes. Then all the message from the suspension period print to dmesg when the computer wakes ups so it is common to see all the "sleep" messages with essentially the same time.

To me, a lot of the initial messages look like normal start-up messages.

So either there is a clock problem somewhere or something really weird is happening here with the feedback you are getting that makes you believe your computer is suspended.
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Yeah i noticed the time thing. When the computer does manage to resume from suspend i notice that the taskbar clock takes several seconds to update to the actual time instead of the time when it was placed into suspend.

All i can say about if its suspended or not is that the cpu fan stops, screen turns off, HDD spins down and the power light starts slowly blinking as it always has when suspended. But it is interesting that it can resume only after this kind of time in suspend. How it could be related to the clock is very much a mystery to me.
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

Laptops don't usually have a Wake On LAN setting, but see if yours has one.
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Didn't see anything in the BIOS and it was set to default in the network config in Mint.
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

quietlife wrote: Thu Mar 16, 2023 7:06 pmAll i can say about if its suspended or not is that the cpu fan stops, screen turns off, HDD spins down and the power light starts slowly blinking as it always has when suspended. But it is interesting that it can resume only after this kind of time in suspend. How it could be related to the clock is very much a mystery to me.
I did some more searching which indicates the time will be wrong if one hibernates or suspends and one should check syslog. You could probably also run a modified version of command I gave you at the very beginning of the topic after a long suspend and we could see if the times are any different.

Code: Select all

journalctl -rb | nc termbin.com 9999
From a hardware standpoint it would not seem to make sense the computer can resume from a longer suspend and not a shorter one unless, perhaps, something is overheating and the longer time lets it cool down?
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Hi SMG,

Ran the command you gave and the given url is : https://termbin.com/u3c4q

The following is taken from the syslog file in var/log. I began the selection just before the time when it started to suspend (i think).

Code: Select all

Mar 21 20:50:52 j NetworkManager[800]: <info>  [1679442652.8174] dhcp4 (wlo1): state changed new lease, address=192.168.43.155
Mar 21 20:55:42 j dbus-daemon[1108]: [session uid=1000 pid=1108] Activating service name='org.freedesktop.thumbnails.Thumbnailer1' requested by ':1.20' (uid=1000 pid=1386 comm="Thunar --daemon " label="unconfined")
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer xapp-epub-thumbnailer -i %i -o %o -s %s
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer xreader-thumbnailer -s %s %u %o
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer xapp-appimage-thumbnailer -i %i -o %o -s %s
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer xapp-mp3-thumbnailer -i %i -o %o -s %s
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer ffmpegthumbnailer -i %i -o %o -s %s -f
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer xapp-raw-thumbnailer -i %i -o %o -s %s
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer gsf-office-thumbnailer -i %i -o %o -s %s
Mar 21 20:55:43 j org.freedesktop.thumbnails.Thumbnailer1[10347]: Registered thumbnailer gnome-thumbnail-font --size %s %u %o
Mar 21 20:55:43 j dbus-daemon[1108]: [session uid=1000 pid=1108] Successfully activated service 'org.freedesktop.thumbnails.Thumbnailer1'
Mar 21 20:56:28 j kernel: [45020.751479] usb 1-2: USB disconnect, device number 5
Mar 21 20:56:28 j gvfsd[10149]: PTP: reading event an error 0x05 occurred
Mar 21 20:56:29 j gvfsd[10149]: Device 0 (VID=0e8d and PID=2008) is a MediaTek Inc MT65xx.
Mar 21 20:56:29 j gvfsd[10149]: Android device detected, assigning default bug flags
Mar 21 20:56:29 j gvfsd[10149]: Received event PTP_EC_ObjectInfoChanged in session 1
Mar 21 20:56:29 j gvfsd[10149]: Received event PTP_EC_DevicePropChanged in session 1
Mar 21 20:56:29 j gvfsd[10149]: message repeated 8 times: [ Received event PTP_EC_DevicePropChanged in session 1]
Mar 21 20:56:29 j gvfsd[10149]: Received event PTP_EC_ObjectInfoChanged in session 1
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.2795] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.2981] device (enp2s0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 21 20:56:59 j ModemManager[870]: <info>  [sleep-monitor] system is about to suspend
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.3300] device (p2p-dev-wlo1): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.3355] manager: NetworkManager state is now ASLEEP
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.3450] device (wlo1): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Mar 21 20:56:59 j dbus-daemon[724]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=800 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Mar 21 20:56:59 j systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 21 20:56:59 j dbus-daemon[724]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 21 20:56:59 j systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 21 20:56:59 j kernel: [45052.009136] wlo1: deauthenticating from 5c:7d:5e:05:38:37 by local choice (Reason: 3=DEAUTH_LEAVING)
Mar 21 20:56:59 j wpa_supplicant[746]: wlo1: CTRL-EVENT-DISCONNECTED bssid=5c:7d:5e:05:38:37 reason=3 locally_generated=1
Mar 21 20:56:59 j wpa_supplicant[746]: wlo1: CTRL-EVENT-DSCP-POLICY clear_all
Mar 21 20:56:59 j wpa_supplicant[746]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.8006] device (wlo1): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Mar 21 20:56:59 j avahi-daemon[703]: Withdrawing address record for fe80::8aa:6292:4754:6924 on wlo1.
Mar 21 20:56:59 j avahi-daemon[703]: Leaving mDNS multicast group on interface wlo1.IPv6 with address fe80::8aa:6292:4754:6924.
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.8042] dhcp4 (wlo1): canceled DHCP transaction
Mar 21 20:56:59 j avahi-daemon[703]: Interface wlo1.IPv6 no longer relevant for mDNS.
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.8043] dhcp4 (wlo1): activation: beginning transaction (timeout in 45 seconds)
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.8045] dhcp4 (wlo1): state changed no lease
Mar 21 20:56:59 j avahi-daemon[703]: Withdrawing address record for 192.168.43.155 on wlo1.
Mar 21 20:56:59 j avahi-daemon[703]: Leaving mDNS multicast group on interface wlo1.IPv4 with address 192.168.43.155.
Mar 21 20:56:59 j avahi-daemon[703]: Interface wlo1.IPv4 no longer relevant for mDNS.
Mar 21 20:56:59 j systemd-resolved[691]: wlo1: Bus client set default route setting: no
Mar 21 20:56:59 j systemd-resolved[691]: wlo1: Bus client reset DNS server list.
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.8554] device (wlo1): supplicant interface state: completed -> disconnected
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.8569] device (wlo1): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 21 20:56:59 j wpa_supplicant[746]: wlo1: CTRL-EVENT-DSCP-POLICY clear_all
Mar 21 20:56:59 j systemd[1]: Reached target Sleep.
Mar 21 20:56:59 j systemd[1]: Starting Record successful boot for GRUB...
Mar 21 20:56:59 j systemd[1]: Starting System Suspend...
Mar 21 20:56:59 j wpa_supplicant[746]: wlo1: CTRL-EVENT-DSCP-POLICY clear_all
Mar 21 20:56:59 j wpa_supplicant[746]: nl80211: deinit ifname=wlo1 disabled_11b_rates=0
Mar 21 20:57:00 j systemd[1]: grub-common.service: Deactivated successfully.
Mar 21 20:57:00 j systemd[1]: Finished Record successful boot for GRUB.
Mar 21 20:57:00 j systemd[1]: Starting GRUB failed boot detection...
Mar 21 20:57:00 j kernel: [45052.498669] PM: suspend entry (deep)
Mar 21 20:57:00 j systemd-sleep[10412]: Entering sleep state 'suspend'...
Mar 21 20:57:00 j systemd[1]: grub-initrd-fallback.service: Deactivated successfully.
Mar 21 20:57:00 j systemd[1]: Finished GRUB failed boot detection.
Mar 21 20:57:00 j kernel: [45052.985290] Filesystems sync: 0.486 seconds
Mar 22 08:27:26 j kernel: [45053.364835] Freezing user space processes ... (elapsed 0.008 seconds) done.
Mar 22 08:27:26 j kernel: [45053.373718] OOM killer disabled.
Mar 22 08:27:26 j kernel: [45053.373720] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Mar 22 08:27:26 j kernel: [45053.376386] printk: Suspending console(s) (use no_console_suspend to debug)
Mar 22 08:27:26 j kernel: [45053.396281] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 22 08:27:26 j kernel: [45053.396419] sd 0:0:0:0: [sda] Stopping disk
Mar 22 08:27:26 j kernel: [45054.592197] ACPI: EC: interrupt blocked
Mar 22 08:27:26 j kernel: [45054.611259] ACPI: PM: Preparing to enter system sleep state S3
Mar 22 08:27:26 j kernel: [45054.721018] ACPI: EC: event blocked
Mar 22 08:27:26 j kernel: [45054.721025] ACPI: EC: EC stopped
Mar 22 08:27:26 j kernel: [45054.721026] ACPI: PM: Saving platform NVS memory
Mar 22 08:27:26 j kernel: [45054.726931] Disabling non-boot CPUs ...
Mar 22 08:27:26 j kernel: [45054.728920] smpboot: CPU 1 is now offline
Mar 22 08:27:26 j kernel: [45054.732527] ACPI: PM: Low-level resume complete
Mar 22 08:27:26 j kernel: [45054.732527] ACPI: EC: EC started
Mar 22 08:27:26 j kernel: [45054.732527] ACPI: PM: Restoring platform NVS memory
Mar 22 08:27:26 j kernel: [45054.732527] PCI-DMA: Resuming GART IOMMU
Mar 22 08:27:26 j kernel: [45054.732527] PCI-DMA: Restoring GART aperture settings
Mar 22 08:27:26 j kernel: [45054.732527] Enabling non-boot CPUs ...
Mar 22 08:27:26 j kernel: [45054.732527] x86: Booting SMP configuration:
Mar 22 08:27:26 j kernel: [45054.732527] smpboot: Booting Node 0 Processor 1 APIC 0x1
Mar 22 08:27:26 j kernel: [45054.728903] process: Switch to broadcast mode on CPU1
Mar 22 08:27:26 j kernel: [45054.734469] CPU1 is up
Mar 22 08:27:26 j kernel: [45054.736200] ACPI: PM: Waking up from system sleep state S3
Mar 22 08:27:26 j kernel: [45055.060664] ACPI: EC: interrupt unblocked
Mar 22 08:27:26 j kernel: [45055.083020] ACPI: EC: event unblocked
Mar 22 08:27:26 j kernel: [45055.083897] sd 0:0:0:0: [sda] Starting disk
Mar 22 08:27:26 j kernel: [45055.090957] [drm] PCIE GART of 512M enabled (table at 0x00000000C0146000).
Mar 22 08:27:26 j kernel: [45055.091029] radeon 0000:01:05.0: WB enabled
Mar 22 08:27:26 j kernel: [45055.091034] radeon 0000:01:05.0: fence driver on ring 0 use gpu addr 0x00000000a0000c00
Mar 22 08:27:26 j kernel: [45055.094881] radeon 0000:01:05.0: fence driver on ring 5 use gpu addr 0x00000000c0056038
Mar 22 08:27:26 j kernel: [45055.095241] debugfs: File 'radeon_ring_gfx' in directory '0' already present!
Mar 22 08:27:26 j kernel: [45055.127329] [drm] ring test on 0 succeeded in 0 usecs
Mar 22 08:27:26 j kernel: [45055.127333] debugfs: File 'radeon_ring_uvd' in directory '0' already present!
Mar 22 08:27:26 j kernel: [45055.302054] [drm] ring test on 5 succeeded in 1 usecs
Mar 22 08:27:26 j kernel: [45055.302061] [drm] UVD initialized successfully.
Mar 22 08:27:26 j kernel: [45055.302131] [drm] ib test on ring 0 succeeded in 0 usecs
Mar 22 08:27:26 j kernel: [45055.346874] usb 1-6: reset high-speed USB device number 3 using ehci-pci
Mar 22 08:27:26 j kernel: [45055.981756] [drm] ib test on ring 5 succeeded
Mar 22 08:27:26 j kernel: [45057.358876] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 22 08:27:26 j kernel: [45057.359870] ata1.00: ATA Identify Device Log not supported
Mar 22 08:27:26 j kernel: [45057.360896] ata1.00: ATA Identify Device Log not supported
Mar 22 08:27:26 j kernel: [45057.360903] ata1.00: configured for UDMA/133
Mar 22 08:27:26 j kernel: [45061.046862] [drm:atom_op_jump [radeon]] *ERROR* atombios stuck in loop for more than 5secs aborting
Mar 22 08:27:26 j kernel: [45061.047013] [drm:atom_execute_table_locked [radeon]] *ERROR* atombios stuck executing E692 (len 498, WS 0, PS 4) @ 0xE6D3
Mar 22 08:27:26 j kernel: [45062.424581] OOM killer enabled.
Mar 22 08:27:26 j kernel: [45062.424586] Restarting tasks ... done.
Mar 22 08:27:26 j rtkit-daemon[1098]: The canary thread is apparently starving. Taking action.
Mar 22 08:27:26 j systemd-resolved[691]: Clock change detected. Flushing caches.
Mar 22 08:27:26 j rtkit-daemon[1098]: Demoting known real-time threads.
Mar 22 08:27:26 j systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 22 08:27:26 j rtkit-daemon[1098]: Successfully demoted thread 1134 of process 1089.
Mar 22 08:27:26 j rtkit-daemon[1098]: Successfully demoted thread 1133 of process 1089.
Mar 22 08:27:26 j zed[748]: Exiting
Mar 22 08:27:26 j rtkit-daemon[1098]: Successfully demoted thread 1089 of process 1089.
Mar 22 08:27:26 j systemd[1]: Started Run anacron jobs.
Mar 22 08:27:26 j rtkit-daemon[1098]: Successfully demoted thread 1109 of process 1088.
Mar 22 08:27:26 j systemd[1]: Starting Daily apt download activities...
Mar 22 08:27:26 j rtkit-daemon[1098]: Successfully demoted thread 1088 of process 1088.
Mar 22 08:27:26 j systemd[1]: Starting Daily dpkg database backup service...
Mar 22 08:27:26 j rtkit-daemon[1098]: Demoted 5 threads.
Mar 22 08:27:26 j systemd[1]: Starting Refresh fwupd metadata and update motd...
Mar 22 08:27:26 j kernel: [45062.703894] video LNXVIDEO:00: Restoring backlight state
Mar 22 08:27:26 j ntpd[911]: Deleting interface #33 wlo1, 192.168.43.155#123, interface stats: received=113, sent=112, dropped=0, active_time=3841 secs
Mar 22 08:27:26 j kernel: [45062.715426] PM: suspend exit
Mar 22 08:27:26 j systemd[1]: Starting Update the plocate database...
Mar 22 08:27:27 j ntpd[911]: 158.69.20.38 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j systemd[1]: Starting Rotate log files...
Mar 22 08:27:27 j ntpd[911]: 209.115.181.108 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j systemd[1]: Starting Daily man-db regeneration...
Mar 22 08:27:27 j ntpd[911]: 206.108.0.131 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j systemd[1]: zfs-zed.service: Deactivated successfully.
Mar 22 08:27:27 j ntpd[911]: 206.248.144.166 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j systemd-sleep[10412]: System returned from sleep state.
Mar 22 08:27:27 j ntpd[911]: 209.115.181.106 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: 216.232.132.95 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: 206.108.0.133 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: 216.6.2.70 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: 95.211.123.72 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: 212.51.144.44 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: 5.39.81.111 local addr 192.168.43.155 -> <null>
Mar 22 08:27:27 j ntpd[911]: Deleting interface #34 wlo1, fe80::8aa:6292:4754:6924%3#123, interface stats: received=0, sent=0, dropped=0, active_time=3841 secs
Mar 22 08:27:27 j anacron[10477]: Anacron 2.3 started on 2023-03-22
Mar 22 08:27:27 j anacron[10477]: Will run job `cron.daily' in 5 min.
Mar 22 08:27:27 j anacron[10477]: Jobs will be executed sequentially
Mar 22 08:27:27 j systemd-sleep[10511]: /dev/sda:
Mar 22 08:27:27 j systemd-sleep[10511]:  setting Advanced Power Management level to 0xfe (254)
Mar 22 08:27:27 j systemd-sleep[10511]:  APM_level#011= 254
Mar 22 08:27:27 j systemd[1]: systemd-suspend.service: Deactivated successfully.
Mar 22 08:27:27 j systemd[1]: Finished System Suspend.
Mar 22 08:27:27 j systemd[1]: Stopped target Sleep.
Mar 22 08:27:27 j systemd[1]: Reached target Suspend.
Mar 22 08:27:27 j systemd[1]: Stopped target Suspend.
Mar 22 08:27:27 j NetworkManager[800]: <info>  [1679484447.8846] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 22 08:27:27 j NetworkManager[800]: <info>  [1679484447.8851] device (enp2s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 22 08:27:27 j ModemManager[870]: <info>  [sleep-monitor] system is resuming
Mar 22 08:27:27 j kernel: [45063.626012] RTL8201CP Ethernet r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC)
Mar 22 08:27:27 j NetworkManager[800]: <info>  [1679484447.9676] device (wlo1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 22 08:27:27 j kernel: [45063.706947] r8169 0000:02:00.0 enp2s0: Link is Down
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0087] device (p2p-dev-wlo1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0177] manager: NetworkManager state is now DISCONNECTED
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0539] device (wlo1): supplicant interface state: internal-starting -> disconnected
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0540] device (p2p-dev-wlo1): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0585] Wi-Fi P2P device controlled by interface wlo1 created
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0595] manager: (p2p-dev-wlo1): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/9)
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0748] device (p2p-dev-wlo1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.0865] device (wlo1): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Mar 22 08:27:28 j NetworkManager[800]: <info>  [1679484448.1015] device (p2p-dev-wlo1): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:28 j dbus-daemon[724]: [system] Activating via systemd: service name='org.freedesktop.fwupd' unit='fwupd.service' requested by ':1.256' (uid=126 pid=10480 comm="/usr/bin/fwupdmgr refresh " label="unconfined")
Mar 22 08:27:28 j systemd[1]: Starting Firmware update daemon...
Mar 22 08:27:28 j systemd[1]: Stopping Make remote CUPS printers available locally...
Mar 22 08:27:29 j systemd[1]: cups-browsed.service: Deactivated successfully.
Mar 22 08:27:29 j systemd[1]: Stopped Make remote CUPS printers available locally.
Mar 22 08:27:29 j systemd[1]: Stopping CUPS Scheduler...
Mar 22 08:27:29 j systemd[1]: cups.service: Deactivated successfully.
Mar 22 08:27:29 j systemd[1]: Stopped CUPS Scheduler.
Mar 22 08:27:29 j systemd[1]: cups.path: Deactivated successfully.
Mar 22 08:27:29 j systemd[1]: Stopped CUPS Scheduler.
Mar 22 08:27:29 j systemd[1]: Stopping CUPS Scheduler...
Mar 22 08:27:29 j systemd[1]: Started CUPS Scheduler.
Mar 22 08:27:29 j systemd[1]: cups.socket: Deactivated successfully.
Mar 22 08:27:29 j systemd[1]: Closed CUPS Scheduler.
Mar 22 08:27:29 j systemd[1]: Stopping CUPS Scheduler...
Mar 22 08:27:29 j systemd[1]: Listening on CUPS Scheduler.
Mar 22 08:27:29 j systemd[1]: Starting CUPS Scheduler...
Mar 22 08:27:30 j systemd[1]: Started CUPS Scheduler.
Mar 22 08:27:30 j kernel: [45065.774985] audit: type=1400 audit(1679484450.036:29): apparmor="DENIED" operation="capable" profile="/usr/sbin/cupsd" pid=10578 comm="cupsd" capability=12  capname="net_admin"
Mar 22 08:27:30 j systemd[1]: dpkg-db-backup.service: Deactivated successfully.
Mar 22 08:27:30 j systemd[1]: Finished Daily dpkg database backup service.
Mar 22 08:27:30 j systemd[1]: Started Make remote CUPS printers available locally.
Mar 22 08:27:30 j kernel: [45065.991046] audit: type=1400 audit(1679484450.252:30): apparmor="DENIED" operation="capable" profile="/usr/sbin/cups-browsed" pid=10579 comm="cups-browsed" capability=23  capname="sys_nice"
Mar 22 08:27:30 j ModemManager[870]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:04.0/0000:02:00.0': not supported by any plugin
Mar 22 08:27:30 j ModemManager[870]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:05.0/0000:08:00.0': not supported by any plugin
Mar 22 08:27:30 j systemd[1]: logrotate.service: Deactivated successfully.
Mar 22 08:27:30 j systemd[1]: Finished Rotate log files.
Mar 22 08:27:35 j dbus-daemon[724]: [system] Successfully activated service 'org.freedesktop.fwupd'
Mar 22 08:27:35 j systemd[1]: Started Firmware update daemon.
Mar 22 08:27:35 j fwupdmgr[10480]: Updating lvfs
Mar 22 08:27:35 j fwupdmgr[10480]: Downloading…: 0%
Mar 22 08:27:35 j systemd[1]: fwupd-refresh.service: Main process exited, code=exited, status=1/FAILURE
Mar 22 08:27:35 j systemd[1]: fwupd-refresh.service: Failed with result 'exit-code'.
Mar 22 08:27:35 j systemd[1]: Failed to start Refresh fwupd metadata and update motd.
Mar 22 08:27:37 j systemd[1]: man-db.service: Deactivated successfully.
Mar 22 08:27:37 j systemd[1]: Finished Daily man-db regeneration.
Mar 22 08:27:37 j systemd[1]: man-db.service: Consumed 1.028s CPU time.
Mar 22 08:27:45 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:45 j rtkit-daemon[1098]: message repeated 5 times: [ Supervising 0 threads of 0 processes of 0 users.]
Mar 22 08:27:45 j rtkit-daemon[1098]: Recovering from system lockup, not allowing further RT threads.
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.8796] policy: auto-activating connection 'FoxFi60' (ffa7cbfa-636b-48ea-baa0-d08bec674c8a)
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.8904] device (wlo1): Activation: starting connection 'FoxFi60' (ffa7cbfa-636b-48ea-baa0-d08bec674c8a)
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.8928] device (wlo1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9030] manager: NetworkManager state is now CONNECTING
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9115] device (wlo1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9161] device (wlo1): Activation: (wifi) access point 'FoxFi60' has security, but secrets are required.
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9221] device (wlo1): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9261] device (wlo1): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9288] device (wlo1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9344] device (wlo1): Activation: (wifi) connection 'FoxFi60' has security, and secrets exist.  No new secrets needed.
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9355] Config: added 'ssid' value 'FoxFi60'
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9363] Config: added 'scan_ssid' value '1'
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9372] Config: added 'bgscan' value 'simple:30:-70:86400'
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9380] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9389] Config: added 'psk' value '<hidden>'
Mar 22 08:27:47 j wpa_supplicant[746]: wlo1: SME: Trying to authenticate with 5c:7d:5e:05:38:37 (SSID='FoxFi60' freq=2437 MHz)
Mar 22 08:27:47 j kernel: [45083.710445] wlo1: authenticate with 5c:7d:5e:05:38:37
Mar 22 08:27:47 j kernel: [45083.726696] wlo1: send auth to 5c:7d:5e:05:38:37 (try 1/3)
Mar 22 08:27:47 j wpa_supplicant[746]: wlo1: Trying to associate with 5c:7d:5e:05:38:37 (SSID='FoxFi60' freq=2437 MHz)
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9957] device (wlo1): supplicant interface state: disconnected -> authenticating
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9958] device (p2p-dev-wlo1): supplicant management interface state: disconnected -> authenticating
Mar 22 08:27:47 j kernel: [45083.728705] wlo1: authenticated
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9985] device (wlo1): supplicant interface state: authenticating -> associating
Mar 22 08:27:47 j NetworkManager[800]: <info>  [1679484467.9986] device (p2p-dev-wlo1): supplicant management interface state: authenticating -> associating
Mar 22 08:27:48 j kernel: [45083.731413] wlo1: associate with 5c:7d:5e:05:38:37 (try 1/3)
Mar 22 08:27:48 j kernel: [45083.735472] wlo1: RX AssocResp from 5c:7d:5e:05:38:37 (capab=0x411 status=0 aid=1)
Mar 22 08:27:48 j kernel: [45083.735679] wlo1: associated
Mar 22 08:27:48 j wpa_supplicant[746]: wlo1: Associated with 5c:7d:5e:05:38:37
Mar 22 08:27:48 j wpa_supplicant[746]: wlo1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0148] device (wlo1): supplicant interface state: associating -> 4way_handshake
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0149] device (p2p-dev-wlo1): supplicant management interface state: associating -> 4way_handshake
Mar 22 08:27:48 j wpa_supplicant[746]: wlo1: WPA: Key negotiation completed with 5c:7d:5e:05:38:37 [PTK=CCMP GTK=CCMP]
Mar 22 08:27:48 j wpa_supplicant[746]: wlo1: CTRL-EVENT-CONNECTED - Connection to 5c:7d:5e:05:38:37 completed [id=0 id_str=]
Mar 22 08:27:48 j kernel: [45083.787264] IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0594] device (wlo1): supplicant interface state: 4way_handshake -> completed
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0596] device (wlo1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "FoxFi60"
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0597] device (p2p-dev-wlo1): supplicant management interface state: 4way_handshake -> completed
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0602] device (wlo1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:48 j NetworkManager[800]: <info>  [1679484468.0654] dhcp4 (wlo1): activation: beginning transaction (timeout in 45 seconds)
Mar 22 08:27:48 j avahi-daemon[703]: Joining mDNS multicast group on interface wlo1.IPv6 with address fe80::8aa:6292:4754:6924.
Mar 22 08:27:48 j avahi-daemon[703]: New relevant interface wlo1.IPv6 for mDNS.
Mar 22 08:27:48 j avahi-daemon[703]: Registering new address record for fe80::8aa:6292:4754:6924 on wlo1.*.
Mar 22 08:27:48 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:48 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:48 j wpa_supplicant[746]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-33 noise=-89 txrate=6500
Mar 22 08:27:49 j ntpd[911]: bind(23) AF_INET6 fe80::8aa:6292:4754:6924%3#123 flags 0x11 failed: Cannot assign requested address
Mar 22 08:27:49 j ntpd[911]: unable to create socket on wlo1 (35) for fe80::8aa:6292:4754:6924%3#123
Mar 22 08:27:49 j ntpd[911]: failed to init interface for address fe80::8aa:6292:4754:6924%3
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.8043] dhcp4 (wlo1): state changed new lease, address=192.168.43.155
Mar 22 08:27:50 j avahi-daemon[703]: Joining mDNS multicast group on interface wlo1.IPv4 with address 192.168.43.155.
Mar 22 08:27:50 j avahi-daemon[703]: New relevant interface wlo1.IPv4 for mDNS.
Mar 22 08:27:50 j avahi-daemon[703]: Registering new address record for 192.168.43.155 on wlo1.IPv4.
Mar 22 08:27:50 j dbus-daemon[724]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=800 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.8199] device (wlo1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:50 j systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 22 08:27:50 j dbus-daemon[724]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 22 08:27:50 j systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 22 08:27:50 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:50 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.9339] device (wlo1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.9349] device (wlo1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.9379] manager: NetworkManager state is now CONNECTED_LOCAL
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.9412] manager: NetworkManager state is now CONNECTED_SITE
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.9455] policy: set 'FoxFi60' (wlo1) as default for IPv4 routing and DNS
Mar 22 08:27:50 j NetworkManager[800]: <info>  [1679484470.9493] device (wlo1): Activation: successful, device activated.
Mar 22 08:27:50 j systemd-resolved[691]: wlo1: Bus client set default route setting: yes
Mar 22 08:27:50 j systemd-resolved[691]: wlo1: Bus client set DNS server list to: 192.168.43.1
Mar 22 08:27:51 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:51 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:51 j NetworkManager[800]: <info>  [1679484471.5870] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 22 08:27:51 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:51 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:51 j nm-dispatcher[10842]: /etc/network/if-up.d/resolved: 12: mystatedir: not found
Mar 22 08:27:52 j nm-dispatcher[10888]: /etc/network/if-up.d/resolved: 12: mystatedir: not found
Mar 22 08:27:53 j ntpd[911]: Listen normally on 36 wlo1 192.168.43.155:123
Mar 22 08:27:53 j ntpd[911]: Listen normally on 37 wlo1 [fe80::8aa:6292:4754:6924%3]:123
Mar 22 08:27:53 j ntpd[911]: new interface(s) found: waking up resolver
Mar 22 08:27:55 j systemd[1]: apt-daily.service: Deactivated successfully.
Mar 22 08:27:55 j systemd[1]: Finished Daily apt download activities.
Mar 22 08:27:55 j systemd[1]: apt-daily.service: Consumed 1.729s CPU time.
Mar 22 08:27:55 j systemd[1]: Starting Daily apt upgrade and clean activities...
Mar 22 08:27:57 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:57 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:27:58 j systemd-resolved[691]: Using degraded feature set UDP instead of UDP+EDNS0 for DNS server 192.168.43.1.
Mar 22 08:27:58 j systemd[1]: apt-daily-upgrade.service: Deactivated successfully.
Mar 22 08:27:58 j systemd[1]: Finished Daily apt upgrade and clean activities.
Mar 22 08:27:58 j systemd[1]: apt-daily-upgrade.service: Consumed 1.624s CPU time.
Mar 22 08:28:02 j systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 22 08:28:02 j ntpd[911]: Soliciting pool server 85.199.214.99
Mar 22 08:28:03 j systemd[1]: plocate-updatedb.service: Deactivated successfully.
Mar 22 08:28:03 j systemd[1]: Finished Update the plocate database.
Mar 22 08:28:03 j systemd[1]: plocate-updatedb.service: Consumed 10.200s CPU time.
Mar 22 08:29:24 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:29:30 j rtkit-daemon[1098]: message repeated 3 times: [ Supervising 0 threads of 0 processes of 0 users.]
Mar 22 08:30:01 j CRON[11247]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
Mar 22 08:30:19 j systemd-resolved[691]: Clock change detected. Flushing caches.
Mar 22 08:30:21 j ntpd[911]: Soliciting pool server 69.196.152.39
Mar 22 08:30:22 j ntpd[911]: Soliciting pool server 208.81.1.244
Mar 22 08:30:23 j ntpd[911]: Soliciting pool server 167.99.186.165
Mar 22 08:30:24 j ntpd[911]: Soliciting pool server 162.159.200.1
Mar 22 08:32:27 j anacron[10477]: Job `cron.daily' started
Mar 22 08:32:27 j anacron[11317]: Updated timestamp for job `cron.daily' to 2023-03-22
Mar 22 08:32:29 j cracklib: no dictionary update necessary.
Mar 22 08:32:29 j anacron[10477]: Job `cron.daily' terminated
Mar 22 08:32:29 j anacron[10477]: Normal exit (1 job run)
Mar 22 08:32:29 j systemd[1]: anacron.service: Deactivated successfully.
Mar 22 08:32:52 j systemd[1]: Started Run anacron jobs.
Mar 22 08:32:52 j anacron[11393]: Anacron 2.3 started on 2023-03-22
Mar 22 08:32:52 j anacron[11393]: Normal exit (0 jobs run)
Mar 22 08:32:52 j systemd[1]: anacron.service: Deactivated successfully.
Mar 22 08:34:55 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.
Mar 22 08:35:29 j rtkit-daemon[1098]: message repeated 3 times: [ Supervising 0 threads of 0 processes of 0 users.]
Mar 22 08:35:50 j dbus-daemon[1108]: [session uid=1000 pid=1108] Activating service name='org.freedesktop.thumbnails.Thumbnailer1' requested by ':1.20' (uid=1000 pid=1386 comm="Thunar --daemon " label="unconfined")
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer xapp-epub-thumbnailer -i %i -o %o -s %s
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer xreader-thumbnailer -s %s %u %o
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer xapp-appimage-thumbnailer -i %i -o %o -s %s
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer xapp-mp3-thumbnailer -i %i -o %o -s %s
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer /usr/bin/gdk-pixbuf-thumbnailer -s %s %u %o
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer ffmpegthumbnailer -i %i -o %o -s %s -f
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer xapp-raw-thumbnailer -i %i -o %o -s %s
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer gsf-office-thumbnailer -i %i -o %o -s %s
Mar 22 08:35:50 j org.freedesktop.thumbnails.Thumbnailer1[11495]: Registered thumbnailer gnome-thumbnail-font --size %s %u %o
Mar 22 08:35:51 j dbus-daemon[1108]: [session uid=1000 pid=1108] Successfully activated service 'org.freedesktop.thumbnails.Thumbnailer1'
Mar 22 08:38:14 j rtkit-daemon[1098]: Supervising 0 threads of 0 processes of 0 users.

Playing around with the partially functioning suspend feature i noticed another behavior - it seems unable to resume if there are any programs running at the time of suspension, even if the usual 12ish hours have elapsed. If there are no programs or windows etc open it cab resume.

thanks again
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

quietlife wrote: Wed Mar 22, 2023 7:47 am Ran the command you gave
And this shows the time gap.

Code: Select all

Mar 22 08:27:26 j kernel: Freezing user space processes ... (elapsed 0.008 seconds) done.
Mar 21 20:57:00 j kernel: Filesystems sync: 0.486 seconds
What often happens is the suspension starts but not all messages are written to the journalctl until after the computer resumes from sleep. It depends on how fast the computer is.

There are errors in this related to the graphics driver which are the same errors we've seen previously.

Code: Select all

Mar 22 08:27:26 j kernel: [drm:atom_op_jump [radeon]] *ERROR* atombios stuck in loop for more than 5secs aborting
Mar 22 08:27:26 j kernel: [drm:atom_execute_table_locked [radeon]] *ERROR* atombios stuck executing E692 (len 498, WS 0, PS 4) @ 0xE6D3
It also appears there may be an issue with how fast your power button is responding?

Code: Select all

Mar 21 20:56:33 j systemd-logind[742]: Power key pressed.
Mar 21 20:56:37 j systemd-logind[742]: Power key pressed.
Mar 21 20:56:38 j systemd-logind[742]: Power key pressed.
Mar 21 20:56:56 j systemd-logind[742]: Power key pressed.
Mar 21 20:56:59 j NetworkManager[800]: <info>  [1679443019.2795] manager: sleep: sleep requested (sleeping: no  enabled: yes)
quietlife wrote: Wed Mar 22, 2023 7:47 amThe following is taken from the syslog file in var/log. I began the selection just before the time when it started to suspend (i think).
That is basically the same information as in the link.
quietlife wrote: Wed Mar 22, 2023 7:47 amPlaying around with the partially functioning suspend feature i noticed another behavior - it seems unable to resume if there are any programs running at the time of suspension, even if the usual 12ish hours have elapsed. If there are no programs or windows etc open it cab resume.
This may just be a sign the computer can not handle newer programs without being given a little extra time to work. I did see a

Code: Select all

Mar 22 08:27:26 j rtkit-daemon[1098]: The canary thread is apparently starving. Taking action.
which can be a sign the system is struggling to keep up. Usually, if one gives it time it can get passed the starving situation, but it depends on the hardware and the exact issue causing the bottleneck.
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

It also appears there may be an issue with how fast your power button is responding?
Well spotted! No, what was happening there was that it was me repeatedly using the power slide button to bring up the shutdown/suspend menu as i always used to on cinnamon 18.3 and which this current install wasn't configured to do yet.

which can be a sign the system is struggling to keep up. Usually, if one gives it time it can get passed the starving situation, but it depends on the hardware and the exact issue causing the bottleneck.
The program usually left running at suspend is firefox which is not exactly the lightest on resources i guess. maybe its bulk is causing some issue.
User avatar
SMG
Level 25
Level 25
Posts: 31941
Joined: Sun Jul 26, 2020 6:15 pm
Location: USA

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by SMG »

quietlife wrote: Wed Mar 22, 2023 11:47 amThe program usually left running at suspend is firefox which is not exactly the lightest on resources i guess. maybe its bulk is causing some issue.
It's possible it is, especially if you have any add-ons that might be doing work as well. Also, Firefox has been making a number of changes recently with regards to how it uses the hardware GPU (which it did not necessarily do in the past) in recent version so that may also be a factor, especially considering there are graphics driver related messages/errors posting.

I do not have any other ideas.
Image
A woman typing on a laptop with LM20.3 Cinnamon.
quietlife
Level 1
Level 1
Posts: 22
Joined: Thu Feb 16, 2023 9:16 am

Re: HP Pavilion crashing on resume from suspend on Mint 21.1 xfce

Post by quietlife »

Fair enough SMG. It's a pain and a shame that mint has this little problem but i'll probably stick with it for now. Thanks for taking the time to try work through it though. I learned a few things along the way which is a bonus.
Locked

Return to “Other topics”