Dec 14 11:40:01 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:03 volumio mpd[1558]: 2025-12-14T11:40:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 14 11:40:04 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 14 11:40:04 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:04 volumio sudo[1530]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:05 volumio volumio[1246]: info: touch_display: No backlight interface detected. Dec 14 11:40:05 volumio volumio[1246]: info: touch_display: systemctl daemon-reload succeeded. Dec 14 11:40:05 volumio volumio[1246]: info: touch_display: systemctl disable getty@tty1.service succeeded. Dec 14 11:40:05 volumio volumio[1246]: info: touch_display: systemctl stop getty@tty1.service succeeded. Dec 14 11:40:06 volumio volumio[1246]: info: MPD Permissions set Dec 14 11:40:06 volumio volumio[1246]: info: MPD Permissions set Dec 14 11:40:06 volumio volumio[1246]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Dec 14 11:40:06 volumio sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 14 11:40:06 volumio sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:06 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Dec 14 11:40:06 volumio sudo[1649]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:06 volumio volumio-remote-updater[701]: [2025-12-14 11:40:06] [connect] Successful connection Dec 14 11:40:06 volumio volumio[1246]: info: Volumio called home Dec 14 11:40:07 volumio startx[1681]: X.Org X Server 1.21.1.7 Dec 14 11:40:07 volumio startx[1681]: X Protocol Version 11, Revision 0 Dec 14 11:40:07 volumio startx[1681]: Current Operating System: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Dec 14 11:40:07 volumio startx[1681]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 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=ttyS0,115200 console=tty1 imgpart=UUID=78d948c3-935b-4358-b7df-c382779bbef9 imgfile=/volumio_current.sqsh bootpart=UUID=A1E8-5028 datapart=UUID=60f254d7-c70a-4b55-956c-3df3766a56af uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Dec 14 11:40:07 volumio startx[1681]: xorg-server 2:21.1.7-3+rpt3+deb12u10 (https://www.debian.org/support) Dec 14 11:40:07 volumio startx[1681]: Current version of pixman: 0.44.0 Dec 14 11:40:07 volumio startx[1681]: Before reporting problems, check http://wiki.x.org Dec 14 11:40:07 volumio startx[1681]: to make sure that you have the latest version. Dec 14 11:40:07 volumio startx[1681]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 14 11:40:07 volumio startx[1681]: (++) from command line, (!!) notice, (II) informational, Dec 14 11:40:07 volumio startx[1681]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 14 11:40:07 volumio startx[1681]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Dec 14 11:40:07 2025 Dec 14 11:40:07 volumio startx[1681]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 14 11:40:07 volumio startx[1681]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 14 11:40:09 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Dec 14 11:40:09 volumio volumio[1246]: assert.ok(self.idling) Dec 14 11:40:09 volumio volumio[1246]: error: The expression evaluated to a falsy value: Dec 14 11:40:09 volumio volumio[1246]: assert.ok(self.idling) Dec 14 11:40:09 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 14 11:40:10 volumio volumio[1246]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 14 11:40:10 volumio volumio[1246]: info: touch_display: Volumio Kiosk started. Dec 14 11:40:10 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 14 11:40:10 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 14 11:40:10 volumio volumio[1246]: info: Completed starting Core Plugins Dec 14 11:40:10 volumio volumio[1246]: info: ------------------------------------------- Dec 14 11:40:10 volumio volumio[1246]: info: ----- MyVolumio plugins startup ---- Dec 14 11:40:10 volumio volumio[1246]: info: ------------------------------------------- Dec 14 11:40:10 volumio volumio[1246]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 14 11:40:10 volumio volumio[1246]: info: MPD running with PID1558 Dec 14 11:40:10 volumio volumio[1246]: ,establishing connection Dec 14 11:40:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio sudo[1743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:40:11 volumio sudo[1743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:11 volumio sudo[1737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 11:40:11 volumio sudo[1737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 11:40:11 volumio mpd_monitor.sh[1772]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:11 volumio sudo[1743]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:11 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 11:40:11 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 11:40:11 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 11:40:11 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 11:40:11 volumio sudo[1737]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:11 volumio mpd_monitor.sh[1776]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:40:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:40:12 volumio volumio[1246]: error: updateQueue error: null Dec 14 11:40:14 volumio volumio[1246]: info: touch_display: X display number found: 0 Dec 14 11:40:14 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Dec 14 11:40:14 volumio volumio[1246]: assert.ok(self.idling) Dec 14 11:40:14 volumio volumio[1246]: error: The expression evaluated to a falsy value: Dec 14 11:40:14 volumio volumio[1246]: assert.ok(self.idling) Dec 14 11:40:14 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Dec 14 11:40:14 volumio volumio[1246]: assert.ok(self.idling) Dec 14 11:40:14 volumio volumio[1246]: error: The expression evaluated to a falsy value: Dec 14 11:40:14 volumio volumio[1246]: assert.ok(self.idling) Dec 14 11:40:14 volumio sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.11/music /mnt/NAS/NAS Dec 14 11:40:14 volumio sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:14 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 14 11:40:14 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 14 11:40:14 volumio kernel: CIFS: Attempting to mount //192.168.1.11/music Dec 14 11:40:15 volumio volumio[1246]: info: Successfully started MPD Monitor Dec 14 11:40:15 volumio volumio[1246]: info: Successfully started MPD Monitor Dec 14 11:40:15 volumio volumio[1246]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 14 11:40:15 volumio volumio[1246]: error: updateQueue error: null Dec 14 11:40:15 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 14 11:40:15 volumio volumio[1246]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 14 11:40:15 volumio volumio[1246]: info: Starting Shairport Sync Dec 14 11:40:15 volumio sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 14 11:40:15 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:16 volumio sudo[1839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:40:16 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:16 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:16 volumio volumio[1246]: info: Starting Shairport Sync Dec 14 11:40:16 volumio sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Dec 14 11:40:16 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:40:16 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:40:16 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:40:16 volumio systemd[1]: shairport-sync.service: Consumed 2.193s CPU time. Dec 14 11:40:16 volumio sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:16 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:40:16 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:16 volumio sudo[1828]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:16 volumio volumio[1246]: info: Starting Shairport Sync Dec 14 11:40:16 volumio sudo[1865]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:40:16 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:17 volumio volumio[1246]: info: touch_display: X display number found: 0 Dec 14 11:40:17 volumio sudo[1869]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 11:40:17 volumio sudo[1869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:40:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:40:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:40:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:40:17 volumio sudo[1869]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:17 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 11:40:17 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 11:40:17 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:40:17 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 11:40:17 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:17 volumio volumio[1246]: info: Shairport-Sync Started Dec 14 11:40:17 volumio volumio[1246]: Error adding Membership: Error: addMembership EINVAL Dec 14 11:40:18 volumio volumio[1246]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Dec 14 11:40:18 volumio volumio[1246]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 14 11:40:18 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 14 11:40:18 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 14 11:40:19 volumio volumio[1246]: info: Shairport-Sync Started Dec 14 11:40:19 volumio volumio[1246]: info: Shairport-Sync Started Dec 14 11:40:19 volumio volumio[1246]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 14 11:40:20 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 14 11:40:20 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 14 11:40:21 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 14 11:40:21 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 14 11:40:21 volumio sudo[1809]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:21 volumio volumio-remote-updater[701]: [2025-12-14 11:40:21] [connect] Successful connection Dec 14 11:40:21 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 14 11:40:21 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 14 11:40:22 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 11:40:22 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:40:22 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 14 11:40:22 volumio volumio[1246]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin bluetooth to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin multiroom to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin metavolumio to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin cd_controller to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 14 11:40:23 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 14 11:40:26 volumio upmpdcli[1954]: writing RSA key Dec 14 11:40:36 volumio volumio-remote-updater[701]: [2025-12-14 11:40:36] [connect] Successful connection Dec 14 11:40:39 volumio volumio[1246]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 14 11:40:39 volumio volumio[1246]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 14 11:40:39 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:40:39 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:40:39 volumio volumio[1246]: info: Starting MyVolumio Remote Streaming Endpoints Dec 14 11:40:40 volumio volumio[1246]: info: MyVolumio login type: Token Dec 14 11:40:40 volumio volumio[1246]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 14 11:40:40 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 14 11:40:48 volumio volumio[1246]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 14 11:40:48 volumio volumio[1246]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 14 11:40:48 volumio volumio[1246]: info: Streaming services startup Dec 14 11:40:48 volumio volumio[1246]: info: Starting Streaming Daemon Dec 14 11:40:48 volumio volumio[1246]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 14 11:40:49 volumio volumio[1246]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Dec 14 11:40:49 volumio sudo[2113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 11:40:49 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:50 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root Dec 14 11:40:50 volumio volumio[1246]: error: Cannot start Volumio Streaming Daemon Dec 14 11:40:50 volumio volumio[1246]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 14 11:40:50 volumio volumio[1246]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 14 11:40:51 volumio volumio[1246]: error: MyVolumio Custom Token format not valid, refreshing it Dec 14 11:40:51 volumio volumio-remote-updater[701]: [2025-12-14 11:40:51] [connect] Successful connection Dec 14 11:40:54 volumio volumio-remote-updater[701]: [2025-12-14 11:40:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1765687251 101 Dec 14 11:40:54 volumio volumio[1246]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Dec 14 11:40:55 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 11:40:55 volumio sudo[2124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.11/music /mnt/NAS/NAS Dec 14 11:40:55 volumio sudo[2124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:40:55 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 14 11:40:55 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 14 11:40:55 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 14 11:40:55 volumio kernel: CIFS: Attempting to mount //192.168.1.11/music Dec 14 11:40:56 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 14 11:40:56 volumio volumio[1246]: info: MyVolumio login type: Token Dec 14 11:40:59 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Dec 14 11:40:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 11:41:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 11:41:00 volumio volumio[1246]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 14 11:41:02 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 14 11:41:02 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 14 11:41:02 volumio sudo[2124]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:02 volumio volumio[1246]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Dec 14 11:41:03 volumio volumio[1246]: info: MyVolumio token set successfully Dec 14 11:41:03 volumio volumio[1246]: info: MYVOLUMIO: Adding device Dec 14 11:41:03 volumio volumio[1246]: info: MYVOLUMIO: Evaluating Server Dec 14 11:41:05 volumio volumio[1246]: info: MyVolumio Plan changed: premium Dec 14 11:41:05 volumio volumio[1246]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 14 11:41:06 volumio volumio[1246]: info: Removing browser output: myVolumio user plan is not superstar Dec 14 11:41:06 volumio volumio[1246]: info: Removing audio output: Dec 14 11:41:06 volumio volumio[1246]: info: MYVOLUMIO: Adding device Dec 14 11:41:06 volumio volumio[1246]: info: MYVOLUMIO: Evaluating Server Dec 14 11:41:08 volumio volumio[1246]: info: Setting Geolocation for MyVolumio to as1 Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:08 volumio volumio[1246]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 14 11:41:08 volumio volumio[1246]: info: Completed starting MyVolumio Plugin Dec 14 11:41:08 volumio volumio[1246]: [Metrics] CommandRouter: 113s 528.17ms Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::volumiosetStartupVolume Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:08 volumio volumio[1246]: info: CoreCommandRouter::Close All Modals sent Dec 14 11:41:09 volumio sudo[2235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=1234,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.11/music /mnt/NAS/NAS Dec 14 11:41:09 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::Close All Modals sent Dec 14 11:41:09 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 14 11:41:09 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 14 11:41:09 volumio kernel: CIFS: Attempting to mount //192.168.1.11/music Dec 14 11:41:09 volumio volumio[1246]: info: Successfully Added MyVolumio device Dec 14 11:41:09 volumio volumio[1246]: info: Setting Geolocation for MyVolumio to as1 Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:09 volumio volumio[1246]: info: Updating MyVolumio device info Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:09 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:09 volumio volumio[1246]: info: Remote config written successfully Dec 14 11:41:09 volumio volumio[1246]: info: Starting Tunnel 1 Dec 14 11:41:09 volumio volumio[1246]: info: Starting Tunnel Connection Checker Dec 14 11:41:10 volumio volumio[1246]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 14 11:41:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 14 11:41:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 11:41:10 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 14 11:41:10 volumio volumio[1246]: info: MYVolumio Device enabled Dec 14 11:41:10 volumio volumio[1246]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 14 11:41:10 volumio volumio[1246]: info: MyVolumio status changed Dec 14 11:41:10 volumio volumio[1246]: info: Streaming services startup Dec 14 11:41:10 volumio volumio[1246]: info: Starting Streaming Daemon Dec 14 11:41:11 volumio volumio[1246]: info: Successfully Added MyVolumio device Dec 14 11:41:11 volumio sudo[2258]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 11:41:11 volumio sudo[2258]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:11 volumio volumio[1246]: info: Updating MyVolumio device info Dec 14 11:41:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:11 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 11:41:11 volumio sudo[2258]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:13 volumio volumio[1246]: error: Cannot start Volumio Streaming Daemon Dec 14 11:41:13 volumio volumio[1246]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 14 11:41:13 volumio volumio[1246]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 14 11:41:13 volumio volumio[1246]: info: Successfully Updated MyVolumio device Dec 14 11:41:13 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 14 11:41:14 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 14 11:41:15 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 14 11:41:15 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 14 11:41:15 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:17 volumio volumio[1246]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 14 11:41:17 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 14 11:41:42 volumio volumio[1246]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 14 11:41:43 volumio sudo[2441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 14 11:41:43 volumio sudo[2441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:43 volumio sudo[2441]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:43 volumio volumio[1246]: info: MRS: MultiRoom plugin initialized Dec 14 11:41:43 volumio volumio[1246]: info: MRS: STOPPING SNAPCLIENT Dec 14 11:41:44 volumio volumio[1246]: info: MRS: Snap server stop Dec 14 11:41:44 volumio volumio[1246]: info: MRS: STOPPING volumioStreaming Dec 14 11:41:44 volumio sudo[2492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 14 11:41:44 volumio sudo[2492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:44 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 14 11:41:44 volumio sudo[2494]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 14 11:41:44 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:44 volumio sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 14 11:41:44 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:44 volumio sudo[2499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 14 11:41:44 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 11:41:44 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:45 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:45 volumio sudo[2492]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:45 volumio sudo[2494]: pam_unix(sudo:session): session closed for user root Dec 14 11:41:45 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 14 11:41:45 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 14 11:41:45 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 14 11:41:46 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 14 11:41:46 volumio volumio[1246]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 14 11:41:46 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 14 11:41:46 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 14 11:41:47 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 14 11:41:47 volumio dbus-daemon[683]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30001ms) Dec 14 11:41:47 volumio volumio[1246]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 14 11:41:47 volumio volumio[1246]: info: Preparing to generate the ALSA configuration file Dec 14 11:41:48 volumio volumio[1246]: info: Successfully Updated MyVolumio device Dec 14 11:41:48 volumio volumio[1246]: info: BOOT COMPLETED Dec 14 11:41:48 volumio volumio[1246]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 14 11:41:48 volumio volumio[1246]: info: Reading ALSA contributions from plugins. Dec 14 11:41:48 volumio volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 11:41:48 volumio volumio[1246]: Error: write EPIPE Dec 14 11:41:48 volumio volumio[1246]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Dec 14 11:41:48 volumio volumio[1246]: at writeGeneric (node:internal/stream_base_commons:151:3) Dec 14 11:41:48 volumio volumio[1246]: at Socket._writeGeneric (node:net:952:11) Dec 14 11:41:48 volumio volumio[1246]: at Socket._write (node:net:964:8) Dec 14 11:41:48 volumio volumio[1246]: at writeOrBuffer (node:internal/streams/writable:399:12) Dec 14 11:41:48 volumio volumio[1246]: at _write (node:internal/streams/writable:340:10) Dec 14 11:41:48 volumio volumio[1246]: at Writable.write (node:internal/streams/writable:344:10) Dec 14 11:41:48 volumio volumio[1246]: at auth (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:67:10) Dec 14 11:41:48 volumio volumio[1246]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:137:32) Dec 14 11:41:48 volumio volumio[1246]: at Object.onceWrapper (node:events:628:28) Dec 14 11:41:48 volumio volumio[1246]: at Socket.emit (node:events:514:28) Dec 14 11:41:48 volumio volumio[1246]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1580:10) { Dec 14 11:41:48 volumio volumio[1246]: errno: -32, Dec 14 11:41:48 volumio volumio[1246]: code: 'EPIPE', Dec 14 11:41:48 volumio volumio[1246]: syscall: 'write' Dec 14 11:41:48 volumio volumio[1246]: } Dec 14 11:41:48 volumio volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 11:42:00 volumio sudo[2586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 11:40' Dec 14 11:42:00 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"