Mar 29 21:43:06 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:43:06+02:00" level=trace msg="sent dealer ping" Mar 29 21:43:06 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:43:06+02:00" level=trace msg="received dealer pong" Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: CALLMETHOD: user_interface touch_display saveHDMIAudioConf [object Object] Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveHDMIAudioConf Mar 29 21:43:14 volumiorpi5 sudo[4242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp /opt/volumiokiosk.sh /opt/volumiokiosk.sh.backup Mar 29 21:43:14 volumiorpi5 sudo[4242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:14 volumiorpi5 sudo[4242]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: touch_display: volumiokiosk.sh backed up successfully. Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: touch_display: Found 1 active display(s). Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: touch_display: No touch devices detected. Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: touch_display: No touch devices found, skipping touch calibration. Mar 29 21:43:14 volumiorpi5 sudo[4268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i '/# HDMI_AUDIO_KEEPALIVE/d' /opt/volumiokiosk.sh Mar 29 21:43:14 volumiorpi5 sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:14 volumiorpi5 sudo[4268]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:14 volumiorpi5 sudo[4272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i '/\\/usr\\/bin\\/chromium-browser/i export DISPLAY=:0 # HDMI_AUDIO_KEEPALIVE\\ntimeout 5 bash -c \'until xrandr >/dev/null 2>&1; do sleep 0.5; done\' # HDMI_AUDIO_KEEPALIVE\\nxrandr --output HDMI-1 --mode 1920x1200 --primary --pos 0x0 # HDMI_AUDIO_KEEPALIVE\\nxrandr --output HDMI-1 --mode 1920x1080 --pos 1920x0 # HDMI_AUDIO_KEEPALIVE\\nsleep 1 # HDMI_AUDIO_KEEPALIVE' /opt/volumiokiosk.sh Mar 29 21:43:14 volumiorpi5 sudo[4272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:14 volumiorpi5 sudo[4272]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:14 volumiorpi5 volumio[1400]: info: touch_display: HDMI audio keep-alive commands inserted into volumiokiosk.sh for HDMI-1 Mar 29 21:43:14 volumiorpi5 sudo[4275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Mar 29 21:43:14 volumiorpi5 sudo[4275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:14 volumiorpi5 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 21:43:14 volumiorpi5 startx[4009]: xinit: connection to X server lost Mar 29 21:43:14 volumiorpi5 startx[4009]: Mar 29 21:43:15 volumiorpi5 startx[4009]: waiting for X server to shut down Mar 29 21:43:15 volumiorpi5 startx[4010]: (II) Server terminated successfully (0). Closing log file. Mar 29 21:43:15 volumiorpi5 startx[4009]: xinit: unexpected signal 15 Mar 29 21:43:15 volumiorpi5 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 29 21:43:15 volumiorpi5 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 29 21:43:15 volumiorpi5 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:15 volumiorpi5 systemd[1]: volumio-kiosk.service: Consumed 1.963s CPU time. Mar 29 21:43:15 volumiorpi5 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:15 volumiorpi5 sudo[4275]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:15 volumiorpi5 volumio[1400]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Mar 29 21:43:15 volumiorpi5 volumio[1400]: info: touch_display: Volumio Kiosk restarted. Mar 29 21:43:15 volumiorpi5 volumio[1400]: info: touch_display: X display number found: Mar 29 21:43:16 volumiorpi5 startx[4306]: X.Org X Server 1.21.1.7 Mar 29 21:43:16 volumiorpi5 startx[4306]: X Protocol Version 11, Revision 0 Mar 29 21:43:16 volumiorpi5 startx[4306]: Current Operating System: Linux volumiorpi5 6.12.74-v8+ #1948 SMP PREEMPT Mon Mar 2 11:30:34 GMT 2026 aarch64 Mar 29 21:43:16 volumiorpi5 startx[4306]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=88:A2:9E:5B:C1:13 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 29 21:43:16 volumiorpi5 startx[4306]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 29 21:43:16 volumiorpi5 startx[4306]: Current version of pixman: 0.44.0 Mar 29 21:43:16 volumiorpi5 startx[4306]: Before reporting problems, check http://wiki.x.org Mar 29 21:43:16 volumiorpi5 startx[4306]: to make sure that you have the latest version. Mar 29 21:43:16 volumiorpi5 startx[4306]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 29 21:43:16 volumiorpi5 startx[4306]: (++) from command line, (!!) notice, (II) informational, Mar 29 21:43:16 volumiorpi5 startx[4306]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 29 21:43:16 volumiorpi5 startx[4306]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Mar 29 21:43:15 2026 Mar 29 21:43:16 volumiorpi5 startx[4306]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 29 21:43:16 volumiorpi5 startx[4306]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: touch_display: HDMI audio configuration already present in volumiokiosk.sh Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioGetState Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: CorePlayQueue::getTrack 0 Mar 29 21:43:16 volumiorpi5 volumio[1400]: info: touch_display: Setting screensaver timeout to 365 seconds. Mar 29 21:43:18 volumiorpi5 kernel: usb 3-2: new full-speed USB device number 4 using xhci-hcd Mar 29 21:43:18 volumiorpi5 kernel: usb 3-2: New USB device found, idVendor=eeef, idProduct=2828, bcdDevice= 0.00 Mar 29 21:43:18 volumiorpi5 kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 29 21:43:18 volumiorpi5 kernel: usb 3-2: Product: CTouch Mar 29 21:43:18 volumiorpi5 kernel: usb 3-2: Manufacturer: TSTP Mar 29 21:43:18 volumiorpi5 kernel: usb 3-2: SerialNumber: CMTP_1.0 Mar 29 21:43:18 volumiorpi5 kernel: input: TSTP CTouch as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:EEEF:2828.0004/input/input9 Mar 29 21:43:18 volumiorpi5 kernel: hid-multitouch 0003:EEEF:2828.0004: input,hidraw1: USB HID v1.11 Device [TSTP CTouch] on usb-xhci-hcd.1-2/input0 Mar 29 21:43:18 volumiorpi5 kernel: input: TSTP CTouch as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.1/0003:EEEF:2828.0005/input/input10 Mar 29 21:43:18 volumiorpi5 kernel: hid-generic 0003:EEEF:2828.0005: input,hidraw2: USB HID v1.11 Keyboard [TSTP CTouch] on usb-xhci-hcd.1-2/input1 Mar 29 21:43:18 volumiorpi5 systemd-logind[1030]: Watching system buttons on /dev/input/event7 (TSTP CTouch) Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: CALLMETHOD: user_interface touch_display saveHDMIAudioConf [object Object] Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveHDMIAudioConf Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: touch_display: Backup of volumiokiosk.sh already exists. Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: touch_display: Found 1 active display(s). Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: touch_display: Touch device detected: TSTP CTouch (id=12) Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: touch_display: 1 touch device(s) will be mapped to HDMI-1 Mar 29 21:43:23 volumiorpi5 sudo[4564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i '/# HDMI_AUDIO_KEEPALIVE/d' /opt/volumiokiosk.sh Mar 29 21:43:23 volumiorpi5 sudo[4564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:23 volumiorpi5 sudo[4564]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:23 volumiorpi5 sudo[4568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i '/\\/usr\\/bin\\/chromium-browser/i export DISPLAY=:0 # HDMI_AUDIO_KEEPALIVE\\ntimeout 5 bash -c \'until xrandr >/dev/null 2>&1; do sleep 0.5; done\' # HDMI_AUDIO_KEEPALIVE\\nxrandr --output HDMI-1 --mode 1920x1080 --primary --pos 0x0 # HDMI_AUDIO_KEEPALIVE\\nxrandr --output HDMI-2 --mode 1920x1080 --pos 1920x0 # HDMI_AUDIO_KEEPALIVE\\nsleep 1 # HDMI_AUDIO_KEEPALIVE\\nxinput map-to-output 12 HDMI-1 # HDMI_AUDIO_KEEPALIVE' /opt/volumiokiosk.sh Mar 29 21:43:23 volumiorpi5 sudo[4568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:23 volumiorpi5 sudo[4568]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:23 volumiorpi5 volumio[1400]: info: touch_display: HDMI audio keep-alive commands inserted into volumiokiosk.sh for HDMI-2 Mar 29 21:43:23 volumiorpi5 sudo[4571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Mar 29 21:43:23 volumiorpi5 sudo[4571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:23 volumiorpi5 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 21:43:23 volumiorpi5 startx[4305]: xinit: connection to X server lost Mar 29 21:43:23 volumiorpi5 startx[4305]: Mar 29 21:43:23 volumiorpi5 startx[4305]: waiting for X server to shut down Mar 29 21:43:23 volumiorpi5 startx[4306]: (II) Server terminated successfully (0). Closing log file. Mar 29 21:43:24 volumiorpi5 startx[4305]: xinit: unexpected signal 15 Mar 29 21:43:24 volumiorpi5 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 29 21:43:24 volumiorpi5 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 29 21:43:24 volumiorpi5 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:24 volumiorpi5 systemd[1]: volumio-kiosk.service: Consumed 2.042s CPU time. Mar 29 21:43:24 volumiorpi5 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:24 volumiorpi5 sudo[4571]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: Volumio Kiosk restarted. Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: X display number found: Mar 29 21:43:24 volumiorpi5 startx[4603]: X.Org X Server 1.21.1.7 Mar 29 21:43:24 volumiorpi5 startx[4603]: X Protocol Version 11, Revision 0 Mar 29 21:43:24 volumiorpi5 startx[4603]: Current Operating System: Linux volumiorpi5 6.12.74-v8+ #1948 SMP PREEMPT Mon Mar 2 11:30:34 GMT 2026 aarch64 Mar 29 21:43:24 volumiorpi5 startx[4603]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=88:A2:9E:5B:C1:13 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 29 21:43:24 volumiorpi5 startx[4603]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 29 21:43:24 volumiorpi5 startx[4603]: Current version of pixman: 0.44.0 Mar 29 21:43:24 volumiorpi5 startx[4603]: Before reporting problems, check http://wiki.x.org Mar 29 21:43:24 volumiorpi5 startx[4603]: to make sure that you have the latest version. Mar 29 21:43:24 volumiorpi5 startx[4603]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 29 21:43:24 volumiorpi5 startx[4603]: (++) from command line, (!!) notice, (II) informational, Mar 29 21:43:24 volumiorpi5 startx[4603]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 29 21:43:24 volumiorpi5 startx[4603]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Mar 29 21:43:24 2026 Mar 29 21:43:24 volumiorpi5 startx[4603]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 29 21:43:24 volumiorpi5 startx[4603]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: HDMI audio configuration already present in volumiokiosk.sh Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioGetState Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: CorePlayQueue::getTrack 0 Mar 29 21:43:24 volumiorpi5 volumio[1400]: info: touch_display: Setting screensaver timeout to 365 seconds. Mar 29 21:43:34 volumiorpi5 volumio[1400]: info: CALLMETHOD: user_interface touch_display saveHDMIAudioConf [object Object] Mar 29 21:43:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveHDMIAudioConf Mar 29 21:43:34 volumiorpi5 volumio[1400]: info: touch_display: Backup of volumiokiosk.sh already exists. Mar 29 21:43:34 volumiorpi5 sudo[4818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i '/# HDMI_AUDIO_KEEPALIVE/d' /opt/volumiokiosk.sh Mar 29 21:43:34 volumiorpi5 sudo[4818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:34 volumiorpi5 sudo[4818]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:34 volumiorpi5 volumio[1400]: info: touch_display: HDMI audio keep-alive commands removed from volumiokiosk.sh. Mar 29 21:43:34 volumiorpi5 sudo[4821]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Mar 29 21:43:34 volumiorpi5 sudo[4821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:34 volumiorpi5 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 21:43:34 volumiorpi5 startx[4602]: xinit: connection to X server lost Mar 29 21:43:34 volumiorpi5 startx[4602]: Mar 29 21:43:34 volumiorpi5 startx[4602]: waiting for X server to shut down Mar 29 21:43:34 volumiorpi5 startx[4603]: (II) Server terminated successfully (0). Closing log file. Mar 29 21:43:35 volumiorpi5 startx[4602]: xinit: unexpected signal 15 Mar 29 21:43:35 volumiorpi5 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 29 21:43:35 volumiorpi5 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 29 21:43:35 volumiorpi5 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:35 volumiorpi5 systemd[1]: volumio-kiosk.service: Consumed 1.940s CPU time. Mar 29 21:43:35 volumiorpi5 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:35 volumiorpi5 sudo[4821]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:35 volumiorpi5 volumio[1400]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Mar 29 21:43:35 volumiorpi5 volumio[1400]: info: touch_display: Volumio Kiosk restarted. Mar 29 21:43:35 volumiorpi5 volumio[1400]: info: touch_display: X display number found: Mar 29 21:43:35 volumiorpi5 startx[4851]: X.Org X Server 1.21.1.7 Mar 29 21:43:35 volumiorpi5 startx[4851]: X Protocol Version 11, Revision 0 Mar 29 21:43:35 volumiorpi5 startx[4851]: Current Operating System: Linux volumiorpi5 6.12.74-v8+ #1948 SMP PREEMPT Mon Mar 2 11:30:34 GMT 2026 aarch64 Mar 29 21:43:35 volumiorpi5 startx[4851]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=88:A2:9E:5B:C1:13 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 29 21:43:35 volumiorpi5 startx[4851]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 29 21:43:35 volumiorpi5 startx[4851]: Current version of pixman: 0.44.0 Mar 29 21:43:35 volumiorpi5 startx[4851]: Before reporting problems, check http://wiki.x.org Mar 29 21:43:35 volumiorpi5 startx[4851]: to make sure that you have the latest version. Mar 29 21:43:35 volumiorpi5 startx[4851]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 29 21:43:35 volumiorpi5 startx[4851]: (++) from command line, (!!) notice, (II) informational, Mar 29 21:43:35 volumiorpi5 startx[4851]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 29 21:43:35 volumiorpi5 startx[4851]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Mar 29 21:43:35 2026 Mar 29 21:43:35 volumiorpi5 startx[4851]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 29 21:43:35 volumiorpi5 startx[4851]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 29 21:43:35 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:35 volumiorpi5 volumio[1400]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 29 21:43:35 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:36 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioGetState Mar 29 21:43:36 volumiorpi5 volumio[1400]: info: CorePlayQueue::getTrack 0 Mar 29 21:43:36 volumiorpi5 volumio[1400]: info: touch_display: Setting screensaver timeout to 365 seconds. Mar 29 21:43:36 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:43:36+02:00" level=trace msg="sent dealer ping" Mar 29 21:43:36 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:43:36+02:00" level=trace msg="received dealer pong" Mar 29 21:43:43 volumiorpi5 volumio[1400]: info: CALLMETHOD: user_interface touch_display saveHDMIAudioConf [object Object] Mar 29 21:43:43 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveHDMIAudioConf Mar 29 21:43:43 volumiorpi5 volumio[1400]: info: touch_display: Backup of volumiokiosk.sh already exists. Mar 29 21:43:43 volumiorpi5 sudo[5066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/sed -i '/# HDMI_AUDIO_KEEPALIVE/d' /opt/volumiokiosk.sh Mar 29 21:43:43 volumiorpi5 sudo[5066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:43 volumiorpi5 sudo[5066]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:43 volumiorpi5 volumio[1400]: info: touch_display: HDMI audio keep-alive commands removed from volumiokiosk.sh. Mar 29 21:43:43 volumiorpi5 sudo[5069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Mar 29 21:43:43 volumiorpi5 sudo[5069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:43:43 volumiorpi5 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 21:43:43 volumiorpi5 startx[4850]: xinit: connection to X server lost Mar 29 21:43:43 volumiorpi5 startx[4850]: Mar 29 21:43:43 volumiorpi5 startx[4850]: waiting for X server to shut down Mar 29 21:43:43 volumiorpi5 startx[4851]: (II) Server terminated successfully (0). Closing log file. Mar 29 21:43:44 volumiorpi5 startx[4850]: xinit: unexpected signal 15 Mar 29 21:43:44 volumiorpi5 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 29 21:43:44 volumiorpi5 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 29 21:43:44 volumiorpi5 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:44 volumiorpi5 systemd[1]: volumio-kiosk.service: Consumed 1.935s CPU time. Mar 29 21:43:44 volumiorpi5 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 29 21:43:44 volumiorpi5 sudo[5069]: pam_unix(sudo:session): session closed for user root Mar 29 21:43:44 volumiorpi5 volumio[1400]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Mar 29 21:43:44 volumiorpi5 volumio[1400]: info: touch_display: Volumio Kiosk restarted. Mar 29 21:43:44 volumiorpi5 volumio[1400]: info: touch_display: X display number found: Mar 29 21:43:44 volumiorpi5 startx[5099]: X.Org X Server 1.21.1.7 Mar 29 21:43:44 volumiorpi5 startx[5099]: X Protocol Version 11, Revision 0 Mar 29 21:43:44 volumiorpi5 startx[5099]: Current Operating System: Linux volumiorpi5 6.12.74-v8+ #1948 SMP PREEMPT Mon Mar 2 11:30:34 GMT 2026 aarch64 Mar 29 21:43:44 volumiorpi5 startx[5099]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=88:A2:9E:5B:C1:13 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 29 21:43:44 volumiorpi5 startx[5099]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 29 21:43:44 volumiorpi5 startx[5099]: Current version of pixman: 0.44.0 Mar 29 21:43:44 volumiorpi5 startx[5099]: Before reporting problems, check http://wiki.x.org Mar 29 21:43:44 volumiorpi5 startx[5099]: to make sure that you have the latest version. Mar 29 21:43:44 volumiorpi5 startx[5099]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 29 21:43:44 volumiorpi5 startx[5099]: (++) from command line, (!!) notice, (II) informational, Mar 29 21:43:44 volumiorpi5 startx[5099]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 29 21:43:44 volumiorpi5 startx[5099]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Mar 29 21:43:44 2026 Mar 29 21:43:44 volumiorpi5 startx[5099]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 29 21:43:44 volumiorpi5 startx[5099]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 29 21:43:44 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:44 volumiorpi5 volumio[1400]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 29 21:43:44 volumiorpi5 volumio[1400]: info: touch_display: X display number found: 0 Mar 29 21:43:45 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioGetState Mar 29 21:43:45 volumiorpi5 volumio[1400]: info: CorePlayQueue::getTrack 0 Mar 29 21:43:45 volumiorpi5 volumio[1400]: info: touch_display: Setting screensaver timeout to 365 seconds. Mar 29 21:43:51 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 21:43:51 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 21:44:03 volumiorpi5 sudo[5334]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Mar 29 21:44:03 volumiorpi5 sudo[5334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:03 volumiorpi5 sudo[5334]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:03 volumiorpi5 volumio[1400]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Mar 29 21:44:03 volumiorpi5 sudo[5337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Mar 29 21:44:03 volumiorpi5 sudo[5337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:03 volumiorpi5 sudo[5337]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:03 volumiorpi5 volumio[1400]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Mar 29 21:44:03 volumiorpi5 sudo[5340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Mar 29 21:44:03 volumiorpi5 sudo[5340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:03 volumiorpi5 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 21:44:03 volumiorpi5 startx[5098]: xinit: connection to X server lost Mar 29 21:44:03 volumiorpi5 startx[5098]: Mar 29 21:44:03 volumiorpi5 startx[5098]: waiting for X server to shut down Mar 29 21:44:03 volumiorpi5 startx[5099]: (II) Server terminated successfully (0). Closing log file. Mar 29 21:44:04 volumiorpi5 startx[5098]: xinit: unexpected signal 15 Mar 29 21:44:04 volumiorpi5 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 29 21:44:04 volumiorpi5 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 29 21:44:04 volumiorpi5 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Mar 29 21:44:04 volumiorpi5 systemd[1]: volumio-kiosk.service: Consumed 1.991s CPU time. Mar 29 21:44:04 volumiorpi5 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 29 21:44:04 volumiorpi5 sudo[5340]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:04 volumiorpi5 volumio[1400]: warn: [now-playing] MyBackgroundMonitor stopped Mar 29 21:44:04 volumiorpi5 volumio[1400]: info: Disabling plugin now_playing Mar 29 21:44:04 volumiorpi5 volumio[1400]: info: Done. Mar 29 21:44:04 volumiorpi5 startx[5369]: X.Org X Server 1.21.1.7 Mar 29 21:44:04 volumiorpi5 startx[5369]: X Protocol Version 11, Revision 0 Mar 29 21:44:04 volumiorpi5 startx[5369]: Current Operating System: Linux volumiorpi5 6.12.74-v8+ #1948 SMP PREEMPT Mon Mar 2 11:30:34 GMT 2026 aarch64 Mar 29 21:44:04 volumiorpi5 startx[5369]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=88:A2:9E:5B:C1:13 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 29 21:44:04 volumiorpi5 startx[5369]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 29 21:44:04 volumiorpi5 startx[5369]: Current version of pixman: 0.44.0 Mar 29 21:44:04 volumiorpi5 startx[5369]: Before reporting problems, check http://wiki.x.org Mar 29 21:44:04 volumiorpi5 startx[5369]: to make sure that you have the latest version. Mar 29 21:44:04 volumiorpi5 startx[5369]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 29 21:44:04 volumiorpi5 startx[5369]: (++) from command line, (!!) notice, (II) informational, Mar 29 21:44:04 volumiorpi5 startx[5369]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 29 21:44:04 volumiorpi5 startx[5369]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Mar 29 21:44:04 2026 Mar 29 21:44:04 volumiorpi5 startx[5369]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 29 21:44:04 volumiorpi5 startx[5369]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 29 21:44:06 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:44:06+02:00" level=trace msg="received accesspoint ping" Mar 29 21:44:06 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:44:06+02:00" level=trace msg="received accesspoint pong ack" Mar 29 21:44:06 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:44:06+02:00" level=trace msg="sent dealer ping" Mar 29 21:44:06 volumiorpi5 go-librespot[1840]: time="2026-03-29T21:44:06+02:00" level=trace msg="received dealer pong" Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: Enabling plugin now_playing Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: Loading plugin "now_playing"... Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: PLUGIN START: now_playing Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: [now-playing] ConfigUpdater: config is up to date. Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: [now-playing] App is listening on port 4004. Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Mar 29 21:44:09 volumiorpi5 sudo[5587]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Mar 29 21:44:09 volumiorpi5 sudo[5587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:09 volumiorpi5 sudo[5587]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Mar 29 21:44:09 volumiorpi5 sudo[5592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Mar 29 21:44:09 volumiorpi5 sudo[5592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:09 volumiorpi5 sudo[5592]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:09 volumiorpi5 volumio[1400]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Mar 29 21:44:09 volumiorpi5 sudo[5595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Mar 29 21:44:09 volumiorpi5 sudo[5595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:09 volumiorpi5 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Mar 29 21:44:09 volumiorpi5 startx[5368]: xinit: connection to X server lost Mar 29 21:44:09 volumiorpi5 startx[5368]: Mar 29 21:44:10 volumiorpi5 startx[5368]: waiting for X server to shut down Mar 29 21:44:10 volumiorpi5 startx[5369]: (II) Server terminated successfully (0). Closing log file. Mar 29 21:44:10 volumiorpi5 startx[5368]: xinit: unexpected signal 15 Mar 29 21:44:10 volumiorpi5 systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 29 21:44:10 volumiorpi5 systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 29 21:44:10 volumiorpi5 systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Mar 29 21:44:10 volumiorpi5 systemd[1]: volumio-kiosk.service: Consumed 1.902s CPU time. Mar 29 21:44:11 volumiorpi5 systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Mar 29 21:44:11 volumiorpi5 sudo[5595]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:11 volumiorpi5 volumio[1400]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 29 21:44:11 volumiorpi5 volumio[1400]: info: Done. Mar 29 21:44:11 volumiorpi5 startx[5624]: X.Org X Server 1.21.1.7 Mar 29 21:44:11 volumiorpi5 startx[5624]: X Protocol Version 11, Revision 0 Mar 29 21:44:11 volumiorpi5 startx[5624]: Current Operating System: Linux volumiorpi5 6.12.74-v8+ #1948 SMP PREEMPT Mon Mar 2 11:30:34 GMT 2026 aarch64 Mar 29 21:44:11 volumiorpi5 startx[5624]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 numa=fake=8 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=88:A2:9E:5B:C1:13 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=dafa3844-b779-48cd-9b4c-01ecfd09e0f4 imgfile=/volumio_current.sqsh bootpart=UUID=3B89-0B23 datapart=UUID=752d19ad-b702-471d-847a-f79ae83515d0 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 29 21:44:11 volumiorpi5 startx[5624]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Mar 29 21:44:11 volumiorpi5 startx[5624]: Current version of pixman: 0.44.0 Mar 29 21:44:11 volumiorpi5 startx[5624]: Before reporting problems, check http://wiki.x.org Mar 29 21:44:11 volumiorpi5 startx[5624]: to make sure that you have the latest version. Mar 29 21:44:11 volumiorpi5 startx[5624]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 29 21:44:11 volumiorpi5 startx[5624]: (++) from command line, (!!) notice, (II) informational, Mar 29 21:44:11 volumiorpi5 startx[5624]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 29 21:44:11 volumiorpi5 startx[5624]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Mar 29 21:44:11 2026 Mar 29 21:44:11 volumiorpi5 startx[5624]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 29 21:44:11 volumiorpi5 startx[5624]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 29 21:44:12 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 21:44:12 volumiorpi5 volumio[1400]: info: Discovery: Getting this device information Mar 29 21:44:12 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioGetState Mar 29 21:44:12 volumiorpi5 volumio[1400]: info: CorePlayQueue::getTrack 0 Mar 29 21:44:12 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: FusionDsp - Stopping clipped samples monitor Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: FusionDsp - Clipped samples monitor stopped Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: FusionDsp - Stopping FusionDsp service Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: camilladsp stopping service pid 1915... Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: camilladsp service terminated, instance 1 Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Disabling plugin fusiondsp Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Preparing to generate the ALSA configuration file Mar 29 21:44:21 volumiorpi5 sudo[5828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Mar 29 21:44:21 volumiorpi5 sudo[5828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioPause Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreStateMachine::pause Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Asound.conf file written Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopping fusiondsp.service - FusionDsp Daemon... Mar 29 21:44:21 volumiorpi5 volumio[1984]: ======== Running on http://0.0.0.0:5011 ======== Mar 29 21:44:21 volumiorpi5 volumio[1984]: (Press CTRL+C to quit) Mar 29 21:44:21 volumiorpi5 sudo[5832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 21:44:21 volumiorpi5 sudo[5832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:21 volumiorpi5 systemd[1]: fusiondsp.service: Deactivated successfully. Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Mar 29 21:44:21 volumiorpi5 sudo[5832]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:21 volumiorpi5 sudo[5828]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:21 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 29 21:44:21 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Output device has changed, restarting MPD Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Output device has changed, restarting Shairport Sync Mar 29 21:44:21 volumiorpi5 sudo[5838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:21 volumiorpi5 sudo[5838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 sudo[5838]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:21 volumiorpi5 sudo[5840]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:21 volumiorpi5 sudo[5840]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Done. Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: MPD Permissions set Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: FusionDsp - Reporting Fusion DSP Disabled Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Removing fusiondspeq DSP Signal Path Element Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Starting Shairport Sync Mar 29 21:44:21 volumiorpi5 sudo[5853]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 21:44:21 volumiorpi5 sudo[5853]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 21:44:21 volumiorpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:21 volumiorpi5 systemd[1]: shairport-sync.service: Consumed 1.968s CPU time. Mar 29 21:44:21 volumiorpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:21 volumiorpi5 sudo[5853]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:21 volumiorpi5 volumio[1400]: info: Shairport-Sync Started Mar 29 21:44:21 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:21 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:21 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:21 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:21 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:21 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:21 volumiorpi5 sudo[5871]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 21:44:21 volumiorpi5 sudo[5871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 21:44:21 volumiorpi5 sudo[5871]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:21 volumiorpi5 mpd[5873]: 2026-03-29T21:44:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 21:44:21 volumiorpi5 systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 21:44:21 volumiorpi5 sudo[5840]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:21 volumiorpi5 volumio[1400]: error: updateQueue error: null Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Enabling plugin fusiondsp Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Loading plugin "fusiondsp"... Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Applying required configuration parameters for plugin fusiondsp Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Preparing to generate the ALSA configuration file Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Reading ALSA contributions from plugins. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Asound.conf file written Mar 29 21:44:27 volumiorpi5 sudo[5898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 21:44:27 volumiorpi5 sudo[5898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:27 volumiorpi5 sudo[5898]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:27 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 29 21:44:27 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Output device has changed, restarting MPD Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Output device has changed, restarting Shairport Sync Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:27 volumiorpi5 sudo[5905]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:27 volumiorpi5 sudo[5905]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:27 volumiorpi5 sudo[5906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:27 volumiorpi5 sudo[5906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:27 volumiorpi5 sudo[5906]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: PLUGIN START: fusiondsp Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: FusionDsp - CamillaDSP binary found. Mar 29 21:44:27 volumiorpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Loading i18n strings for locale pl Mar 29 21:44:27 volumiorpi5 volumio[1400]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: FusionDsp - mixtype--------------------- Hardware Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Preparing to generate the ALSA configuration file Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Done. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Reading ALSA contributions from plugins. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: MPD Permissions set Mar 29 21:44:27 volumiorpi5 volumio[1400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:27 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:27 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:27 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Starting Shairport Sync Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Asound.conf file unchanged, so no further update is needed Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Output device has changed, restarting MPD Mar 29 21:44:27 volumiorpi5 sudo[5921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 21:44:27 volumiorpi5 sudo[5921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:27 volumiorpi5 sudo[5924]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Output device has changed, restarting Shairport Sync Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:27 volumiorpi5 sudo[5924]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:27 volumiorpi5 sudo[5926]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:27 volumiorpi5 sudo[5926]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:27 volumiorpi5 sudo[5924]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:27 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: MPD Permissions set Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:27 volumiorpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 21:44:27 volumiorpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 21:44:27 volumiorpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:27 volumiorpi5 systemd[1]: shairport-sync.service: Consumed 1.517s CPU time. Mar 29 21:44:27 volumiorpi5 volumio[1400]: info: Starting Shairport Sync Mar 29 21:44:27 volumiorpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:27 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:27 volumiorpi5 sudo[5921]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:27 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:28 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:28 volumiorpi5 volumio[1400]: info: Shairport-Sync Started Mar 29 21:44:28 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:28 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:28 volumiorpi5 sudo[5941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 21:44:28 volumiorpi5 sudo[5941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:28 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:28 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:28 volumiorpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 21:44:28 volumiorpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 21:44:28 volumiorpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:28 volumiorpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:28 volumiorpi5 sudo[5941]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:28 volumiorpi5 volumio[1400]: info: Shairport-Sync Started Mar 29 21:44:28 volumiorpi5 sudo[5957]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 21:44:28 volumiorpi5 sudo[5957]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 21:44:28 volumiorpi5 sudo[5957]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:28 volumiorpi5 mpd[5974]: 2026-03-29T21:44:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 21:44:28 volumiorpi5 systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 21:44:28 volumiorpi5 sudo[5905]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:28 volumiorpi5 sudo[5926]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:28 volumiorpi5 volumio[1400]: error: MPD error: The expression evaluated to a falsy value: Mar 29 21:44:28 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:28 volumiorpi5 volumio[1400]: error: The expression evaluated to a falsy value: Mar 29 21:44:28 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:28 volumiorpi5 volumio[1400]: error: updateQueue error: null Mar 29 21:44:29 volumiorpi5 volumio[1400]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 29 21:44:29 volumiorpi5 volumio[1400]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Mar 29 21:44:29 volumiorpi5 volumio[1400]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: camilladsp service started and running in background, instance 2 Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: FusionDsp loaded Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: FusionDsp - --[object Object] Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: Adding Signal Path Element [object Object] Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: Adding fusiondspeq DSP Signal Path Element Mar 29 21:44:29 volumiorpi5 volumio[1400]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Mar 29 21:44:29 volumiorpi5 volumio[1400]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 29 21:44:29 volumiorpi5 sudo[5991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 29 21:44:29 volumiorpi5 sudo[5991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:30 volumiorpi5 systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Mar 29 21:44:30 volumiorpi5 volumio[1400]: info: FusionDsp - ---- read samplerate, raw: Mar 29 21:44:30 volumiorpi5 volumio[1400]: error: FusionDsp - invalid sample rate Mar 29 21:44:30 volumiorpi5 volumio[1400]: info: FusionDsp - ---- read samplerate, raw: Mar 29 21:44:30 volumiorpi5 volumio[1400]: error: FusionDsp - invalid sample rate Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Enabling plugin peppy_screensaver Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Loading plugin "peppy_screensaver"... Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Applying required configuration parameters for plugin peppy_screensaver Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Preparing to generate the ALSA configuration file Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Reading ALSA contributions from plugins. Mar 29 21:44:33 volumiorpi5 volumio[1400]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Asound.conf file written Mar 29 21:44:33 volumiorpi5 sudo[5998]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 21:44:33 volumiorpi5 sudo[5998]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:33 volumiorpi5 sudo[5998]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:33 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 29 21:44:33 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Output device has changed, restarting MPD Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Output device has changed, restarting Shairport Sync Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:33 volumiorpi5 sudo[6004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:33 volumiorpi5 sudo[6004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:33 volumiorpi5 sudo[6004]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:33 volumiorpi5 sudo[6007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:33 volumiorpi5 sudo[6007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: PLUGIN START: peppy_screensaver Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: Loading i18n strings for locale pl Mar 29 21:44:33 volumiorpi5 volumio[1400]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Mar 29 21:44:33 volumiorpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: /tmp/myfifo created Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: /tmp/myfifosa created Mar 29 21:44:33 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:33 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:33 volumiorpi5 sudo[6024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Mar 29 21:44:33 volumiorpi5 sudo[6024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:33 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:33 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:33 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:33 volumiorpi5 sudo[6024]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:33 volumiorpi5 volumio[1400]: info: snd-dummy loaded Mar 29 21:44:33 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:33 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 sudo[6031]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 21:44:34 volumiorpi5 sudo[6031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 sudo[6031]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Adding peppy_screensaver REST Endpoint for plugin: user_interface/peppy_screensaver Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Adding peppy_screensaver_font REST Endpoint for plugin: user_interface/peppy_screensaver Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_font Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Adding peppy_screensaver_vinyl REST Endpoint for plugin: user_interface/peppy_screensaver Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: peppy_screensaver: REST endpoint registered: peppy_screensaver_vinyl Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: peppy_screensaver: Config version updated: 7403fa88 Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Done. Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: MPD Permissions set Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::volumioGetState Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CorePlayQueue::getTrack 0 Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: peppy_screensaver: pushState - status=stop service=mpd volatile=undefined Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Starting Shairport Sync Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Starting Shairport Sync Mar 29 21:44:34 volumiorpi5 sudo[6072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 21:44:34 volumiorpi5 sudo[6072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 sudo[6074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 21:44:34 volumiorpi5 sudo[6074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 21:44:34 volumiorpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:34 volumiorpi5 systemd[1]: shairport-sync.service: Consumed 1.521s CPU time. Mar 29 21:44:34 volumiorpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:34 volumiorpi5 sudo[6072]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 sudo[6074]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Shairport-Sync Started Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Shairport-Sync Started Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: peppy_screensaver: ALSA template: /data/plugins/user_interface/peppy_screensaver/Peppyalsa.postPeppyalsa.5.conf.tmpl (isX64=false) Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Creating Spotify config file Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Preparing to generate the ALSA configuration file Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Reading ALSA contributions from plugins. Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Spotify config file written Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Asound.conf file unchanged, so no further update is needed Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Output device has changed, restarting MPD Mar 29 21:44:34 volumiorpi5 sudo[6104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 29 21:44:34 volumiorpi5 sudo[6104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 sudo[6103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 29 21:44:34 volumiorpi5 sudo[6103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 sudo[6103]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Output device has changed, restarting Shairport Sync Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:34 volumiorpi5 sudo[6110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:34 volumiorpi5 sudo[6110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 29 21:44:34 volumiorpi5 systemd[1]: go-librespot-daemon.service: Killing process 1898 (go-librespot) with signal SIGKILL. Mar 29 21:44:34 volumiorpi5 systemd[1]: go-librespot-daemon.service: Killing process 1930 (go-librespot) with signal SIGKILL. Mar 29 21:44:34 volumiorpi5 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 29 21:44:34 volumiorpi5 sudo[6107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:34 volumiorpi5 sudo[6107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 sudo[6107]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Connection to go-librespot Websocket closed Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Mar 29 21:44:34 volumiorpi5 sudo[6119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 sudo[6119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 sudo[6119]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 29 21:44:34 volumiorpi5 go-librespot[6117]: go-librespot daemon starting... Mar 29 21:44:34 volumiorpi5 sudo[6104]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: MPD Permissions set Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: MPD Permissions set Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=info msg="running go-librespot 0.7.1" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="app state loaded" Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 21:44:34 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:34 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Starting Shairport Sync Mar 29 21:44:34 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:34 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:34 volumiorpi5 sudo[6140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 21:44:34 volumiorpi5 sudo[6140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 29 21:44:34 volumiorpi5 systemd[1]: shairport-sync.service: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:34 volumiorpi5 sudo[6138]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 21:44:34 volumiorpi5 sudo[6138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 21:44:34 volumiorpi5 sudo[6138]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 29 21:44:34 volumiorpi5 sudo[6140]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: Shairport-Sync Started Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=info msg="zeroconf server listening on port 41203" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="obtained new client token: AAC9c8q+jxusPu9Jw3LBQD0by5KnK2MvFBCuA7A0AitmW63JWO5qiLe2WS8kEY+yPWOZNLCKIH4vKScXZQvZuWerysjbUx8q5I76Bywy4f41BDjitOvb4dYGiVfPwltnp8/vp2N3RCH2tTN8xr+oxyaUEsxGEPHHY6fMVJbLtbVLtRxKCgPMEcB63Z6Rh+EN1EP2l1HIi4MhOZs5v5CYnl2i6ayYYBWRQzK64nzyiEGELdeO4fnfrP+d" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="completed keyexchange" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="completed challenge" Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 29 21:44:34 volumiorpi5 sudo[6163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:34 volumiorpi5 sudo[6163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=info msg="authenticated AP" username="ju*******06" Mar 29 21:44:34 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:34 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:34 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:34 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:34 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 29 21:44:34 volumiorpi5 sudo[6168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:34 volumiorpi5 sudo[6168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:34 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:34 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:34 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:34 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:34 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:34 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:34 volumiorpi5 sudo[6171]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 21:44:34 volumiorpi5 sudo[6171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 21:44:34 volumiorpi5 sudo[6171]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=info msg="authenticated Login5" username="ju*******06" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="initializing zeroconf session" username="ju*******06" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="dealer connection opened" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=trace msg="starting accesspoint recv loop" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=trace msg="starting dealer recv loop" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=trace msg="received accesspoint ping" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=debug msg="received connection id: YTY1ZWE1ZDAtMzkx...MjQ4RUNBNzQ3NA==" Mar 29 21:44:34 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:34+02:00" level=trace msg="received accesspoint pong ack" Mar 29 21:44:35 volumiorpi5 sudo[5991]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:35 volumiorpi5 systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Mar 29 21:44:35 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:35+02:00" level=debug msg="put connect state because NEW_DEVICE" Mar 29 21:44:35 volumiorpi5 mpd[6174]: 2026-03-29T21:44:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 21:44:35 volumiorpi5 systemd[1]: Started mpd.service - Music Player Daemon. Mar 29 21:44:35 volumiorpi5 sudo[6168]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:35 volumiorpi5 sudo[6110]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:35 volumiorpi5 sudo[6163]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:35 volumiorpi5 sudo[6007]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: MPD error: The expression evaluated to a falsy value: Mar 29 21:44:35 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: The expression evaluated to a falsy value: Mar 29 21:44:35 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: MPD error: The expression evaluated to a falsy value: Mar 29 21:44:35 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: The expression evaluated to a falsy value: Mar 29 21:44:35 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: MPD error: The expression evaluated to a falsy value: Mar 29 21:44:35 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: The expression evaluated to a falsy value: Mar 29 21:44:35 volumiorpi5 volumio[1400]: assert.ok(self.idling) Mar 29 21:44:35 volumiorpi5 volumio[1400]: error: updateQueue error: null Mar 29 21:44:35 volumiorpi5 volumio[1400]: info: peppy_screensaver: MPD output 1 disabled Mar 29 21:44:36 volumiorpi5 volumio[1400]: info: peppy_screensaver: Startup - MPD output 1 disabled Mar 29 21:44:37 volumiorpi5 volumio[1400]: info: Initializing connection to go-librespot Websocket Mar 29 21:44:37 volumiorpi5 go-librespot[6126]: time="2026-03-29T21:44:37+02:00" level=debug msg="new websocket client" Mar 29 21:44:37 volumiorpi5 volumio[1400]: info: Connection to go-librespot Websocket established Mar 29 21:44:37 volumiorpi5 volumio[1400]: info: go-librespot daemon successfully initialized Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: CoreStateMachine::stop Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Removing peppy_screensaver REST Endpoint Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Disabling plugin peppy_screensaver Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Preparing to generate the ALSA configuration file Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Reading ALSA contributions from plugins. Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Creating Spotify config file Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Spotify config file written Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Asound.conf file written Mar 29 21:44:40 volumiorpi5 sudo[6210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Mar 29 21:44:40 volumiorpi5 sudo[6210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:40 volumiorpi5 sudo[6212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 29 21:44:40 volumiorpi5 sudo[6212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:40 volumiorpi5 systemd[1]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Deactivated successfully. Mar 29 21:44:40 volumiorpi5 sudo[6210]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:40 volumiorpi5 sudo[6216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 21:44:40 volumiorpi5 sudo[6216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:40 volumiorpi5 sudo[6216]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:40 volumiorpi5 systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 29 21:44:40 volumiorpi5 systemd[1]: go-librespot-daemon.service: Killing process 6131 (go-librespot) with signal SIGKILL. Mar 29 21:44:40 volumiorpi5 systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 29 21:44:40 volumiorpi5 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 29 21:44:40 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 29 21:44:40 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 29 21:44:40 volumiorpi5 volumio[1400]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Output device has changed, restarting MPD Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Output device has changed, restarting Shairport Sync Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 21:44:40 volumiorpi5 sudo[6226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 21:44:40 volumiorpi5 sudo[6226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:40 volumiorpi5 sudo[6225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 21:44:40 volumiorpi5 sudo[6225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:40 volumiorpi5 sudo[6226]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Done. Mar 29 21:44:40 volumiorpi5 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Getting Spotify volume Mar 29 21:44:40 volumiorpi5 volumio[1400]: info: Initializing connection to go-librespot Websocket Mar 29 21:44:40 volumiorpi5 go-librespot[6222]: go-librespot daemon starting... Mar 29 21:44:40 volumiorpi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 29 21:44:40 volumiorpi5 volumio[1400]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 21:44:40 volumiorpi5 sudo[6212]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:40 volumiorpi5 volumio[1400]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 21:44:40 volumiorpi5 volumio[1400]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 29 21:44:40 volumiorpi5 volumio[1400]: errno: -111, Mar 29 21:44:40 volumiorpi5 volumio[1400]: code: 'ECONNREFUSED', Mar 29 21:44:40 volumiorpi5 volumio[1400]: syscall: 'connect', Mar 29 21:44:40 volumiorpi5 volumio[1400]: address: '127.0.0.1', Mar 29 21:44:40 volumiorpi5 volumio[1400]: port: 9879, Mar 29 21:44:40 volumiorpi5 volumio[1400]: response: undefined Mar 29 21:44:40 volumiorpi5 volumio[1400]: } Mar 29 21:44:40 volumiorpi5 volumio[1400]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=info msg="running go-librespot 0.7.1" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=debug msg="app state loaded" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 21:44:40 volumiorpi5 systemd[1]: mpd.service: Deactivated successfully. Mar 29 21:44:40 volumiorpi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 29 21:44:40 volumiorpi5 systemd[1]: mpd.socket: Deactivated successfully. Mar 29 21:44:40 volumiorpi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 29 21:44:40 volumiorpi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 29 21:44:40 volumiorpi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 29 21:44:40 volumiorpi5 systemd[1]: Starting mpd.service - Music Player Daemon... Mar 29 21:44:40 volumiorpi5 sudo[6243]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 21:44:40 volumiorpi5 sudo[6243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 21:44:40 volumiorpi5 sudo[6243]: pam_unix(sudo:session): session closed for user root Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=info msg="zeroconf server listening on port 38931" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=debug msg="obtained new client token: AAAVRLwRZAV8PnO1MEAnBfNUOff9FfC0O+Wp7Kr96bYzFPN2/Ln99EmmcaQFfUmoeDgOHpYPcQgJ9LHRS6qEm42peZREkKpY0Q+j5L4mfmWbeSaYbzo+9p9gCJG/Fa5qgjE3ZOWFbBcBfnRD2WO/LRcwb6FpY2+R3fu8p0O9LbaxdelP0ReuJtigMljt24v7Ev4YifavjRM8Ux6LhEc4lkqnQ9xmgUQsGxtLwOLMQpDbxWBG8wJbHu6l" Mar 29 21:44:40 volumiorpi5 sudo[6260]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 21:43' Mar 29 21:44:40 volumiorpi5 sudo[6260]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 21:44:40 volumiorpi5 go-librespot[6233]: time="2026-03-29T21:44:40+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"