search

raspberrypi / bookworm-feedback

13 stars 1 forks source link

[regression] disconnect/connect HDMI cable leads to no sound and some kind of wayfire reload #205

Open qrp73 opened 9 months ago

qrp73 commented 9 months ago

Steps to reproduce: 1) Connect USB sound card 2) Open VLC player and start playing some music 3) Disconnect HDMI cable and connect it again

Expected result: music should continue play even with no display and after reconnecting HDMI all should works as before, the music play should continue

Actual result: the sound immediately stops when HDMI cable is disconnected and didn't restored even after HDMI cable reconnected again. The apps which playing sounds cannot play sound anymore until app restart

In addition there some weird update of taskbar and background image, probably wayfire restart or something like that...

The sound works after reconnecting HDMI cable, but only for newly opened apps, for apps which continue to run it stops with no way to resume and requires restart the application in order to fix no sound issue.

The same issue with apps which playing sound through OpenAL.

Previously there was no such issue, all worked ok. This is regression issue. It starts to happens with some apt update, but I cannot say exactly how long ago it starts to happens, probably about week or one-two month ago...

Probably it caused with some fix for app close & logout issue which happened sometimes during HDMI cable reconnect.

spl237 commented 9 months ago

Try using the Advanced Options menu in raspi-config to switch the audio system to pulseaudio instead of pipewire - does that make a difference?

popcornmix commented 9 months ago

I could reproduce this. Switching to pulseaudio does not have the issue (so may be a temporary workaround). I could also observe this with a bluetooth device.

Looks like wireplumber (and then pipewire) is "stopped by signal" (not sure if this a crash, or deliberate shutdown based on the hdmi audio device disappearing).

Feb 06 15:04:12 pi5 wireplumber[1317]: stopped by signal: Terminated
Feb 06 15:04:12 pi5 systemd[823]: Stopping wireplumber.service -
Multimedia Service Session Manager...
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/ldac
Feb 06 15:04:12 pi5 wireplumber[1317]: disconnected from pipewire
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSink/aptx_hd
Feb 06 15:04:12 pi5 systemd[823]: Stopped wireplumber.service -
Multimedia Service Session Manager.
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/aptx_hd
Feb 06 15:04:12 pi5 systemd[823]: Stopping pipewire.service - PipeWire
Multimedia Service...
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSink/aptx
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/aptx
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSink/sbc
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/sbc
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSink/sbc_xq
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/sbc_xq
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/aptx_ll_1
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/aptx_ll_0
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_1
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_0
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/faststream
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/faststream_duplex
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSink/opus_05
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/opus_05
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSink/opus_05_duplex
Feb 06 15:04:12 pi5 bluetoothd[712]: Endpoint unregistered:
sender=:1.41 path=/MediaEndpoint/A2DPSource/opus_05_duplex
Feb 06 15:04:12 pi5 xdg-desktop-por[1095]: Caught PipeWire error:
connection error
Feb 06 15:04:12 pi5 xdg-desktop-portal-wlr[1340]: 2024/02/06 15:04:12
[ERROR] - pipewire: fatal error event from core
Feb 06 15:04:12 pi5 systemd[823]: Stopped pipewire.service - PipeWire
Multimedia Service.
Feb 06 15:04:12 pi5 systemd[823]: xdg-desktop-portal-wlr.service: Main
process exited, code=exited, status=1/FAILURE
Feb 06 15:04:12 pi5 systemd[823]: xdg-desktop-portal-wlr.service:
Failed with result 'exit-code'.
qrp73 commented 9 months ago

Try using the Advanced Options menu in raspi-config to switch the audio system to pulseaudio instead of pipewire - does that make a difference?

yes, when I switch to pulseaudio there is no such issue, but I need better control on sound card sample rate from user app to avoid unwanted resampling. In pipewire I just put all supported sample rate into /usr/share/pipewire/pipewire.conf as default.clock.allowed-rates = [ 44100, 48000, 96000, 192000, 384000 ] and it automatically switch sound card sample rate according to requested sample rate from app. For example If app requests 384 kHz, it switch sound card to 384 kHz, so there is no resampling with it's unwanted artifacts.

With pulseaudio I don't know how to achieve that. The only way that I find is to setup hardcoded sample rate in /etc/pulse/daemon.conf. But since I'm using different sample rate, it requires to reconfigure pulseaudio each time when I need to change sample rate in app...

Could you please fix pipewire service?

PS: also I found that pulseaudio has higher max sound volume, so it seems that something is wrong with sound volume when using pipeware service...

spl237 commented 9 months ago

Could you please fix pipewire service?

We're trying to - the problem is unfortunately not trivial.

apassu commented 9 months ago

I have a very similar problem with quite the same logs. I have a RPI 4 with an external HDMI monitor (with Alexa builts in the monitor) and when Alexa activates itself there is a crash of pipewire/wireplumber.

SYSTEMS INFO:

Raspberry PI 4 Raspbian 12 (bookworm) Linux rpi 6.1.0-rpi8-rpi-v8 https://github.com/raspberrypi/linux/issues/1 SMP PREEMPT Debian 1:6.1.73-1+rpt1 (2024-01-25) aarch64 GNU/Linux HDMI Monitor Samsung Smart Monitor M7 43" (with Alexa built in) raspi-config -> 6 Advanced Options -> A7 Audio Config -> 2 Pipewire

Steps to reproduce the behaviour The monitor has Alexa built in and when it activates itself there is a crash of pipewire/wireplumber.

LOGS:

2024-02-14T14:13:51.581663+01:00 rpi sh[4488]: Warning: ../src/main.c: 319: Selected output HDMI-A-1 went away 2024-02-14T14:13:51.585451+01:00 rpi sh[4488]: Warning: ../src/main.c: 330: No fallback outputs left. Detaching... 2024-02-14T14:13:52.479597+01:00 rpi wireplumber[5848]: stopped by signal: Terminated 2024-02-14T14:13:52.485894+01:00 rpi systemd[1750]: Stopping wireplumber.service - Multimedia Service Session Manager... 2024-02-14T14:13:52.537204+01:00 rpi wireplumber[5848]: disconnected from pipewire 2024-02-14T14:13:52.574138+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/ldac 2024-02-14T14:13:52.575006+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSink/aptx_hd 2024-02-14T14:13:52.576500+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/aptx_hd 2024-02-14T14:13:52.577591+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSink/aptx 2024-02-14T14:13:52.578691+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/aptx 2024-02-14T14:13:52.580101+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSink/sbc 2024-02-14T14:13:52.581530+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/sbc 2024-02-14T14:13:52.582848+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSink/sbc_xq 2024-02-14T14:13:52.584948+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/sbc_xq 2024-02-14T14:13:52.586777+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/aptx_ll_1 2024-02-14T14:13:52.588349+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/aptx_ll_0 2024-02-14T14:13:52.589798+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_1 2024-02-14T14:13:52.591290+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_0 2024-02-14T14:13:52.594042+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/faststream 2024-02-14T14:13:52.596998+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/faststream_duplex 2024-02-14T14:13:52.600100+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSink/opus_05 2024-02-14T14:13:52.601406+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/opus_05 2024-02-14T14:13:52.603212+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSink/opus_05_duplex 2024-02-14T14:13:52.603778+01:00 rpi systemd[1750]: Stopped wireplumber.service - Multimedia Service Session Manager. 2024-02-14T14:13:52.604164+01:00 rpi systemd[1750]: wireplumber.service: Consumed 1.551s CPU time. 2024-02-14T14:13:52.605368+01:00 rpi bluetoothd[911]: Endpoint unregistered: sender=:1.92 path=/MediaEndpoint/A2DPSource/opus_05_duplex 2024-02-14T14:13:52.609849+01:00 rpi systemd[1750]: Stopping pipewire.service - PipeWire Multimedia Service... 2024-02-14T14:13:52.637246+01:00 rpi xdg-desktop-portal-wlr[5866]: 2024/02/14 14:13:52 [ERROR] - pipewire: fatal error event from core 2024-02-14T14:13:52.638465+01:00 rpi xdg-desktop-por[2270]: Caught PipeWire error: connection error 2024-02-14T14:13:52.651400+01:00 rpi systemd[1750]: Stopped pipewire.service - PipeWire Multimedia Service. 2024-02-14T14:13:52.651914+01:00 rpi systemd[1750]: pipewire.service: Consumed 2.980s CPU time. 2024-02-14T14:13:52.685947+01:00 rpi systemd[1750]: Started pipewire.service - PipeWire Multimedia Service. 2024-02-14T14:13:52.692411+01:00 rpi systemd[1750]: Started wireplumber.service - Multimedia Service Session Manager. 2024-02-14T14:13:52.693201+01:00 rpi systemd[1750]: xdg-desktop-portal-wlr.service: Main process exited, code=exited, status=1/FAILURE 2024-02-14T14:13:52.693686+01:00 rpi systemd[1750]: xdg-desktop-portal-wlr.service: Failed with result 'exit-code'. 2024-02-14T14:13:52.880896+01:00 rpi systemd[1750]: xdg-desktop-portal-wlr.service: Scheduled restart job, restart counter is at 22. 2024-02-14T14:13:52.881560+01:00 rpi systemd[1750]: Stopped xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-14T14:13:52.913039+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5946 of process 5938 owned by '1000' high priority at nice level -11. 2024-02-14T14:13:52.914130+01:00 rpi rtkit-daemon[1336]: Supervising 1 threads of 1 processes of 1 users. 2024-02-14T14:13:52.914730+01:00 rpi systemd[1750]: Starting xdg-desktop-portal-wlr.service - Portal service (wlroots implementation)... 2024-02-14T14:13:52.935441+01:00 rpi sh[4488]: Warning: ../src/main.c: 319: Selected output NOOP-1 went away 2024-02-14T14:13:52.967394+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5947 of process 5942 owned by '1000' high priority at nice level -11. 2024-02-14T14:13:52.968354+01:00 rpi rtkit-daemon[1336]: Supervising 2 threads of 2 processes of 1 users. 2024-02-14T14:13:52.979169+01:00 rpi xdg-desktop-portal-wlr[5958]: 2024/02/14 14:13:52 [ERROR] - xdpw: Could not find render node 2024-02-14T14:13:52.980339+01:00 rpi xdg-desktop-portal-wlr[5958]: 2024/02/14 14:13:52 [ERROR] - xdpw: Could not find render node 2024-02-14T14:13:53.017015+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5950 of process 5938 owned by '1000' RT at priority 20. 2024-02-14T14:13:53.017898+01:00 rpi rtkit-daemon[1336]: Supervising 3 threads of 2 processes of 1 users. 2024-02-14T14:13:53.075491+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5948 of process 5942 owned by '1000' RT at priority 20. 2024-02-14T14:13:53.076219+01:00 rpi rtkit-daemon[1336]: Supervising 4 threads of 2 processes of 1 users. 2024-02-14T14:13:53.161644+01:00 rpi systemd[1750]: Started xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-14T14:13:53.203937+01:00 rpi kernel: [17287.577184] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 7184 (slots) 2024-02-14T14:13:53.341349+01:00 rpi wireplumber[5942]: [4:48:07.398713203] [5942] #33[1;32m INFO #33[1;37mCamera #33[1;34mcamera_manager.cpp:284 #33[0mlibcamera v0.1.0+118-563cd78e 2024-02-14T14:13:53.347852+01:00 rpi kernel: [17287.724462] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 7184 (slots) 2024-02-14T14:13:53.547171+01:00 rpi systemd[1750]: Stopping wireplumber.service - Multimedia Service Session Manager... 2024-02-14T14:13:54.554553+01:00 rpi wireplumber[5942]: stopped by signal: Terminated 2024-02-14T14:13:54.566114+01:00 rpi wireplumber[5942]: WpImplMetadata:0x55976ba3f0 Object activation aborted: PipeWire proxy destroyed 2024-02-14T14:13:54.569245+01:00 rpi wireplumber[5942]: failed to activate route-settings metadata: Object activation aborted: PipeWire proxy destroyed 2024-02-14T14:13:54.575536+01:00 rpi wireplumber[5942]: disconnected from pipewire 2024-02-14T14:13:54.625579+01:00 rpi systemd[1750]: Stopped wireplumber.service - Multimedia Service Session Manager. 2024-02-14T14:13:54.627562+01:00 rpi systemd[1750]: wireplumber.service: Consumed 1.398s CPU time. 2024-02-14T14:13:54.632763+01:00 rpi systemd[1750]: Stopping pipewire.service - PipeWire Multimedia Service... 2024-02-14T14:13:54.643623+01:00 rpi xdg-desktop-portal-wlr[5958]: 2024/02/14 14:13:54 [ERROR] - pipewire: fatal error event from core 2024-02-14T14:13:54.647259+01:00 rpi xdg-desktop-por[2270]: Caught PipeWire error: connection error 2024-02-14T14:13:54.662883+01:00 rpi systemd[1750]: Stopped pipewire.service - PipeWire Multimedia Service. 2024-02-14T14:13:54.715623+01:00 rpi systemd[1750]: Started pipewire.service - PipeWire Multimedia Service. 2024-02-14T14:13:54.728564+01:00 rpi systemd[1750]: Started wireplumber.service - Multimedia Service Session Manager. 2024-02-14T14:13:54.732058+01:00 rpi systemd[1750]: xdg-desktop-portal-wlr.service: Main process exited, code=exited, status=1/FAILURE 2024-02-14T14:13:54.733715+01:00 rpi systemd[1750]: xdg-desktop-portal-wlr.service: Failed with result 'exit-code'. 2024-02-14T14:13:54.882785+01:00 rpi systemd[1750]: xdg-desktop-portal-wlr.service: Scheduled restart job, restart counter is at 23. 2024-02-14T14:13:54.885922+01:00 rpi systemd[1750]: Stopped xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-14T14:13:54.931824+01:00 rpi systemd[1750]: Starting xdg-desktop-portal-wlr.service - Portal service (wlroots implementation)... 2024-02-14T14:13:54.940390+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5987 of process 5981 owned by '1000' high priority at nice level -11. 2024-02-14T14:13:54.941011+01:00 rpi rtkit-daemon[1336]: Supervising 1 threads of 1 processes of 1 users. 2024-02-14T14:13:55.023238+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5986 of process 5976 owned by '1000' high priority at nice level -11. 2024-02-14T14:13:55.024609+01:00 rpi rtkit-daemon[1336]: Supervising 2 threads of 2 processes of 1 users. 2024-02-14T14:13:55.034922+01:00 rpi xdg-desktop-portal-wlr[6000]: 2024/02/14 14:13:55 [ERROR] - xdpw: Could not find render node 2024-02-14T14:13:55.036145+01:00 rpi xdg-desktop-portal-wlr[6000]: 2024/02/14 14:13:55 [ERROR] - xdpw: Could not find render node 2024-02-14T14:13:55.108915+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5988 of process 5981 owned by '1000' RT at priority 20. 2024-02-14T14:13:55.110125+01:00 rpi rtkit-daemon[1336]: Supervising 3 threads of 2 processes of 1 users. 2024-02-14T14:13:55.204189+01:00 rpi rtkit-daemon[1336]: Successfully made thread 5989 of process 5976 owned by '1000' RT at priority 20. 2024-02-14T14:13:55.205438+01:00 rpi rtkit-daemon[1336]: Supervising 4 threads of 2 processes of 1 users. 2024-02-14T14:13:55.261598+01:00 rpi systemd[1750]: Started xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-14T14:13:55.485171+01:00 rpi wireplumber[5981]: [4:48:09.544493784] [5981] #33[1;32m INFO #33[1;37mCamera #33[1;34mcamera_manager.cpp:284 #33[0mlibcamera v0.1.0+118-563cd78e 2024-02-14T14:13:56.465094+01:00 rpi wireplumber[5981]: Trying to use legacy bluez5 API for LE Audio - only A2DP will be supported. Please upgrade bluez5. 2024-02-14T14:13:56.500484+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/ldac 2024-02-14T14:13:56.501811+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSink/aptx_hd 2024-02-14T14:13:56.502440+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/aptx_hd 2024-02-14T14:13:56.503315+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSink/aptx 2024-02-14T14:13:56.504046+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/aptx 2024-02-14T14:13:56.506558+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSink/sbc 2024-02-14T14:13:56.507318+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/sbc 2024-02-14T14:13:56.507910+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSink/sbc_xq 2024-02-14T14:13:56.508415+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/sbc_xq 2024-02-14T14:13:56.508892+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/aptx_ll_1 2024-02-14T14:13:56.509357+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/aptx_ll_0 2024-02-14T14:13:56.509850+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_1 2024-02-14T14:13:56.510521+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_0 2024-02-14T14:13:56.510963+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/faststream 2024-02-14T14:13:56.511445+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/faststream_duplex 2024-02-14T14:13:56.515440+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSink/opus_05 2024-02-14T14:13:56.516684+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/opus_05 2024-02-14T14:13:56.517647+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSink/opus_05_duplex 2024-02-14T14:13:56.518481+01:00 rpi bluetoothd[911]: Endpoint registered: sender=:1.94 path=/MediaEndpoint/A2DPSource/opus_05_duplex 2024-02-14T14:13:56.522852+01:00 rpi wireplumber[5981]: WpPortalPermissionStorePlugin:0x55948fa8d0 Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera 2024-02-14T14:13:56.587168+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video14' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.587832+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video15' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.588360+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video21' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.588743+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video22' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.674206+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video14' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.674863+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video15' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.675387+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video21' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.675888+01:00 rpi pipewire[5976]: spa.v4l2: '/dev/video22' VIDIOC_ENUM_FRAMEINTERVALS: Inappropriate ioctl for device 2024-02-14T14:13:56.708663+01:00 rpi wireplumber[5981]: WpPortalPermissionStorePlugin:0x55948fa8d0 Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera 2024-02-14T14:13:56.723855+01:00 rpi wireplumber[5981]: WpPortalPermissionStorePlugin:0x55948fa8d0 Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera 2024-02-14T14:13:56.734403+01:00 rpi wireplumber[5981]: WpPortalPermissionStorePlugin:0x55948fa8d0 Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera 2024-02-14T14:13:56.742703+01:00 rpi wireplumber[5981]: WpPortalPermissionStorePlugin:0x55948fa8d0 Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for camera 2024-02-14T14:14:02.708043+01:00 rpi bluetoothd[911]: src/profile.c:record_cb() Unable to get Hands-Free Voice gateway SDP record: Host is down

After i switched to: raspi-config -> 6 Advanced Options -> A7 Audio Config -> 1 Pulse Audio

and when Alexa activated itself the logs were:

2024-02-19T21:48:45.120582+01:00 rpi sh[929]: Warning: ../src/main.c: 319: Selected output HDMI-A-1 went away 2024-02-19T21:48:45.121308+01:00 rpi sh[929]: Warning: ../src/main.c: 330: No fallback outputs left. Detaching... 2024-02-19T21:48:46.143632+01:00 rpi xdg-desktop-por[2652]: Caught PipeWire error: connection error 2024-02-19T21:48:46.146523+01:00 rpi systemd[1711]: Stopping pipewire.service - PipeWire Multimedia Service... 2024-02-19T21:48:46.153722+01:00 rpi systemd[1711]: Stopped pipewire.service - PipeWire Multimedia Service. 2024-02-19T21:48:46.155775+01:00 rpi xdg-desktop-portal-wlr[4100]: 2024/02/19 21:48:46 [ERROR] - pipewire: fatal error event from core 2024-02-19T21:48:46.192619+01:00 rpi systemd[1711]: Started pipewire.service - PipeWire Multimedia Service. 2024-02-19T21:48:46.194721+01:00 rpi systemd[1711]: xdg-desktop-portal-wlr.service: Main process exited, code=exited, status=1/FAILURE 2024-02-19T21:48:46.195500+01:00 rpi systemd[1711]: xdg-desktop-portal-wlr.service: Failed with result 'exit-code'. 2024-02-19T21:48:46.385439+01:00 rpi rtkit-daemon[1306]: Successfully made thread 4220 of process 4213 owned by '1000' high priority at nice level -11. 2024-02-19T21:48:46.386258+01:00 rpi rtkit-daemon[1306]: Supervising 1 threads of 1 processes of 1 users. 2024-02-19T21:48:46.434303+01:00 rpi rtkit-daemon[1306]: Successfully made thread 4221 of process 4213 owned by '1000' RT at priority 20. 2024-02-19T21:48:46.434535+01:00 rpi rtkit-daemon[1306]: Supervising 2 threads of 1 processes of 1 users. 2024-02-19T21:48:46.507804+01:00 rpi systemd[1711]: xdg-desktop-portal-wlr.service: Scheduled restart job, restart counter is at 4. 2024-02-19T21:48:46.508379+01:00 rpi systemd[1711]: Stopped xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-19T21:48:46.537332+01:00 rpi sh[929]: Warning: ../src/main.c: 319: Selected output NOOP-1 went away 2024-02-19T21:48:46.539068+01:00 rpi systemd[1711]: Starting xdg-desktop-portal-wlr.service - Portal service (wlroots implementation)... 2024-02-19T21:48:46.595887+01:00 rpi xdg-desktop-portal-wlr[4235]: 2024/02/19 21:48:46 [ERROR] - xdpw: Could not find render node 2024-02-19T21:48:46.596931+01:00 rpi xdg-desktop-portal-wlr[4235]: 2024/02/19 21:48:46 [ERROR] - xdpw: Could not find render node 2024-02-19T21:48:46.742459+01:00 rpi systemd[1711]: Started xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-19T21:48:46.768262+01:00 rpi kernel: [ 569.860018] swiotlb_tbl_map_single: 8 callbacks suppressed 2024-02-19T21:48:46.768314+01:00 rpi kernel: [ 569.860040] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 378 (slots) 2024-02-19T21:48:46.832275+01:00 rpi kernel: [ 569.920288] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 336 (slots) 2024-02-19T21:48:46.884318+01:00 rpi kernel: [ 569.974447] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 920 (slots) 2024-02-19T21:48:47.108221+01:00 rpi kernel: [ 570.199867] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 378 (slots) 2024-02-19T21:48:47.156548+01:00 rpi systemd[1711]: Stopping pipewire.service - PipeWire Multimedia Service... 2024-02-19T21:48:47.159269+01:00 rpi xdg-desktop-por[2652]: Caught PipeWire error: connection error 2024-02-19T21:48:47.160818+01:00 rpi xdg-desktop-portal-wlr[4235]: 2024/02/19 21:48:47 [ERROR] - pipewire: fatal error event from core 2024-02-19T21:48:47.175348+01:00 rpi systemd[1711]: Stopped pipewire.service - PipeWire Multimedia Service. 2024-02-19T21:48:47.192341+01:00 rpi kernel: [ 570.280900] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 336 (slots) 2024-02-19T21:48:47.202429+01:00 rpi systemd[1711]: Started pipewire.service - PipeWire Multimedia Service. 2024-02-19T21:48:47.205426+01:00 rpi systemd[1711]: xdg-desktop-portal-wlr.service: Main process exited, code=exited, status=1/FAILURE 2024-02-19T21:48:47.206341+01:00 rpi systemd[1711]: xdg-desktop-portal-wlr.service: Failed with result 'exit-code'. 2024-02-19T21:48:47.341622+01:00 rpi rtkit-daemon[1306]: Successfully made thread 4248 of process 4242 owned by '1000' high priority at nice level -11. 2024-02-19T21:48:47.341994+01:00 rpi rtkit-daemon[1306]: Supervising 1 threads of 1 processes of 1 users. 2024-02-19T21:48:47.378992+01:00 rpi rtkit-daemon[1306]: Successfully made thread 4249 of process 4242 owned by '1000' RT at priority 20. 2024-02-19T21:48:47.379277+01:00 rpi rtkit-daemon[1306]: Supervising 2 threads of 1 processes of 1 users. 2024-02-19T21:48:47.436232+01:00 rpi kernel: [ 570.526019] vc4-drm gpu: swiotlb buffer is full (sz: 524288 bytes), total 32768 (slots), used 920 (slots) 2024-02-19T21:48:47.507775+01:00 rpi systemd[1711]: xdg-desktop-portal-wlr.service: Scheduled restart job, restart counter is at 5. 2024-02-19T21:48:47.508412+01:00 rpi systemd[1711]: Stopped xdg-desktop-portal-wlr.service - Portal service (wlroots implementation). 2024-02-19T21:48:47.537987+01:00 rpi systemd[1711]: Starting xdg-desktop-portal-wlr.service - Portal service (wlroots implementation)... 2024-02-19T21:48:47.614274+01:00 rpi xdg-desktop-portal-wlr[4260]: 2024/02/19 21:48:47 [ERROR] - xdpw: Could not find render node 2024-02-19T21:48:47.616248+01:00 rpi xdg-desktop-portal-wlr[4260]: 2024/02/19 21:48:47 [ERROR] - xdpw: Could not find render node 2024-02-19T21:48:47.723053+01:00 rpi systemd[1711]: Started xdg-desktop-portal-wlr.service - Portal service (wlroots implementation).

I noticed that, even if I switched to Pulse Audio, the pipewire service is enabled and active:

$ systemctl list-units --user | grep wire pipewire.service loaded active running PipeWire Multimedia Service pipewire.socket loaded active running PipeWire Multimedia System Socket