Hello all,
I switched a couple of times between the branches testing
and stable
as per the Wiki.
I did overwrite some files that seemed related to alsa when downgrading (testing → stable) the first time. Sound used to be working, not anymore
aplay -l
:
**** List of PLAYBACK Hardware Devices ****
card 0: PCH [HDA Intel PCH], device 0: ALC1220 Analog [ALC1220 Analog]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 0: PCH [HDA Intel PCH], device 1: ALC1220 Digital [ALC1220 Digital]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: NVidia [HDA NVidia], device 3: HDMI 0 [HDMI 0]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: NVidia [HDA NVidia], device 7: HDMI 1 [HDMI 1]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: NVidia [HDA NVidia], device 8: HDMI 2 [HDMI 2]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: NVidia [HDA NVidia], device 9: HDMI 3 [HDMI 3]
Subdevices: 1/1
Subdevice #0: subdevice #0
At some point, I tried unloading/reloading the related sound driver rmmod / moddprobe
while monitoring the output, and this is the log I managed to capture:
journalctl -f
# In separate terminal
rmmod snd_hda_intel
modprobe snd_hda_intel
Output:
Oct 29 14:39:21 linux-main systemd[1364]: Started VTE child process 4908 launched by terminator process 4383.
Oct 29 14:39:28 linux-main audit[5104]: USER_AUTH pid=5104 uid=1000 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="argo" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main dbus-daemon[578]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.239' (uid=0 pid=5104 comm="sudo rmmod snd_hda_intel ")
Oct 29 14:39:28 linux-main kernel: audit: type=1100 audit(1603953568.488:69): pid=5104 uid=1000 auid=1000 ses=2 msg='op=PAM:authentication grantors=pam_faillock,pam_permit,pam_faillock acct="argo" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main kernel: audit: type=1101 audit(1603953568.492:70): pid=5104 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_permit,pam_time acct="argo" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main audit[5104]: USER_ACCT pid=5104 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_permit,pam_time acct="argo" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main audit[5104]: CRED_REFR pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main dbus-daemon[578]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Oct 29 14:39:28 linux-main sudo[5104]: pam_systemd_home(sudo:account): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Oct 29 14:39:28 linux-main sudo[5104]: argo : TTY=pts/2 ; PWD=/home/argo ; USER=root ; COMMAND=/usr/bin/rmmod snd_hda_intel
Oct 29 14:39:28 linux-main kernel: audit: type=1110 audit(1603953568.492:71): pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main sudo[5104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 29 14:39:28 linux-main audit[5104]: USER_START pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main kernel: audit: type=1105 audit(1603953568.498:72): pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main sudo[5104]: pam_unix(sudo:session): session closed for user root
Oct 29 14:39:28 linux-main audit[5104]: USER_END pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main audit[5104]: CRED_DISP pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main kernel: audit: type=1106 audit(1603953568.972:73): pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:28 linux-main kernel: audit: type=1104 audit(1603953568.972:74): pid=5104 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:32 linux-main plasmashell[1546]: kf.plasma.core: findInCache with a lastModified timestamp of 0 is deprecated
Oct 29 14:39:38 linux-main plasmashell[1546]: kf.plasma.core: findInCache with a lastModified timestamp of 0 is deprecated
Oct 29 14:39:46 linux-main dbus-daemon[578]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.240' (uid=0 pid=5145 comm="sudo modprobe snd_hda_intel ")
Oct 29 14:39:46 linux-main dbus-daemon[578]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Oct 29 14:39:46 linux-main sudo[5145]: pam_systemd_home(sudo:account): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Oct 29 14:39:46 linux-main kernel: audit: type=1101 audit(1603953586.455:75): pid=5145 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_permit,pam_time acct="argo" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main kernel: audit: type=1110 audit(1603953586.455:76): pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main audit[5145]: USER_ACCT pid=5145 uid=1000 auid=1000 ses=2 msg='op=PAM:accounting grantors=pam_permit,pam_time acct="argo" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main audit[5145]: CRED_REFR pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main sudo[5145]: argo : TTY=pts/2 ; PWD=/home/argo ; USER=root ; COMMAND=/usr/bin/modprobe snd_hda_intel
Oct 29 14:39:46 linux-main sudo[5145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Oct 29 14:39:46 linux-main kernel: audit: type=1105 audit(1603953586.462:77): pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main audit[5145]: USER_START pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:session_open grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main sudo[5145]: pam_unix(sudo:session): session closed for user root
Oct 29 14:39:46 linux-main audit[5145]: USER_END pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main audit[5145]: CRED_DISP pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main kernel: snd_hda_intel 0000:01:00.1: Disabling MSI
Oct 29 14:39:46 linux-main kernel: snd_hda_intel 0000:01:00.1: Handle vga_switcheroo audio client
Oct 29 14:39:46 linux-main kernel: audit: type=1106 audit(1603953586.475:78): pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:session_close grantors=pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main kernel: audit: type=1104 audit(1603953586.475:79): pid=5145 uid=0 auid=1000 ses=2 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_env,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/2 res=success'
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC1220: line_outs=3 (0x14/0x15/0x16/0x0/0x0) type:line
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: hp_outs=1 (0x1b/0x0/0x0/0x0/0x0)
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: mono: mono_out=0x0
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: dig-out=0x1e/0x0
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: inputs:
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: Front Mic=0x19
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: Rear Mic=0x18
Oct 29 14:39:46 linux-main kernel: snd_hda_codec_realtek hdaudioC0D0: Line=0x1a
Oct 29 14:39:46 linux-main systemd[1364]: Reached target Sound Card.
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Front Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input55
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Rear Mic as /devices/pci0000:00/0000:00:1f.3/sound/card0/input56
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Line as /devices/pci0000:00/0000:00:1f.3/sound/card0/input57
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Line Out Front as /devices/pci0000:00/0000:00:1f.3/sound/card0/input58
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Line Out Surround as /devices/pci0000:00/0000:00:1f.3/sound/card0/input59
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Line Out CLFE as /devices/pci0000:00/0000:00:1f.3/sound/card0/input60
Oct 29 14:39:46 linux-main kernel: input: HDA Intel PCH Front Headphone as /devices/pci0000:00/0000:00:1f.3/sound/card0/input61
Oct 29 14:39:46 linux-main kernel: pipewire-media-[1714]: segfault at 50 ip 00007f714c9a4590 sp 00007ffe47524298 error 4 in libpipewire-0.3.so.0.313.0[7f714c972000+3f000]
Oct 29 14:39:46 linux-main kernel: Code: 66 2e 0f 1f 84 00 00 00 00 00 48 8b 47 20 48 85 c0 74 10 48 8b 80 58 01 00 00 48 85 c0 74 04 48 8b 40 10 c3 66 0f 1f 44 00 00 <48> 8b 47 50 48 89 56 10 48 89 4e 18 48 8b 10 48 89 46 08 48 89 16
Oct 29 14:39:46 linux-main kernel: audit: type=1701 audit(1603953586.578:80): auid=1000 uid=1000 gid=1000 ses=3 pid=1714 comm="pipewire-media-" exe="/usr/bin/pipewire-media-session" sig=11 res=1
Oct 29 14:39:46 linux-main audit[1714]: ANOM_ABEND auid=1000 uid=1000 gid=1000 ses=3 pid=1714 comm="pipewire-media-" exe="/usr/bin/pipewire-media-session" sig=11 res=1
Oct 29 14:39:46 linux-main systemd[1]: Created slice system-systemd\x2dcoredump.slice.
Oct 29 14:39:46 linux-main systemd[1]: Started Process Core Dump (PID 5164/UID 0).
Oct 29 14:39:46 linux-main audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-5164-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 29 14:39:46 linux-main kernel: audit: type=1130 audit(1603953586.595:81): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-5164-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 29 14:39:46 linux-main bluetoothd[1072]: Endpoint unregistered: sender=:1.54 path=/A2DP/SBC/Source/0
Oct 29 14:39:46 linux-main pulseaudio[1990]: Failed to load module "module-alsa-card" (argument: "device_id="0" name="pci-0000_00_1f.3" card_name="alsa_card.pci-0000_00_1f.3" namereg_fail=false tsched=yes fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Oct 29 14:39:46 linux-main bluetoothd[1072]: Endpoint unregistered: sender=:1.54 path=/A2DP/SBC/Sink/1
Oct 29 14:39:46 linux-main bluetoothd[1072]: Endpoint unregistered: sender=:1.54 path=/A2DP/SBC/Source/2
Oct 29 14:39:46 linux-main bluetoothd[1072]: Endpoint unregistered: sender=:1.54 path=/A2DP/SBC/Sink/3
Oct 29 14:39:46 linux-main systemd-coredump[5165]: [🡕] Process 1714 (pipewire-media-) of user 1000 dumped core.
Stack trace of thread 1714:
#0 0x00007f714c9a4590 pw_proxy_add_listener (libpipewire-0.3.so.0 + 0x55590)
#1 0x00005629d469173f n/a (pipewire-media-session + 0x1773f)
#2 0x00005629d468bde7 n/a (pipewire-media-session + 0x11de7)
#3 0x00007f714c9217d6 n/a (libdbus-1.so.3 + 0x277d6)
#4 0x00007f714c9113bd dbus_connection_dispatch (libdbus-1.so.3 + 0x173bd)
#5 0x00007f714ca0f1a7 n/a (libspa-dbus.so + 0x11a7)
#6 0x00007f714ca1a38b n/a (libspa-support.so + 0x738b)
#7 0x00007f714c98b2cb pw_main_loop_run (libpipewire-0.3.so.0 + 0x3c2cb)
#8 0x00005629d468983d n/a (pipewire-media-session + 0xf83d)
#9 0x00007f714c759152 __libc_start_main (libc.so.6 + 0x28152)
#10 0x00005629d4689b9e n/a (pipewire-media-session + 0xfb9e)
Stack trace of thread 1716:
#0 0x00007f714c8315de epoll_wait (libc.so.6 + 0x1005de)
#1 0x00007f714ca22fc1 n/a (libspa-support.so + 0xffc1)
#2 0x00007f714ca1a2f4 n/a (libspa-support.so + 0x72f4)
#3 0x00007f714c975fb0 n/a (libpipewire-0.3.so.0 + 0x26fb0)
#4 0x00007f714c7123e9 start_thread (libpthread.so.0 + 0x93e9)
#5 0x00007f714c831293 __clone (libc.so.6 + 0x100293)
Oct 29 14:39:46 linux-main systemd[1]: systemd-coredump@0-5164-0.service: Succeeded.
Oct 29 14:39:46 linux-main kernel: audit: type=1131 audit(1603953586.832:82): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-5164-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 29 14:39:46 linux-main audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@0-5164-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 29 14:39:48 linux-main kernel: input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input62
Oct 29 14:39:48 linux-main kernel: input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input63
Oct 29 14:39:48 linux-main kernel: input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input64
Oct 29 14:39:48 linux-main kernel: input: HDA NVidia HDMI/DP,pcm=9 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input65
Oct 29 14:39:48 linux-main pulseaudio[1990]: Failed to find a working profile.
Oct 29 14:39:48 linux-main pulseaudio[1990]: Failed to load module "module-alsa-card" (argument: "device_id="1" name="pci-0000_01_00.1" card_name="alsa_card.pci-0000_01_00.1" namereg_fail=false tsched=yes fixed_latency_range=no ignore_dB=no deferred_volume=yes use_ucm=yes avoid_resampling=no card_properties="module-udev-detect.discovered=1""): initialization failed.
Oct 29 14:40:42 linux-main plasmashell[1546]: kf.plasma.core: findInCache with a lastModified timestamp of 0 is deprecated
Oct 29 14:41:51 linux-main plasmashell[1546]: kf.plasma.core: findInCache with a lastModified timestamp of 0 is deprecated
Oct 29 14:41:57 linux-main systemd[1364]: Started PulseAudio Volume Control - Volume Control.
Oct 29 14:42:01 linux-main kwin_x11[1474]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 26391, resource id: 60817414, major code: 15 (QueryTree), minor code: 0
Oct 29 14:42:01 linux-main systemd[1364]: app-pavucontrol\x2dqt-fafae1e2223a430e996be0bf7d62863c.scope: Succeeded.
Oct 29 14:42:01 linux-main kwin_x11[1474]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 26492, resource id: 60817424, major code: 18 (ChangeProperty), minor code: 0
Oct 29 14:42:01 linux-main kwin_x11[1474]: qt.qpa.xcb: QXcbConnection: XCB error: 3 (BadWindow), sequence: 26496, resource id: 33554598, major code: 18 (ChangeProperty), minor code: 0
So definitely something here.
fuser -v /dev/snd/*
USER PID ACCESS COMMAND
/dev/snd/seq: argo 1706 F.... pipewire
Not sure what this one does or means.
I am at a loss.