Arch Linux

zapphot

Member

Registered: 2018-03-15

Posts: 14

systemd-journal.service crashes after resume from s2idle (x1 carbon ge

As stated in the title, i have the problem that systemd-journal.service crashes after resume from s2idle. Here is the dmesg:

[ 8712.029339] PM: suspend entry (s2idle)
[ 8712.029343] PM: Syncing filesystems ... done.
[ 8712.037189] Freezing user space processes ... (elapsed 0.002 seconds) done.
[ 8712.039734] OOM killer disabled.
[ 8712.039735] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[ 8712.041031] Suspending console(s) (use no_console_suspend to debug)
[ 8712.323269] acpi INT3400:00: Unsupported event [0x86]
[ 8712.329504] thinkpad_acpi: unknown possible thermal alarm or keyboard event received
[ 8712.329505] thinkpad_acpi: unhandled HKEY event 0x6032
[ 8712.329506] thinkpad_acpi: please report the conditions when this event happened to ibm-acpi-devel@lists.sourceforge.net
[11776.114713] acpi INT3400:00: Unsupported event [0x86]
[11776.127949] [drm] HuC: Loaded firmware i915/kbl_huc_ver02_00_1810.bin (version 2.0)
[11776.132231] [drm] GuC: Loaded firmware i915/kbl_guc_ver9_14.bin (version 9.14)
[11776.136865] i915 0000:00:02.0: GuC submission enabled (firmware i915/kbl_guc_ver9_14.bin [version 9.14])
[11776.216113] OOM killer enabled.
[11776.216115] Restarting tasks ... done.
[11776.234317] thermal thermal_zone6: failed to read out thermal zone (-61)
[11776.240441] [drm] RC6 on
[11776.265787] PM: suspend exit
[11776.274426] systemd[1]: Started Suspend.
[11776.274493] systemd[1]: sleep.target: Unit not needed anymore. Stopping.
[11776.275339] systemd[1]: Stopped target Sleep.
[11776.275396] systemd[1]: Reached target Suspend.
[11776.275582] systemd[1]: suspend.target: Unit not needed anymore. Stopping.
[11776.275646] systemd[1]: Stopped target Suspend.
[11776.281631] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
[11776.361994] systemd[1]: systemd-journald.service: Main process exited, code=dumped, status=6/ABRT
[11776.362104] systemd[1]: systemd-journald.service: Failed with result 'watchdog'.
[11776.363288] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart.
[11776.363379] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 3.
[11776.373377] systemd-coredump[1466]: MESSAGE=Process 29702 (systemd-journal) of user 0 dumped core.
[11776.373382] systemd-coredump[1466]: Coredump diverted to /var/lib/systemd/coredump/core.systemd-journal.0.56a9e55ce675439aa8633b80736a3d7d.29702.1521981817000000.lz4
[11776.373385] systemd-coredump[1466]: Stack trace of thread 29702:
[11776.373388] systemd-coredump[1466]: #0  0x00007f2ce2302f90 epoll_pwait (libc.so.6)
[11776.373390] systemd-coredump[1466]: #1  0x00007f2ce1e8075c sd_event_wait (libsystemd-shared-238.so)
[11776.373392] systemd-coredump[1466]: #2  0x00007f2ce1e8156c sd_event_run (libsystemd-shared-238.so)
[11776.373395] systemd-coredump[1466]: #3  0x000055799ca52c4a n/a (systemd-journald)
[11776.373397] systemd-coredump[1466]: #4  0x00007f2ce222df4a __libc_start_main (libc.so.6)
[11776.373399] systemd-coredump[1466]: #5  0x000055799ca5505a n/a (systemd-journald)
[11776.387669] systemd-journald[1492]: File /run/log/journal/182aea36cfee4e9ab9a932a763629f15/system.journal corrupted or uncleanly shut down, renaming and replacing.

This laptop does not (at least officially) support s3 suspend as discussed here https://bbs.archlinux.org/viewtopic.php?id=234913 and therefore only uses s2idle. I load the guc and huc firmware, but the crashes also occur if I prevent them from loading.

I wanted to check the coredump but  "coredumpctl list" only returns "No coredumps found and i dont know how to handle the core.systemd-journal...lz4 file.

Do you think this is a general suspend issue (kernel/bios related) or is it a problem with systemd?

Last edited by zapphot (2018-03-25 13:44:51)

misarm

Member

Registered: 2008-12-02

Posts: 9

Re: systemd-journal.service crashes after resume from s2idle (x1 carbon ge

Same here on ThinkPad E16 Gen 2 (21MA002SCK)

Kernel: 6.12.3-arch1-1 arch: x86_64 bits: 64 compiler: gcc v: 14.2.1
CPU Intel Core Ultra 7 155H

journalctl -r :

pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: [?] Process 430 (systemd-journal) of user 0 dumped core.
pro 10 06:45:11 MMthinkpad rtkit-daemon[2982]: Successfully demoted thread 2983 of process 2961.
pro 10 06:45:11 MMthinkpad systemd-sleep[68638]: System returned from sleep operation 'suspend'.
pro 10 06:45:11 MMthinkpad rtkit-daemon[2982]: Successfully demoted thread 55185 of process 55057.
pro 10 06:45:11 MMthinkpad systemd[1]: systemd-journald.service: Killing process 430 (systemd-journal) with signal SIGABRT.
pro 10 06:45:11 MMthinkpad rtkit-daemon[2982]: Demoting known real-time threads.
pro 10 06:45:11 MMthinkpad systemd[1]: systemd-journald.service: Watchdog timeout (limit 3min)!
pro 10 06:45:11 MMthinkpad rtkit-daemon[2982]: The canary thread is apparently starving. Taking action.
pro 10 06:45:11 MMthinkpad systemd-logind[1068]: Lid opened.
pro 10 06:45:11 MMthinkpad systemd-journald[68962]: System Journal (/var/log/journal/8b967fbb75b54400b06139aaf6560540) is 137.4M, max 4G, 3.8G free.
pro 10 06:45:11 MMthinkpad systemd-journald[68962]: Journal started
pro 10 06:45:11 MMthinkpad systemd-journald[68962]: File /var/log/journal/8b967fbb75b54400b06139aaf6560540/system.journal corrupted or uncleanly shut down, renaming and replacing.
pro 10 06:45:11 MMthinkpad systemd-journald[68962]: Collecting audit messages is disabled.
pro 10 06:45:11 MMthinkpad systemd[1]: Finished Rotate log files.
pro 10 06:45:11 MMthinkpad systemd[1]: logrotate.service: Deactivated successfully.
pro 10 06:45:11 MMthinkpad systemd[1]: Starting Journal Service...
pro 10 06:45:11 MMthinkpad systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
pro 10 06:45:11 MMthinkpad systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully.
pro 10 06:45:11 MMthinkpad systemd[1]: systemd-journald.service: Failed with result 'watchdog'.
pro 10 06:45:11 MMthinkpad systemd[1]: systemd-journald.service: Main process exited, code=dumped, status=6/ABRT
pro 10 06:45:11 MMthinkpad kernel: Process accounting resumed
pro 10 06:45:11 MMthinkpad systemd[1]: Finished Restart atop daemon to rotate logs.
pro 10 06:45:11 MMthinkpad systemd[1]: atop-rotate.service: Deactivated successfully.
pro 10 06:45:11 MMthinkpad systemd[1]: Started Atop advanced performance monitor.
pro 10 06:45:11 MMthinkpad systemd[1]: shadow.service: Deactivated successfully.
pro 10 06:45:11 MMthinkpad systemd[1]: Starting Atop advanced performance monitor...
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: ELF object binary architecture: AMD x86-64
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #4  0x00007e1c44f282a4 __clone (libc.so.6 + 0x1192a4)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #3  0x00007e1c44ea339d n/a (libc.so.6 + 0x9439d)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #2  0x00007e1c4513171e n/a (libsystemd-shared-256.9-1.so + 0x13171e)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #1  0x00007e1c4513134a n/a (libsystemd-shared-256.9-1.so + 0x13134a)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #0  0x00007e1c44f1ddec fsync (libc.so.6 + 0x10edec)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: Stack trace of thread 68764:
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #11 0x000060e1772f76f5 n/a (systemd-journald + 0x86f5)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #10 0x00007e1c44e34ecc __libc_start_main (libc.so.6 + 0x25ecc)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #9  0x00007e1c44e34e08 n/a (libc.so.6 + 0x25e08)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #8  0x000060e1772f7224 n/a (systemd-journald + 0x8224)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #7  0x00007e1c453118f1 sd_event_run (libsystemd-shared-256.9-1.so + 0x3118f1)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #6  0x00007e1c4530defe sd_event_dispatch (libsystemd-shared-256.9-1.so + 0x30defe)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #5  0x00007e1c4530dbc2 n/a (libsystemd-shared-256.9-1.so + 0x30dbc2)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #4  0x000060e177303a9b n/a (systemd-journald + 0x14a9b)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #3  0x000060e177300dd2 n/a (systemd-journald + 0x11dd2)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #2  0x00007e1c452e1ba5 journal_file_append_entry (libsystemd-shared-256.9-1.so + 0x2e1ba5)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #1  0x00007e1c452e0e96 n/a (libsystemd-shared-256.9-1.so + 0x2e0e96)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: #0  0x00007e1c452df59b journal_file_append_object (libsystemd-shared-256.9-1.so + 0x2df59b)
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: Stack trace of thread 430:
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: Coredump diverted to /var/lib/systemd/coredump/core.systemd-journal.0.1047652f48234f51b2e724eb2c03b8ca.430.1733809511000000.zst
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: Process 430 (systemd-journal) of user 0 dumped core.
pro 10 06:45:11 MMthinkpad systemd[1]: Started Verify integrity of password and group files.
pro 10 06:45:11 MMthinkpad systemd[1]: Starting Daily man-db regeneration...
pro 10 06:45:11 MMthinkpad systemd[1]: Starting Rotate log files...
pro 10 06:45:11 MMthinkpad systemd-coredump[68786]: Process 430 (systemd-journal) of user 0 terminated abnormally with signal 6/ABRT, processing...
pro 10 06:45:11 MMthinkpad kernel: PM: suspend exit
pro 10 06:45:11 MMthinkpad systemd[1]: Starting Restart atop daemon to rotate logs...
pro 10 06:45:11 MMthinkpad kernel: random: crng reseeded on system resumption
pro 10 06:45:11 MMthinkpad kernel: done.
pro 10 06:45:11 MMthinkpad kernel: CIFS: VFS: \\samba has not responded in 180 seconds. Reconnecting...
pro 10 06:45:11 MMthinkpad kernel: Restarting tasks ... 
pro 10 06:45:11 MMthinkpad kernel: OOM killer enabled.
pro 10 06:45:11 MMthinkpad kernel: mei_gsc_proxy 0000:00:16.0-0f73db04-97ab-4125-b893-e904ad0d5464: bound 0000:00:02.0 (ops i915_gsc_proxy_component_ops [i915])
pro 10 06:45:11 MMthinkpad kernel: nvme nvme0: 16/0/0 default/read/poll queues
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT1: GUC: RC enabled
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT1: GUC: SLPC enabled
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT1: GUC: submission enabled
pro 10 06:45:11 MMthinkpad kernel: nvme nvme0: D3 entry latency set to 10 seconds
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT1: HuC firmware i915/mtl_huc_gsc.bin version 8.5.4
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT1: GuC firmware i915/mtl_guc_70.bin version 70.29.2
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT0: GUC: RC enabled
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT0: GUC: SLPC enabled
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT0: GUC: submission enabled
pro 10 06:45:11 MMthinkpad kernel: spd5118 14-0052: PM: failed to resume async: error -6
pro 10 06:45:11 MMthinkpad kernel: spd5118 14-0052: PM: dpm_run_callback(): spd5118_resume [spd5118] returns -6
pro 10 06:45:11 MMthinkpad kernel: spd5118 14-0052: Failed to write b = 0: -6
pro 10 06:45:11 MMthinkpad kernel: spd5118 14-0050: PM: failed to resume async: error -6
pro 10 06:45:11 MMthinkpad kernel: spd5118 14-0050: PM: dpm_run_callback(): spd5118_resume [spd5118] returns -6
pro 10 06:45:11 MMthinkpad kernel: spd5118 14-0050: Failed to write b = 0: -6
pro 10 06:45:11 MMthinkpad kernel: i915 0000:00:02.0: [drm] GT0: GuC firmware i915/mtl_guc_70.bin version 70.29.2
pro 10 06:45:11 MMthinkpad kernel: ACPI: EC: interrupt unblocked
pro 10 06:45:11 MMthinkpad kernel: ACPI: EC: interrupt blocked
pro 10 06:45:11 MMthinkpad kernel: e1000e: EEE TX LPI TIMER: 00000011
pro 10 06:45:11 MMthinkpad kernel: printk: Suspending console(s) (use no_console_suspend to debug)
pro 10 06:45:11 MMthinkpad kernel: Freezing remaining freezable tasks completed (elapsed 1.553 seconds)
pro 10 06:45:11 MMthinkpad kernel: Freezing remaining freezable tasks
pro 10 06:45:11 MMthinkpad kernel: OOM killer disabled.
pro 10 06:45:11 MMthinkpad kernel: Freezing user space processes completed (elapsed 0.001 seconds)
pro 09 15:35:26 MMthinkpad kernel: Filesystems sync: 0.003 seconds
pro 09 15:35:26 MMthinkpad kernel: PM: suspend entry (s2idle)
pro 09 15:35:26 MMthinkpad systemd-sleep[68638]: Performing sleep operation 'suspend'...

seth

Member

Registered: 2012-09-03

Posts: 59,911

Re: systemd-journal.service crashes after resume from s2idle (x1 carbon ge

Both abort, second one after a 90s timeout.
The second one also has a remove filesystem.

Does either of you have an nvidia GPU and if not, does it help to disable the user services freeze?
https://bbs.archlinux.org/viewtopic.php?id=296954

---

How to upload text · How to boot w/o GUI · Disable Windows Fast-Start! · Fix your xinitrc