Arch Linux

hatrix

Member

Registered: 2020-04-21

Posts: 4

Stuttering, MCE and CPU hard lockup

Hey,

When playing videos or listening to music, be it streaming or on disk, I've got some stuttering.
The whole system freezes for a fraction of a second.
It's fairly random, it can happen every 10 seconds for a few minutes and disappear for hours.
I've checked the journal, nothing there. I got no idea where it could come from, BIOS, CPU, GPU?

I also can't suspend, the computer restarts by itself after one minute or two.

I've got however an MCE when booting, but not every time: https://framapic.org/ri88X0y5zIUV/mEFbAGBji2zZ.jpg

System info:
- CPU: AMD Ryzen 7 3700x
- Motherboard: MSI X570-A PRO, BIOS 7C37vH7
- GPU: AMD RX 5700XT
- linux 5.6.15-arch1-1
- mesa 20.0.7-3
- amd-ucode 20200519.8ba6fa6-1

The log below is when I rebooted my wifi router. The system crashed right after.
This is one of the only logs I've got that might point to a problem.
I've noticed there might be a BIOS problem but it's currently up to date.

Jun 01 11:53:40 ryzhat dhcpcd[555]: wlan0: carrier lost
Jun 01 11:53:40 ryzhat kernel: rtlwifi: AP off, try to reconnect now
Jun 01 11:53:40 ryzhat kernel: wlan0: Connection to AP f4:6b:ef:67:a0:ee lost
Jun 01 11:53:40 ryzhat dhcpcd[555]: wlan0: deleting address fe80::7533:e306:fb6c:6b92
Jun 01 11:53:40 ryzhat iwd[532]: Received Deauthentication event, reason: 4, from_ap: false
Jun 01 11:53:41 ryzhat dhcpcd[555]: wlan0: deleting route to 192.168.0.0/24
Jun 01 11:53:41 ryzhat dhcpcd[555]: wlan0: deleting default route via 192.168.0.1
Jun 01 11:53:42 ryzhat kernel: [drm] Fence fallback timer expired on ring gfx_0.0.0
Jun 01 11:53:45 ryzhat kernel: clocksource: timekeeping watchdog on CPU6: Marking clocksource 'tsc' as unstable because the skew is too large:
Jun 01 11:53:45 ryzhat kernel: clocksource:                       'hpet' wd_now: d133efaf wd_last: d0515111 mask: ffffffff
Jun 01 11:53:45 ryzhat kernel: clocksource:                       'tsc' cs_now: 148d383fe774 cs_last: 148c84e03654 mask: ffffffffffffffff
Jun 01 11:53:45 ryzhat kernel: tsc: Marking TSC unstable due to clocksource watchdog
Jun 01 11:53:47 ryzhat kernel: sched: RT throttling activated
Jun 01 11:53:47 ryzhat kernel: TSC found unstable after boot, most likely due to broken BIOS. Use 'tsc=unstable'.
Jun 01 11:53:47 ryzhat kernel: sched_clock: Marking unstable (6245486001476, -23859961)<-(6245515594610, -53453331)
Jun 01 11:53:49 ryzhat kernel: clocksource: Switched to clocksource hpet
Jun 01 11:53:54 ryzhat kernel: hrtimer: interrupt took 604015096 ns
Jun 01 11:54:00 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 0.000 msecs
Jun 01 11:54:01 ryzhat kernel: perf: interrupt took too long (749816 > 2500), lowering kernel.perf_event_max_sample_rate to 300
Jun 01 11:54:01 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 95.863 msecs
Jun 01 11:54:01 ryzhat kernel: perf: interrupt took too long (1145086 > 937270), lowering kernel.perf_event_max_sample_rate to 300
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: snd_pcm_avail() returned a value that is exceptionally large: 89276 bytes (506 ms).
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: Most likely this is a bug in the ALSA driver 'snd_hda_intel'. Please report this issue to the ALSA developers.
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: snd_pcm_dump():
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: Soft volume PCM
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: Control: PCM Playback Volume
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: min_dB: -51
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: max_dB: 0
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: resolution: 256
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: Its setup is:
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   stream       : PLAYBACK
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   access       : MMAP_INTERLEAVED
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   format       : S16_LE
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   subformat    : STD
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   channels     : 2
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   rate         : 44100
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   exact rate   : 44100 (44100/1)
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   msbits       : 16
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   buffer_size  : 4416
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_size  : 736
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_time  : 16689
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   tstamp_mode  : ENABLE
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   tstamp_type  : MONOTONIC
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_step  : 1
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   avail_min    : 736
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_event : 1
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   start_threshold  : -1
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   stop_threshold   : 4971973988617027584
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   silence_threshold: 0
Jun 01 11:54:01 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   silence_size : 0
Jun 01 11:54:11 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 146.441 msecs
Jun 01 11:54:11 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 174.428 msecs
Jun 01 11:54:11 ryzhat kernel: perf: interrupt took too long (2107172 > 1431357), lowering kernel.perf_event_max_sample_rate to 300
Jun 01 11:54:11 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 176.238 msecs
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   boundary     : 4971973988617027584
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: Slave: Hardware PCM card 1 'HD-Audio Generic' device 0 subdevice 0
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c: Its setup is:
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   stream       : PLAYBACK
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   access       : MMAP_INTERLEAVED
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   format       : S16_LE
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   subformat    : STD
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   channels     : 2
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   rate         : 44100
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   exact rate   : 44100 (44100/1)
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   msbits       : 16
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   buffer_size  : 4416
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_size  : 736
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_time  : 16689
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   tstamp_mode  : ENABLE
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   tstamp_type  : MONOTONIC
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_step  : 1
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   avail_min    : 736
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   period_event : 1
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   start_threshold  : -1
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   stop_threshold   : 4971973988617027584
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   silence_threshold: 0
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   silence_size : 0
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   boundary     : 4971973988617027584
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   appl_ptr     : 274314225
Jun 01 11:54:11 ryzhat pulseaudio[936]: E: [alsa-sink-ALC1220 Analog] alsa-util.c:   hw_ptr       : 274332128
Jun 01 11:54:36 ryzhat kernel: [drm] Fence fallback timer expired on ring gfx_0.0.0
Jun 01 11:54:36 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 199.759 msecs
Jun 01 11:54:36 ryzhat kernel: perf: interrupt took too long (2691122 > 2633965), lowering kernel.perf_event_max_sample_rate to 300
Jun 01 11:54:36 ryzhat kernel: INFO: NMI handler (perf_event_nmi_handler) took too long to run: 194.806 msecs
Jun 01 11:54:36 ryzhat kernel: perf: interrupt took too long (3419207 > 3363902), lowering kernel.perf_event_max_sample_rate to 300
Jun 01 11:54:36 ryzhat kernel: [drm] Fence fallback timer expired on ring gfx_0.0.0
Jun 01 11:54:36 ryzhat kernel: [drm] Fence fallback timer expired on ring gfx_0.0.0
Jun 01 11:54:36 ryzhat kernel: [drm] Fence fallback timer expired on ring gfx_0.0.0
Jun 01 11:54:36 ryzhat kernel: NMI watchdog: Watchdog detected hard LOCKUP on cpu 15

Do you have any idea what logs I could check or what might be the problem?

Thanks!

Last edited by hatrix (2020-06-04 10:20:08)