May 26 15:32:00 volumio volumio[1306]: info: FusionDsp loaded May 26 15:32:00 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:32:00 volumio volumio[1306]: info: FusionDsp - --[object Object] May 26 15:32:00 volumio volumio[1306]: error: FusionDsp - Socket connection not established May 26 15:32:00 volumio volumio[1306]: info: FusionDsp - Reporting Fusion DSP Enabled May 26 15:32:00 volumio volumio[1306]: info: Adding Signal Path Element [object Object] May 26 15:32:00 volumio volumio[1306]: info: Adding fusiondspeq DSP Signal Path Element May 26 15:32:00 volumio volumio[1306]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) May 26 15:32:00 volumio volumio[1306]: info: FusionDsp - PEQ graph server listening on port 10015 May 26 15:32:00 volumio sudo[2104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 26 15:32:00 volumio sudo[2104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:00 volumio volumio-remote-updater[823]: Test mode enabled May 26 15:32:00 volumio volumio-remote-updater[823]: Alpha mode disabled May 26 15:32:00 volumio volumio-remote-updater[823]: Alpha legacy test mode disabled May 26 15:32:00 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... May 26 15:32:00 volumio volumio[1306]: verbose: New Socket.io Connection to 192.168.0.68 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 May 26 15:32:01 volumio volumio[1306]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success May 26 15:32:01 volumio volumio[1306]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 26 15:32:01 volumio volumio[1306]: info: touch_display: Volumio Kiosk started. May 26 15:32:01 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:01 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:01 volumio sudo[2121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness May 26 15:32:01 volumio sudo[2121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:01 volumio sudo[2121]: pam_unix(sudo:session): session closed for user root May 26 15:32:01 volumio sudo[2117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service May 26 15:32:01 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:01 volumio volumio[1306]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 26 15:32:01 volumio sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf May 26 15:32:01 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:01 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root May 26 15:32:01 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... May 26 15:32:02 volumio volumio[1306]: error: updateQueue error: null May 26 15:32:02 volumio lircd-setup[2136]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. May 26 15:32:02 volumio lircd-setup[2136]: parser = configparser.SafeConfigParser() May 26 15:32:02 volumio volumio[1306]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 26 15:32:02 volumio systemd[1]: lircd-setup.service: Deactivated successfully. May 26 15:32:02 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. May 26 15:32:02 volumio systemd[1]: Started evo_remote.service. May 26 15:32:02 volumio lircd-0.10.1[2139]: Info: lircd: Opening log, level: Info May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Version: lircd 0.10.1 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: System info: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l GNU/Linux May 26 15:32:02 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root May 26 15:32:02 volumio volumio[1306]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 15:32:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 15:32:02 volumio sudo[2138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 26 15:32:02 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:02 volumio lircd-0.10.1[2139]: Info: Initial device: /dev/lirc0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: 'lirc' written to protocols file /sys/class/rc/rc2/protocols May 26 15:32:02 volumio lircd-0.10.1[2139]: Info: Initial device: /dev/lirc0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Info: [lirc] protocol is enabled May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: driver: default May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: output: /var/run/lirc/lircd May 26 15:32:02 volumio lircd[2139]: lircd-0.10.1[2139]: Info: lircd: Opening log, level: Info May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: nodaemon: 1 May 26 15:32:02 volumio lircd[2139]: lircd-0.10.1[2139]: Warning: Running as root May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: logfile: syslog May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: immediate-init: 0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: permission: 666 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: driver-options: May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: listen: 0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: connect: (null) May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: userelease: 0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: effective_user: (null) May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: release_suffix: _EVUP May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: allow_simulate: 0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: repeat_max: 600 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Options: dynamic_codes: (null) May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Current driver: default May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Driver API version: 3 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Driver version: 0.10.0 May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 26 15:32:02 volumio lircd-0.10.1[2139]: Info: lircd: Opening log, level: Info May 26 15:32:02 volumio lircd-0.10.1[2139]: Warning: Running as root May 26 15:32:02 volumio lircd[2139]: lircd-0.10.1[2139]: Info: Using remote: ApEvo. May 26 15:32:02 volumio lircd-0.10.1[2139]: Info: Using remote: ApEvo. May 26 15:32:02 volumio lircd-0.10.1[2139]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 26 15:32:02 volumio lircd[2139]: lircd-0.10.1[2139]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 26 15:32:02 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:02 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:02 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root May 26 15:32:02 volumio volumio[1306]: verbose: New Socket.io Connection to 192.168.0.68 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 May 26 15:32:02 volumio volumio[1306]: error: FusionDsp - Reload WebSocket error: [object Object] May 26 15:32:02 volumio volumio[1306]: info: Starting Shairport Sync May 26 15:32:02 volumio volumio[1306]: info: Starting Shairport Sync May 26 15:32:02 volumio sudo[2152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:32:02 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:02 volumio volumio[1306]: info: Starting Shairport Sync May 26 15:32:02 volumio sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:32:02 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:32:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:32:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:02 volumio systemd[1]: shairport-sync.service: Consumed 1.588s CPU time. May 26 15:32:03 volumio sudo[2165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:32:03 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:03 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:32:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:03 volumio volumio[1306]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. May 26 15:32:03 volumio volumio[1306]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. May 26 15:32:03 volumio volumio[1306]: info: touch_display: File permissions for backlight brightness control set. May 26 15:32:03 volumio volumio[1306]: info: EVO SABRE : systemd cmd restart evo_remote.service : success May 26 15:32:03 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root May 26 15:32:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:32:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:32:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:03 volumio sudo[2170]: pam_unix(sudo:session): session closed for user root May 26 15:32:03 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:32:03 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:32:03 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:03 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:03 volumio volumio[1306]: info: touch_display: X display number found: 0 May 26 15:32:03 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root May 26 15:32:03 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:32:03 volumio volumio[1306]: verbose: New Socket.io Connection to 192.168.0.68 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 May 26 15:32:03 volumio sudo[2197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service May 26 15:32:03 volumio sudo[2197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:03 volumio volumio[1306]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 26 15:32:03 volumio volumio[1306]: info: Shairport-Sync Started May 26 15:32:04 volumio volumio[1306]: Error adding Membership: Error: addMembership EINVAL May 26 15:32:04 volumio volumio[1306]: info: Shairport-Sync Started May 26 15:32:04 volumio volumio[1306]: info: Shairport-Sync Started May 26 15:32:04 volumio volumio[1306]: info: Asound.conf file unchanged, so no further update is needed May 26 15:32:04 volumio volumio[1306]: info: Output device has changed, restarting MPD May 26 15:32:04 volumio sudo[2234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 15:32:04 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:04 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root May 26 15:32:04 volumio systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... May 26 15:32:04 volumio sudo[2238]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 15:32:04 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:04 volumio volumio[1306]: info: Output device has changed, restarting Shairport Sync May 26 15:32:04 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:04 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:04 volumio lircd-setup[2235]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. May 26 15:32:04 volumio lircd-setup[2235]: parser = configparser.SafeConfigParser() May 26 15:32:04 volumio systemd[1]: lircd-setup.service: Deactivated successfully. May 26 15:32:04 volumio systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. May 26 15:32:04 volumio systemd[1]: Started evo_irexec.service. May 26 15:32:04 volumio sudo[2197]: pam_unix(sudo:session): session closed for user root May 26 15:32:04 volumio lircd[2139]: lircd-0.10.1[2139]: Notice: accepted new client on /var/run/lirc/lircd May 26 15:32:04 volumio lircd-0.10.1[2139]: Notice: accepted new client on /var/run/lirc/lircd May 26 15:32:04 volumio lircd[2139]: lircd-0.10.1[2139]: Info: [lirc] protocol is enabled May 26 15:32:04 volumio lircd-0.10.1[2139]: Info: [lirc] protocol is enabled May 26 15:32:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 26 15:32:04 volumio volumio[1306]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 15:32:05 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 26 15:32:05 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 15:32:05 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 15:32:05 volumio systemd[1]: mpd.service: Consumed 2.869s CPU time. May 26 15:32:05 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 15:32:05 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 15:32:05 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 15:32:05 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 15:32:05 volumio volumio[1306]: info: touch_display: X display number found: 0 May 26 15:32:05 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 15:32:05 volumio volumio[1306]: info: Received Get System Info May 26 15:32:05 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:32:05 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:32:05 volumio volumio[1306]: info: Discovery: Getting this device information May 26 15:32:05 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:05 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:05 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:32:05 volumio volumio[1306]: verbose: New Socket.io Connection to 192.168.0.68 from 192.168.0.40 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 May 26 15:32:05 volumio sudo[2262]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 15:32:05 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:32:05 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root May 26 15:32:05 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. May 26 15:32:05 volumio sudo[2104]: pam_unix(sudo:session): session closed for user root May 26 15:32:05 volumio volumio[1306]: info: MPD Permissions set May 26 15:32:05 volumio volumio[1306]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success May 26 15:32:05 volumio volumio[1306]: info: Completed starting Core Plugins May 26 15:32:05 volumio volumio[1306]: info: ------------------------------------------- May 26 15:32:05 volumio volumio[1306]: info: ----- MyVolumio plugins startup ---- May 26 15:32:05 volumio volumio[1306]: info: ------------------------------------------- May 26 15:32:05 volumio volumio[1306]: info: [MyVolumio PluginManager] Fetching plans data.... May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 26 15:32:06 volumio volumio5-onboarding[1733]: time=2026-05-26T15:32:06.090+02:00 level=INFO msg="enabling local network discovery" May 26 15:32:06 volumio volumio5-onboarding[1733]: time=2026-05-26T15:32:06.125+02:00 level=INFO msg="enabling BLE discovery" May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:32:06 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:07 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:07 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 15:32:07 volumio volumio5-onboarding[1733]: time=2026-05-26T15:32:07.190+02:00 level=INFO msg="service successfully established" component=discovery/localnet May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 15:32:07 volumio volumio[1306]: info: Received Get System Info May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:32:07 volumio volumio[1306]: info: Discovery: Getting this device information May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:07 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:32:07 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:07 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:07 volumio volumio[1306]: info: Listing playlists May 26 15:32:08 volumio volumio[1306]: info: touch_display: Setting screensaver timeout to 0 seconds. May 26 15:32:09 volumio volumio[1306]: info: Starting Shairport Sync May 26 15:32:09 volumio sudo[2314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:32:09 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:09 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:32:09 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:32:09 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:32:09 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root May 26 15:32:09 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:09 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:10 volumio mpd[2273]: 2026-05-26T15:32:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 15:32:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 26 15:32:10 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root May 26 15:32:10 volumio volumio[1306]: info: CoreCommandRouter::getUIConfigOnPlugin May 26 15:32:10 volumio volumio[1306]: info: Shairport-Sync Started May 26 15:32:11 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:32:11 volumio volumio[1306]: info: Discovery: Getting this device information May 26 15:32:11 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:11 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:11 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:32:12 volumio volumio[1306]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 May 26 15:32:12 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. May 26 15:32:12 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. May 26 15:32:13 volumio volumio[1306]: error: updateQueue error: null May 26 15:32:15 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:15 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:15 volumio volumio[1306]: info: CoreCommandRouter::volumioGetQueue May 26 15:32:15 volumio volumio[1306]: info: CoreStateMachine::getQueue May 26 15:32:15 volumio volumio[1306]: info: CorePlayQueue::getQueue May 26 15:32:15 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:15 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:15 volumio volumio[1306]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 26 15:32:16 volumio volumio[1306]: info: Adding plugin bluetooth to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: Adding plugin multiroom to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: Adding plugin metavolumio to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: Adding plugin cd_controller to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: Adding plugin qobuzconnect to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: Adding plugin smart_inputs to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: Adding plugin tidalconnect to MyMusic Plugins May 26 15:32:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 26 15:32:24 volumio volumio[1306]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 26 15:32:24 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 26 15:32:24 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:24 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:24 volumio volumio[1306]: info: Starting MyVolumio Remote Streaming Endpoints May 26 15:32:25 volumio volumio[1306]: info: MyVolumio login type: Token May 26 15:32:25 volumio volumio[1306]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 26 15:32:25 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 26 15:32:28 volumio volumio[1306]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 26 15:32:28 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 26 15:32:28 volumio volumio[1306]: info: Streaming services startup May 26 15:32:28 volumio volumio[1306]: info: Starting Streaming Daemon May 26 15:32:28 volumio sudo[2380]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 26 15:32:28 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:28 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root May 26 15:32:28 volumio volumio[1306]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 26 15:32:29 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:32:29 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:29 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:32:29 volumio volumio[1306]: info: [now-playing] Weather not configured: Pogoda nie jest skonfigurowana: dodaj współrzędne w Lokalizacji i klucz API OpenWeatherMap w Usłudze pogody. May 26 15:32:29 volumio volumio[1306]: info: CoreCommandRouter::volumioGetQueue May 26 15:32:29 volumio volumio[1306]: info: CoreStateMachine::getQueue May 26 15:32:29 volumio volumio[1306]: info: CorePlayQueue::getQueue May 26 15:32:29 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: ytmusic , handleBrowseUri May 26 15:32:29 volumio volumio[1306]: info: [ytmusic-browse] browseUri: ytmusic May 26 15:32:29 volumio volumio[1306]: error: Cannot start Volumio Streaming Daemon May 26 15:32:29 volumio volumio[1306]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 26 15:32:29 volumio volumio[1306]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 26 15:32:29 volumio volumio[1306]: info: Preload queue cleared May 26 15:32:31 volumio volumio[1306]: Searching plugin music_service/ytmusic May 26 15:32:31 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search May 26 15:32:32 volumio volumio-remote-updater[823]: Test mode enabled May 26 15:32:32 volumio volumio-remote-updater[823]: Alpha mode disabled May 26 15:32:32 volumio volumio-remote-updater[823]: Alpha legacy test mode disabled May 26 15:32:33 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 26 15:32:33 volumio volumio[1306]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 26 15:32:33 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 26 15:32:34 volumio volumio[1306]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 26 15:32:34 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 26 15:32:34 volumio volumio[1306]: info: Listing playlists May 26 15:32:34 volumio volumio[1306]: info: CoreCommandRouter::volumioGetQueue May 26 15:32:34 volumio volumio[1306]: info: CoreStateMachine::getQueue May 26 15:32:34 volumio volumio[1306]: info: CorePlayQueue::getQueue May 26 15:32:34 volumio volumio[1306]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:32:34 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:32:34 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:32:34 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:32:35 volumio volumio[1306]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 26 15:32:38 volumio volumio[1306]: info: MyVolumio token set successfully May 26 15:32:38 volumio volumio[1306]: info: MYVOLUMIO: Adding device May 26 15:32:38 volumio volumio[1306]: info: MYVOLUMIO: Evaluating Server May 26 15:32:39 volumio volumio[1306]: info: MyVolumio Plan changed: premium May 26 15:32:39 volumio volumio[1306]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 26 15:32:39 volumio volumio[1306]: info: Removing browser output: myVolumio user plan is not superstar May 26 15:32:39 volumio volumio[1306]: info: Removing audio output: May 26 15:32:39 volumio volumio[1306]: info: MYVOLUMIO: Adding device May 26 15:32:39 volumio volumio[1306]: info: MYVOLUMIO: Evaluating Server May 26 15:32:41 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 26 15:32:42 volumio volumio[1306]: info: Setting Geolocation for MyVolumio to eu4 May 26 15:32:42 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:42 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:42 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:42 volumio volumio[1306]: info: Setting Geolocation for MyVolumio to eu10 May 26 15:32:42 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:42 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:42 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:42 volumio volumio[1306]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 May 26 15:32:42 volumio volumio[1306]: info: Remote config written successfully May 26 15:32:42 volumio volumio[1306]: info: Starting Tunnel 1 May 26 15:32:42 volumio volumio[1306]: info: Starting Tunnel Connection Checker May 26 15:32:42 volumio volumio[1306]: info: Completed starting MyVolumio Plugin May 26 15:32:42 volumio volumio[1306]: info: Successfully Added MyVolumio device May 26 15:32:42 volumio volumio[1306]: info: MYVolumio Device enabled May 26 15:32:42 volumio volumio[1306]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 26 15:32:42 volumio volumio[1306]: info: MyVolumio status changed May 26 15:32:42 volumio volumio[1306]: info: Streaming services startup May 26 15:32:42 volumio volumio[1306]: info: Starting Streaming Daemon May 26 15:32:43 volumio sudo[2460]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 26 15:32:43 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:43 volumio volumio[1306]: info: Updating MyVolumio device info May 26 15:32:43 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:43 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:43 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:43 volumio volumio[1306]: info: Successfully Added MyVolumio device May 26 15:32:43 volumio volumio[1306]: info: Updating MyVolumio device info May 26 15:32:43 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:43 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:43 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:32:43 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root May 26 15:32:44 volumio volumio[1306]: error: Cannot start Volumio Streaming Daemon May 26 15:32:44 volumio volumio[1306]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 26 15:32:44 volumio volumio[1306]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 26 15:32:44 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 26 15:32:44 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 26 15:32:46 volumio volumio[1306]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth May 26 15:32:46 volumio volumio[1306]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth May 26 15:32:46 volumio volumio[1306]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 26 15:32:46 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 26 15:32:58 volumio volumio[1306]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 26 15:32:58 volumio sudo[2480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 26 15:32:58 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:32:58 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root May 26 15:32:59 volumio volumio[1306]: info: MRS: MultiRoom plugin initialized May 26 15:32:59 volumio volumio[1306]: info: MRS: STOPPING SNAPCLIENT May 26 15:32:59 volumio volumio[1306]: info: MRS: Snap server stop May 26 15:32:59 volumio volumio[1306]: info: MRS: STOPPING volumioStreaming May 26 15:33:00 volumio sudo[2497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 26 15:33:00 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:00 volumio sudo[2499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 26 15:33:00 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:00 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 26 15:33:00 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root May 26 15:33:00 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root May 26 15:33:00 volumio sudo[2504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 26 15:33:00 volumio sudo[2504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:00 volumio sudo[2505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 26 15:33:00 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:00 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root May 26 15:33:00 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 26 15:33:00 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 26 15:33:00 volumio sudo[2504]: pam_unix(sudo:session): session closed for user root May 26 15:33:00 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 26 15:33:01 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 26 15:33:01 volumio volumio[1306]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 26 15:33:01 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 26 15:33:01 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 26 15:33:01 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 26 15:33:02 volumio volumio[1306]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 26 15:33:02 volumio volumio[1306]: info: Preparing to generate the ALSA configuration file May 26 15:33:02 volumio volumio[1306]: info: Successfully Updated MyVolumio device May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:33:02 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::volumioGetBrowseSources May 26 15:33:02 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 26 15:33:02 volumio volumio[1306]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 26 15:33:02 volumio volumio[1306]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 26 15:33:02 volumio volumio[1306]: info: Reading ALSA contributions from plugins. May 26 15:33:02 volumio volumio[1306]: info: Successfully Updated MyVolumio device May 26 15:33:03 volumio volumio[1306]: info: MRS: Removed streaming files May 26 15:33:03 volumio volumio[1306]: info: MRS: volumioStreaming STOPPED May 26 15:33:03 volumio volumio[1306]: info: MRS: SNAPSERVER STOPPED May 26 15:33:03 volumio volumio[1306]: info: MRS: SNAPCLIENT STOPPED May 26 15:33:04 volumio sudo[2531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 26 15:33:04 volumio sudo[2531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:33:04 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 26 15:33:04 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 26 15:33:04 volumio autossh[2534]: port set to 0, monitoring disabled May 26 15:33:04 volumio autossh[2534]: starting ssh (count 1) May 26 15:33:04 volumio autossh[2534]: ssh child pid is 2537 May 26 15:33:04 volumio sudo[2531]: pam_unix(sudo:session): session closed for user root May 26 15:33:04 volumio volumio[1306]: info: Remote SSH Started May 26 15:33:04 volumio volumiossh-tunnel[2537]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. May 26 15:33:05 volumio volumio[1306]: info: Asound.conf file written May 26 15:33:06 volumio sudo[2540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 26 15:33:06 volumio sudo[2540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:06 volumio sudo[2540]: pam_unix(sudo:session): session closed for user root May 26 15:33:07 volumio volumio[1306]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 26 15:33:07 volumio volumio[1306]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 26 15:33:07 volumio volumio[1306]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 26 15:33:07 volumio volumio[1306]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 May 26 15:33:07 volumio volumio[1306]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 May 26 15:33:07 volumio volumio[1306]: info: Output device has changed, restarting MPD May 26 15:33:07 volumio volumio[1306]: info: Output device has changed, restarting Shairport Sync May 26 15:33:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:33:07 volumio sudo[2546]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 26 15:33:07 volumio sudo[2546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:07 volumio sudo[2548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 26 15:33:07 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:07 volumio sudo[2546]: pam_unix(sudo:session): session closed for user root May 26 15:33:07 volumio volumio[1306]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 26 15:33:07 volumio volumio[1306]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 15:33:07 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 26 15:33:07 volumio systemd[1]: mpd.service: Deactivated successfully. May 26 15:33:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 26 15:33:07 volumio systemd[1]: mpd.service: Consumed 1.640s CPU time. May 26 15:33:07 volumio systemd[1]: mpd.socket: Deactivated successfully. May 26 15:33:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 26 15:33:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 26 15:33:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 26 15:33:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 26 15:33:08 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 26 15:33:08 volumio volumio[1306]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 26 15:33:08 volumio volumio[1306]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 26 15:33:08 volumio sudo[2557]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 26 15:33:08 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 26 15:33:08 volumio sudo[2557]: pam_unix(sudo:session): session closed for user root May 26 15:33:08 volumio volumio[1306]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 26 15:33:08 volumio volumio[1306]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 26 15:33:08 volumio volumio[1306]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 26 15:33:08 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 26 15:33:08 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 26 15:33:08 volumio volumio[1306]: info: MRS: Pushing multiroomSync output for this device May 26 15:33:08 volumio volumio[1306]: info: MRS: Pushing multiroomSync output May 26 15:33:08 volumio volumio[1306]: info: Adding audio output: May 26 15:33:08 volumio volumio[1306]: info: Adding audio output: May 26 15:33:08 volumio volumio[1306]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 26 15:33:08 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:08 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 26 15:33:08 volumio volumio[1306]: info: Adding METAVOLUMIO REST API Endpoints May 26 15:33:08 volumio volumio[1306]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 26 15:33:08 volumio volumio[1306]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 26 15:33:08 volumio volumio[1306]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 26 15:33:09 volumio volumio[1306]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 26 15:33:09 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 26 15:33:09 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 26 15:33:09 volumio volumio[1306]: info: Preparing CD Folders May 26 15:33:09 volumio volumio[1306]: info: Adding CD REST API Endpoints May 26 15:33:09 volumio volumio[1306]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 26 15:33:09 volumio volumio[1306]: info: Starting UDEV Watcher for CD May 26 15:33:09 volumio volumio[1306]: info: Detecting CD presence with UDEV May 26 15:33:09 volumio bluetoothd[913]: Path / reserved for Adv Monitor app :1.28 May 26 15:33:09 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 26 15:33:09 volumio bluetoothd[913]: Adv Monitor app :1.28 disconnected from D-Bus May 26 15:33:10 volumio mpd[2560]: 2026-05-26T15:33:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 26 15:33:10 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 26 15:33:10 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root May 26 15:33:15 volumio volumio[1306]: warn: [cd-plugin] cdspeedctl: device or media not ready May 26 15:33:15 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 26 15:33:15 volumio volumio[1306]: info: Adding inputs REST Endpoints May 26 15:33:15 volumio volumio[1306]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 26 15:33:15 volumio volumio[1306]: info: Scanning Audio Inputs May 26 15:33:15 volumio volumio[1306]: info: Checking against Known Cards name May 26 15:33:15 volumio volumio[1306]: info: Adding Server instance for streaming May 26 15:33:15 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 26 15:33:15 volumio volumio[1306]: error: Hi Res Audio Failed Login: Missing Login Data May 26 15:33:15 volumio volumio[1306]: info: Adding HIGHRESAUDIO REST API Endpoints May 26 15:33:15 volumio volumio[1306]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 26 15:33:15 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 26 15:33:15 volumio volumio[1306]: info: Refreshing TIDAL token May 26 15:33:15 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 26 15:33:15 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 26 15:33:16 volumio volumio[1306]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect May 26 15:33:16 volumio volumio[1306]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect May 26 15:33:16 volumio sudo[2630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 26 15:33:16 volumio sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:16 volumio volumio[1306]: info: QobuzConnect: Starting Qobuz Connect socket and service May 26 15:33:16 volumio sudo[2630]: pam_unix(sudo:session): session closed for user root May 26 15:33:16 volumio sudo[2636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service May 26 15:33:16 volumio sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:16 volumio volumio[1306]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections May 26 15:33:16 volumio volumio[1306]: info: Stopping AccessToken refresher cron for QOBUZ May 26 15:33:16 volumio sudo[2636]: pam_unix(sudo:session): session closed for user root May 26 15:33:16 volumio volumio[1306]: info: AccessToken refresher cron started for QOBUZ May 26 15:33:16 volumio volumio[1306]: info: Adding QOBUZ REST API Endpoints May 26 15:33:16 volumio sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service May 26 15:33:16 volumio sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:16 volumio volumio[1306]: info: MRS: Getting audio outputs on start May 26 15:33:16 volumio volumio[1306]: info: MRS: Requesting all other devices output May 26 15:33:16 volumio volumio[1306]: error: MyVolumio Plugins failed to start in a timely fashion May 26 15:33:16 volumio volumio[1306]: error: Plugin audio_interface bluetooth failed to complete 'onStart' in a timely fashion May 26 15:33:16 volumio volumio[1306]: error: Plugin audio_interface multiroom failed to complete 'onStart' in a timely fashion May 26 15:33:16 volumio volumio[1306]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion May 26 15:33:16 volumio volumio[1306]: error: Plugin music_service tidalconnect failed to complete 'onStart' in a timely fashion May 26 15:33:16 volumio volumio[1306]: error: Plugin music_service qobuzconnect failed to complete 'onStart' in a timely fashion May 26 15:33:16 volumio volumio[1306]: [Metrics] CommandRouter: 123s 428.86ms May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::volumiosetStartupVolume May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:16 volumio volumio[1306]: info: VolumeController:: Setting startup Volume 15 May 26 15:33:16 volumio volumio[1306]: info: VolumeController::SetAlsaVolume15 May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::Close All Modals sent May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::Close All Modals sent May 26 15:33:16 volumio volumio[1306]: info: CoreStateMachine::pushState May 26 15:33:16 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::volumioPushState May 26 15:33:16 volumio volumio[1306]: info: MRS: Pushing multiroomSync output update for this device May 26 15:33:16 volumio volumio[1306]: info: MRS: Pushing multiroomSync output May 26 15:33:16 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:33:16 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:17 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch May 26 15:33:17 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. May 26 15:33:17 volumio sudo[2639]: pam_unix(sudo:session): session closed for user root May 26 15:33:17 volumio volumio[1306]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 26 15:33:18 volumio sudo[2652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 26 15:33:18 volumio sudo[2652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:18 volumio volumio[1306]: info: MPD Permissions set May 26 15:33:18 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 26 15:33:18 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 26 15:33:18 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 26 15:33:18 volumio volumio[1306]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 26 15:33:18 volumio volumio[1306]: info: FusionDsp - ---- read samplerate from file: 44100 May 26 15:33:18 volumio volumio[1306]: info: camilladsp stopping service pid 2086... May 26 15:33:18 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 26 15:33:18 volumio sudo[2652]: pam_unix(sudo:session): session closed for user root May 26 15:33:18 volumio volumiobt[2659]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 26 15:33:18 volumio sudo[2660]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 26 15:33:18 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:18 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root May 26 15:33:18 volumio sudo[2662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 26 15:33:18 volumio sudo[2662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:18 volumio sudo[2662]: pam_unix(sudo:session): session closed for user root May 26 15:33:18 volumio volumiobt[2664]: INFO [BTSTART] Powering on Bluetooth if needed... May 26 15:33:18 volumio bluetoothd[913]: Adv Monitor app :1.30 disconnected from D-Bus May 26 15:33:18 volumio volumiobt[2669]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 26 15:33:18 volumio volumiobt[2670]: [176B blob data] May 26 15:33:18 volumio volumiobt[2670]: [157B blob data] May 26 15:33:18 volumio volumiobt[2670]: [157B blob data] May 26 15:33:18 volumio volumiobt[2670]: [157B blob data] May 26 15:33:18 volumio volumiobt[2670]: [113B blob data] May 26 15:33:18 volumio volumiobt[2670]: [bluetoothctl]> discoverable on May 26 15:33:18 volumio volumiobt[2670]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended May 26 15:33:18 volumio volumiobt[2670]: [bluetoothctl]> pairable on May 26 15:33:18 volumio bluetoothd[913]: Path / reserved for Adv Monitor app :1.31 May 26 15:33:18 volumio bluetoothd[913]: Adv Monitor app :1.31 disconnected from D-Bus May 26 15:33:18 volumio volumiobt[2670]: [bluetoothctl]> May 26 15:33:18 volumio volumiobt[2672]: INFO [BTSTART] Registering Bluetooth agent... May 26 15:33:18 volumio volumio[1306]: grep: /proc/2086/cmdline: binary file matches May 26 15:33:18 volumio volumio[1306]: grep: /proc/2086/cmdline: binary file matches May 26 15:33:18 volumio volumio[1306]: info: camilladsp service terminated, instance 1 May 26 15:33:18 volumio volumio[1306]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 26 15:33:18 volumio volumiobt[2673]: [NEW] Media /org/bluez/hci0 May 26 15:33:18 volumio volumiobt[2673]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 26 15:33:18 volumio volumiobt[2673]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 26 15:33:18 volumio volumiobt[2673]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 26 15:33:18 volumio bluetoothd[913]: Adv Monitor app :1.32 disconnected from D-Bus May 26 15:33:18 volumio volumiobt[2674]: No agent is registered May 26 15:33:18 volumio volumiobt[2674]: [NEW] Media /org/bluez/hci0 May 26 15:33:18 volumio volumiobt[2674]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb May 26 15:33:18 volumio volumiobt[2674]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb May 26 15:33:18 volumio volumiobt[2674]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb May 26 15:33:18 volumio bluetoothd[913]: Adv Monitor app :1.33 disconnected from D-Bus May 26 15:33:18 volumio volumiobt[2675]: INFO [BTSTART] Agent registered successfully. May 26 15:33:18 volumio volumiobt[2676]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 26 15:33:19 volumio volumio[1306]: info: camilladsp service started and running in background, instance 1 May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Connecting to system D-Bus May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Connected to system D-Bus May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 bluezutils [INFO] Found adapter at: /org/bluez/hci0 May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Found Bluetooth adapter: /org/bluez/hci0 May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Set DiscoverableTimeout to infinite May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Enabled Discoverable mode May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Agent registered at /local/a2dpagent May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] Agent set as default May 26 15:33:19 volumio volumiobt[2677]: 2026-05-26 15:33:19 a2dp-agent [INFO] A2DP agent running, waiting for connections... May 26 15:33:19 volumio volumio[1306]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 26 15:33:19 volumio volumio[1306]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 26 15:33:19 volumio volumio[1306]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 26 15:33:19 volumio volumio[1306]: info: camilladsp respawn in 100 ms (attempt 1/10) May 26 15:33:20 volumio volumio[1306]: info: Executing endpoint qc_getconfig May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.401 [2650.2650] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 26 15:33:20 volumio volumio[1306]: info: TidalConnect service stoped! May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.508 [2650.2650] INFO VolumeManager: [0x91c068]: Setting new playback volume: 75 May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.509 [2650.2650] INFO VolumeManager: [0x91c068]: Setting new mute state: 0 May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.509 [2650.2650] INFO AudioStreamManager: [0x91bdc0]: Setting new audio download buffer size: 1048576 May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.509 [2650.2650] INFO QobuzConnect: [0x91c930]: Client initialized! May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.509 [2650.2650] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.526 [2650.2650] INFO LocalConfigManager: [0x91bae8]: Starting Local Configuration server May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.526 [2650.2650] INFO SampleApp: Starting Local configuration server May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.527 [2650.2650] INFO SampleApp: Connected to UNIX socket client 0x906818 May 26 15:33:20 volumio qobuz-connect[2650]: 20260526 15:33:20.552 [2650.2650] INFO SampleApp: Playback volume changed: 75 May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 26 15:33:20 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 26 15:33:20 volumio volumio[1306]: error: updateQueue error: null May 26 15:33:21 volumio volumio[1306]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 26 15:33:21 volumio volumio[1306]: info: QobuzConnect: QOBUZ Connect daemon connected May 26 15:33:21 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:33:21 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:21 volumio volumio[1306]: info: MRS: Found cast device: SHIELD-Android-TV-6342eac47aaaecaa412ced02b70b9d3c May 26 15:33:21 volumio volumio[1306]: info: Adding audio output: May 26 15:33:21 volumio volumio[1306]: info: Access Token successfully retrieved May 26 15:33:21 volumio volumio[1306]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 26 15:33:21 volumio volumio[1306]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 26 15:33:21 volumio volumio[1306]: info: Starting Shairport Sync May 26 15:33:22 volumio sudo[2708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 26 15:33:22 volumio sudo[2708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 26 15:33:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 26 15:33:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:33:22 volumio systemd[1]: shairport-sync.service: Consumed 1.349s CPU time. May 26 15:33:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 26 15:33:22 volumio sudo[2708]: pam_unix(sudo:session): session closed for user root May 26 15:33:22 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:33:22 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:22 volumio volumio[1306]: info: Successfully retrieved User Session From TIDAL May 26 15:33:22 volumio volumio[1306]: info: Shairport-Sync Started May 26 15:33:22 volumio sudo[2726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 26 15:33:22 volumio sudo[2726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 26 15:33:23 volumio volumio[1306]: info: Successfully retrieved User Subscription From TIDAL May 26 15:33:23 volumio volumio[1306]: info: Adding TIDAL to Browse Sources May 26 15:33:23 volumio volumio[1306]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 26 15:33:23 volumio volumio[1306]: info: [1779802403274] CoreMusicLibrary::Adding element TIDAL May 26 15:33:23 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:33:23 volumio volumio[1306]: Cannot find translation for source YouTube Music May 26 15:33:23 volumio volumio[1306]: Cannot find translation for source TIDAL May 26 15:33:23 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 26 15:33:23 volumio sudo[2726]: pam_unix(sudo:session): session closed for user root May 26 15:33:23 volumio volumio[1306]: info: Adding TIDAL REST API Endpoints May 26 15:33:23 volumio volumio[1306]: info: Executing endpoint tc_getconfig May 26 15:33:23 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 26 15:33:23 volumio vtcs[2729]: STARTING TidalConnect services, version: 1.6.1 May 26 15:33:23 volumio vtcs[2729]: STARTED TidalConnect services. May 26 15:33:24 volumio volumio[1306]: info: BOOT COMPLETED May 26 15:33:24 volumio volumio[1306]: info: camilladsp respawn in 200 ms (attempt 2/10) May 26 15:33:24 volumio volumio[1306]: info: Executing endpoint tc_connect May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 26 15:33:24 volumio volumio[1306]: info: Connecting to TidalConnect May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::servicePushState May 26 15:33:24 volumio volumio[1306]: info: CoreStateMachine::pushState May 26 15:33:24 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::volumioPushState May 26 15:33:24 volumio volumio[1306]: info: MRS: Pushing multiroomSync output update for this device May 26 15:33:24 volumio volumio[1306]: info: MRS: Pushing multiroomSync output May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:33:24 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:24 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:24 volumio volumio[1306]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::servicePushState May 26 15:33:24 volumio volumio[1306]: info: CoreStateMachine::pushState May 26 15:33:24 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::volumioPushState May 26 15:33:24 volumio volumio[1306]: info: MRS: Pushing multiroomSync output update for this device May 26 15:33:24 volumio volumio[1306]: info: MRS: Pushing multiroomSync output May 26 15:33:24 volumio volumio[1306]: info: CoreCommandRouter::volumioGetState May 26 15:33:24 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:24 volumio volumio[1306]: info: CorePlayQueue::getTrack 0 May 26 15:33:24 volumio volumio[1306]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 26 15:33:25 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList May 26 15:33:25 volumio volumio[1306]: info: CoreCommandRouter::volumioGetBrowseSources May 26 15:33:25 volumio volumio[1306]: info: CoreCommandRouter::volumioGetBrowseSources May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:25 volumio volumio[1306]: info: All cached search sources collected May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: mpd , search May 26 15:33:26 volumio volumio[1306]: info: CoreCommandRouter::executeOnPlugin: tidal , search May 26 15:33:28 volumio volumio[1306]: info: TidalConnect service started! May 26 15:33:29 volumio volumio[1306]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:33:29 volumio volumio[1306]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. May 26 15:33:29 volumio volumio[1306]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues May 26 15:33:29 volumio volumio[1306]: at new NodeError (node:internal/errors:405:5) May 26 15:33:29 volumio volumio[1306]: at assert (node:internal/assert:14:11) May 26 15:33:29 volumio volumio[1306]: at internalConnectMultiple (node:net:1118:3) May 26 15:33:29 volumio volumio[1306]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) May 26 15:33:29 volumio volumio[1306]: at listOnTimeout (node:internal/timers:575:11) May 26 15:33:29 volumio volumio[1306]: at process.processTimers (node:internal/timers:514:7) { May 26 15:33:29 volumio volumio[1306]: code: 'ERR_INTERNAL_ASSERTION' May 26 15:33:29 volumio volumio[1306]: } May 26 15:33:29 volumio volumio[1306]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:33:36 volumio sudo[2791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 15:32' May 26 15:33:36 volumio sudo[2791]: 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="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"