Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: Pool taking: 31.58.102.164 Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: Pool taking: 45.13.105.44 Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: Pool taking: 172.234.184.36 Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: Pool taking: 146.59.30.179 Feb 18 10:10:00 volumiopi5 ntpd[1548]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 18 10:10:00 volumiopi5 sudo[1701]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: Regdomain already correct: FR Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: Single Network Mode: Ethernet active, maintaining WiFi scan capability Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: SNM: Maintaining wlan0 UP without IP (scan mode) Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: SNM: Users can configure WiFi via WebUI while ethernet is active Feb 18 10:10:00 volumiopi5 sudo[1728]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 18 10:10:00 volumiopi5 sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 10:10:00 volumiopi5 sudo[1728]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:00 volumiopi5 sudo[1731]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 18 10:10:00 volumiopi5 sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 10:10:00 volumiopi5 sudo[1731]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:00 volumiopi5 wpa_supplicant[1734]: Successfully initialized wpa_supplicant Feb 18 10:10:00 volumiopi5 wpa_supplicant[1734]: nl80211: kernel reports: Registration to specific type not supported Feb 18 10:10:00 volumiopi5 wpa_supplicant[1737]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: SNM: Transition to scan mode completed in 3313ms Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: SNM: wlan0 is UP without IP, scan capable Feb 18 10:10:00 volumiopi5 wireless.js[915]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 18 10:10:01 volumiopi5 volumio[1208]: info: Received Get System Info Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 10:10:01 volumiopi5 volumio[1208]: info: Discovery: Getting this device information Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CorePlayQueue::getTrack 0 Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 10:10:01 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 10:10:01 volumiopi5 volumio[1208]: info: Discovery: adding 3f8c4401-2d84-44fc-ae3f-3da2c198cfda Feb 18 10:10:01 volumiopi5 volumio[1208]: info: Discovery: Found device Volumio Feb 18 10:10:01 volumiopi5 volumio[1208]: info: Discovery: Connecting to remote: 192.168.1.88 Feb 18 10:10:01 volumiopi5 volumio[1208]: info: Discovery: Connected to remote: 192.168.1.88 Feb 18 10:10:02 volumiopi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Feb 18 10:10:02 volumiopi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 10:10:02 volumiopi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 10:10:02 volumiopi5 upmpdcli[1754]: Could not open config: /tmp/upmpdcli.conf Feb 18 10:10:02 volumiopi5 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 18 10:10:02 volumiopi5 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 18 10:10:02 volumiopi5 volumio[1208]: info: Volumio Network Manager: Network status updated: 1 Feb 18 10:10:02 volumiopi5 sudo[1770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 10:10:02 volumiopi5 sudo[1770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:02 volumiopi5 sudo[1772]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 10:10:02 volumiopi5 sudo[1770]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:02 volumiopi5 sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:02 volumiopi5 sudo[1772]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:02 volumiopi5 sudo[1775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 10:10:02 volumiopi5 sudo[1775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:03 volumiopi5 ntpd[1548]: CLOCK: time stepped by 0.459251 Feb 18 10:10:03 volumiopi5 ntpd[1548]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Feb 18 10:10:04 volumiopi5 volumio[1208]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 18 10:10:04 volumiopi5 volumio[1208]: info: Received Get System Info Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 10:10:04 volumiopi5 volumio[1208]: info: Discovery: Getting this device information Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CorePlayQueue::getTrack 0 Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 18 10:10:04 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 18 10:10:04 volumiopi5 volumio[1208]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.096&uuid=9d3af8b839f0b205dea3c050608afe19" http://updates.volumio.org/downloader-v1/track-device Feb 18 10:10:04 volumiopi5 volumio[1208]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 18 10:10:04 volumiopi5 volumio[1208]: Dload Upload Total Spent Left Speed Feb 18 10:10:04 volumiopi5 volumio[1208]: [132B blob data] Feb 18 10:10:04 volumiopi5 volumio[1208]: retrying in 5 seconds, trial 0 Feb 18 10:10:04 volumiopi5 volumio[1208]: info: Volumio Calling Home Feb 18 10:10:04 volumiopi5 volumio[1208]: info: Volumio called home Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin bluetooth to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin multiroom to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin metavolumio to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin cd_controller to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 18 10:10:07 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:07 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Starting MyVolumio Remote Streaming Endpoints Feb 18 10:10:07 volumiopi5 volumio[1208]: info: MyVolumio login type: Token Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Streaming services startup Feb 18 10:10:07 volumiopi5 volumio[1208]: info: Starting Streaming Daemon Feb 18 10:10:07 volumiopi5 sudo[1782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 10:10:07 volumiopi5 sudo[1782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:07 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 18 10:10:08 volumiopi5 sudo[1782]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:08 volumiopi5 volumio[1208]: error: Cannot start Volumio Streaming Daemon Feb 18 10:10:08 volumiopi5 volumio[1208]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 10:10:08 volumiopi5 volumio[1208]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 10:10:08 volumiopi5 volumio[1208]: error: MyVolumio Custom Token format not valid, refreshing it Feb 18 10:10:08 volumiopi5 volumio[1208]: info: MyVolumio login type: Token Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MyVolumio token set successfully Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MYVOLUMIO: Adding device Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MYVOLUMIO: Evaluating Server Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MyVolumio Plan changed: premium Feb 18 10:10:09 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Removing browser output: myVolumio user plan is not superstar Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Removing audio output: Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MYVOLUMIO: Adding device Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MYVOLUMIO: Evaluating Server Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Remote config written successfully Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Starting Tunnel 1 Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Starting Tunnel Connection Checker Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Completed starting MyVolumio Plugin Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MYVolumio Device enabled Feb 18 10:10:09 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 18 10:10:09 volumiopi5 volumio[1208]: info: MyVolumio status changed Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Streaming services startup Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Starting Streaming Daemon Feb 18 10:10:09 volumiopi5 sudo[1828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 10:10:09 volumiopi5 sudo[1828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:09 volumiopi5 sudo[1828]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 18 10:10:09 volumiopi5 volumio[1208]: error: [MyVolumio PluginManager] Cache data is invalid! Feb 18 10:10:09 volumiopi5 volumio[1208]: error: Cannot start Volumio Streaming Daemon Feb 18 10:10:09 volumiopi5 volumio[1208]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 10:10:09 volumiopi5 volumio[1208]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Setting Geolocation for MyVolumio to eu10 Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:09 volumiopi5 volumio[1208]: info: Setting Geolocation for MyVolumio to eu7 Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:09 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 18 10:10:10 volumiopi5 sudo[1833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 18 10:10:10 volumiopi5 sudo[1833]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 sudo[1833]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: MultiRoom plugin initialized Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: STOPPING SNAPCLIENT Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: Snap server stop Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: STOPPING volumioStreaming Feb 18 10:10:10 volumiopi5 sudo[1850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 18 10:10:10 volumiopi5 sudo[1850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 18 10:10:10 volumiopi5 sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 18 10:10:10 volumiopi5 sudo[1855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 18 10:10:10 volumiopi5 sudo[1855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 18 10:10:10 volumiopi5 sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 sudo[1858]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 18 10:10:10 volumiopi5 sudo[1850]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 sudo[1855]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 18 10:10:10 volumiopi5 sudo[1852]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Preparing to generate the ALSA configuration file Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Successfully Added MyVolumio device Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Successfully Added MyVolumio device Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: Removed streaming files Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: volumioStreaming STOPPED Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: SNAPSERVER STOPPED Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: SNAPCLIENT STOPPED Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Asound.conf file unchanged, so no further update is needed Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Output device has changed, restarting MPD Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Output device has changed, restarting Shairport Sync Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 sudo[1862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 10:10:10 volumiopi5 sudo[1862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 sudo[1862]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 sudo[1864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 10:10:10 volumiopi5 sudo[1864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: [FUNC] onStart Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding METAVOLUMIO REST API Endpoints Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding inputs REST Endpoints Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Scanning Audio Inputs Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Checking against Known Cards name Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding Server instance for streaming Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Feb 18 10:10:10 volumiopi5 volumio[1208]: error: Hi Res Audio Failed Login: Missing Login Data Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Refreshing TIDAL token Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Path / reserved for Adv Monitor app :1.21 Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Adv Monitor app :1.21 disconnected from D-Bus Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Feb 18 10:10:10 volumiopi5 volumio[1208]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Stopping AccessToken refresher cron for QOBUZ Feb 18 10:10:10 volumiopi5 sudo[1879]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 18 10:10:10 volumiopi5 sudo[1879]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 volumio[1208]: info: AccessToken refresher cron started for QOBUZ Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Adding QOBUZ REST API Endpoints Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Feb 18 10:10:10 volumiopi5 sudo[1879]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumio[1208]: info: MPD Permissions set Feb 18 10:10:10 volumiopi5 sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 18 10:10:10 volumiopi5 sudo[1883]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Starting Shairport Sync Feb 18 10:10:10 volumiopi5 sudo[1892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 10:10:10 volumiopi5 sudo[1892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Updating MyVolumio device info Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Feb 18 10:10:10 volumiopi5 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 18 10:10:10 volumiopi5 volumiobt[1894]: INFO [BTSTART] Ensuring Bluetooth directory exists... Feb 18 10:10:10 volumiopi5 systemd[1]: shairport-sync.service: Deactivated successfully. Feb 18 10:10:10 volumiopi5 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 10:10:10 volumiopi5 systemd[1]: shairport-sync.service: Consumed 1.529s CPU time. Feb 18 10:10:10 volumiopi5 sudo[1895]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Feb 18 10:10:10 volumiopi5 sudo[1895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 18 10:10:10 volumiopi5 sudo[1895]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 sudo[1883]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 sudo[1898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Feb 18 10:10:10 volumiopi5 sudo[1898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:10 volumiopi5 sudo[1898]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 sudo[1892]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumiobt[1900]: INFO [BTSTART] Powering on Bluetooth if needed... Feb 18 10:10:10 volumiopi5 systemd[1]: mpd.service: Deactivated successfully. Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Shairport-Sync Started Feb 18 10:10:10 volumiopi5 systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 18 10:10:10 volumiopi5 systemd[1]: mpd.service: Consumed 1.448s CPU time. Feb 18 10:10:10 volumiopi5 systemd[1]: mpd.socket: Deactivated successfully. Feb 18 10:10:10 volumiopi5 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 18 10:10:10 volumiopi5 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 18 10:10:10 volumiopi5 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioStop Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreStateMachine::stop Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 10:10:10 volumiopi5 systemd[1]: Starting mpd.service - Music Player Daemon... Feb 18 10:10:10 volumiopi5 volumio[1208]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Path / reserved for Adv Monitor app :1.22 Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Adv Monitor app :1.22 disconnected from D-Bus Feb 18 10:10:10 volumiopi5 volumiobt[1918]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [176B blob data] Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [157B blob data] Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [157B blob data] Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [157B blob data] Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [113B blob data] Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [bluetoothctl]> discoverable on Feb 18 10:10:10 volumiopi5 volumiobt[1919]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [bluetoothctl]> pairable on Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Path / reserved for Adv Monitor app :1.25 Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Adv Monitor app :1.25 disconnected from D-Bus Feb 18 10:10:10 volumiopi5 volumiobt[1919]: [bluetoothctl]> Feb 18 10:10:10 volumiopi5 volumiobt[1921]: INFO [BTSTART] Registering Bluetooth agent... Feb 18 10:10:10 volumiopi5 sudo[1904]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 18 10:10:10 volumiopi5 sudo[1904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 18 10:10:10 volumiopi5 sudo[1904]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:10 volumiopi5 volumiobt[1922]: [NEW] Media /org/bluez/hci0 Feb 18 10:10:10 volumiopi5 volumiobt[1922]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 18 10:10:10 volumiopi5 volumiobt[1922]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 18 10:10:10 volumiopi5 volumiobt[1922]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Path / reserved for Adv Monitor app :1.26 Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Adv Monitor app :1.26 disconnected from D-Bus Feb 18 10:10:10 volumiopi5 volumiobt[1925]: No agent is registered Feb 18 10:10:10 volumiopi5 volumiobt[1925]: [NEW] Media /org/bluez/hci0 Feb 18 10:10:10 volumiopi5 volumiobt[1925]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Feb 18 10:10:10 volumiopi5 volumiobt[1925]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Feb 18 10:10:10 volumiopi5 volumiobt[1925]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Path / reserved for Adv Monitor app :1.27 Feb 18 10:10:10 volumiopi5 bluetoothd[902]: Adv Monitor app :1.27 disconnected from D-Bus Feb 18 10:10:10 volumiopi5 volumiobt[1926]: INFO [BTSTART] Agent registered successfully. Feb 18 10:10:10 volumiopi5 volumiobt[1927]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Updating MyVolumio device info Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:10 volumiopi5 volumio[1208]: info: Successfully Updated MyVolumio device Feb 18 10:10:11 volumiopi5 volumio[1208]: info: Access Token successfully retrieved Feb 18 10:10:11 volumiopi5 volumio[1208]: info: Successfully Updated MyVolumio device Feb 18 10:10:11 volumiopi5 volumiobt[1928]: INFO:a2dp-agent:Connecting to system bus. Feb 18 10:10:11 volumiopi5 volumiobt[1928]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Feb 18 10:10:11 volumiopi5 volumiobt[1928]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Feb 18 10:10:11 volumiopi5 volumiobt[1928]: INFO:a2dp-agent:Registering agent. Feb 18 10:10:11 volumiopi5 volumiobt[1928]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Feb 18 10:10:11 volumiopi5 volumiobt[1928]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Feb 18 10:10:11 volumiopi5 volumio[1208]: info: Successfully retrieved User Session From TIDAL Feb 18 10:10:11 volumiopi5 volumio[1208]: info: Successfully retrieved User Subscription From TIDAL Feb 18 10:10:11 volumiopi5 volumio[1208]: info: Adding TIDAL to Browse Sources Feb 18 10:10:11 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 10:10:11 volumiopi5 volumio[1208]: info: [1771405811258] CoreMusicLibrary::Adding element TIDAL Feb 18 10:10:11 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 10:10:11 volumiopi5 volumio[1208]: Cannot find translation for source TIDAL Feb 18 10:10:11 volumiopi5 volumio[1208]: info: Adding TIDAL REST API Endpoints Feb 18 10:10:11 volumiopi5 mpd[1924]: 2026-02-18T10:10:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 10:10:11 volumiopi5 systemd[1]: Started mpd.service - Music Player Daemon. Feb 18 10:10:11 volumiopi5 sudo[1864]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:11 volumiopi5 volumio[1208]: error: updateQueue error: null Feb 18 10:10:13 volumiopi5 volumio[1208]: info: TidalConnect service stoped! Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 18 10:10:13 volumiopi5 sudo[1954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 18 10:10:13 volumiopi5 sudo[1954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:13 volumiopi5 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 18 10:10:13 volumiopi5 sudo[1954]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Executing endpoint tc_getconfig Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 18 10:10:13 volumiopi5 vtcs[1957]: STARTING TidalConnect services, version: 1.6.1 Feb 18 10:10:13 volumiopi5 vtcs[1957]: STARTED TidalConnect services. Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Executing endpoint tc_connect Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Connecting to TidalConnect Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::servicePushState Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreStateMachine::pushState Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioPushState Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CorePlayQueue::getTrack 0 Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::servicePushState Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreStateMachine::pushState Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioPushState Feb 18 10:10:13 volumiopi5 volumio[1208]: info: CorePlayQueue::getTrack 0 Feb 18 10:10:13 volumiopi5 volumio[1208]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 18 10:10:14 volumiopi5 sudo[1979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 18 10:10:14 volumiopi5 sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:14 volumiopi5 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. Feb 18 10:10:14 volumiopi5 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. Feb 18 10:10:14 volumiopi5 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 18 10:10:14 volumiopi5 sudo[1979]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:14 volumiopi5 volumio[1208]: info: Remote SSH Started Feb 18 10:10:14 volumiopi5 autossh[1982]: port set to 0, monitoring disabled Feb 18 10:10:14 volumiopi5 autossh[1982]: starting ssh (count 1) Feb 18 10:10:14 volumiopi5 autossh[1982]: ssh child pid is 1985 Feb 18 10:10:14 volumiopi5 volumio[1208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 18 10:10:14 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:14 volumiopi5 volumio[1208]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.eIwi9RGZMWhyufwfhaZY4leWTUH2.9d3af8b839f0b205dea3c050608afe19.state.status' Feb 18 10:10:14 volumiopi5 volumiossh-tunnel[1985]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 18 10:10:16 volumiopi5 systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 18 10:10:16 volumiopi5 volumio[1208]: info: TidalConnect service started! Feb 18 10:10:16 volumiopi5 volumio[1208]: [Metrics] CommandRouter: 24s 368.13ms Feb 18 10:10:16 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumiosetStartupVolume Feb 18 10:10:16 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:16 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:16 volumiopi5 volumio[1208]: info: CoreCommandRouter::Close All Modals sent Feb 18 10:10:16 volumiopi5 volumio[1208]: info: CoreCommandRouter::Close All Modals sent Feb 18 10:10:17 volumiopi5 nfsidmap[1988]: nss_getpwnam: name 'root@nfs-liefooghe' does not map into domain 'localdomain' Feb 18 10:10:17 volumiopi5 nfsidmap[1989]: nss_name_to_gid: name 'root@nfs-liefooghe' does not map into domain 'localdomain' Feb 18 10:10:17 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 18 10:10:17 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 18 10:10:17 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 18 10:10:17 volumiopi5 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 18 10:10:18 volumiopi5 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Feb 18 10:10:18 volumiopi5 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 10:10:18 volumiopi5 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 18 10:10:18 volumiopi5 sudo[1775]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:18 volumiopi5 volumio[1208]: info: Upmpdcli Daemon Started Feb 18 10:10:22 volumiopi5 nfsidmap[2014]: nss_getpwnam: name 'admin@nfs-liefooghe' does not map into domain 'localdomain' Feb 18 10:10:22 volumiopi5 nfsidmap[2015]: nss_name_to_gid: name 'users@nfs-liefooghe' does not map into domain 'localdomain' Feb 18 10:10:22 volumiopi5 sudo[1711]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:22 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 10:10:22 volumiopi5 volumio[1208]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 18 10:10:22 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:23 volumiopi5 volumio[1208]: info: BOOT COMPLETED Feb 18 10:10:24 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 10:10:24 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 10:10:24 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 10:10:24 volumiopi5 volumio-remote-updater[913]: Test mode disabled Feb 18 10:10:24 volumiopi5 volumio-remote-updater[913]: Alpha mode disabled Feb 18 10:10:24 volumiopi5 volumio-remote-updater[913]: Alpha legacy test mode disabled Feb 18 10:10:24 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 18 10:10:24 volumiopi5 volumio[1208]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Feb 18 10:10:24 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Feb 18 10:10:25 volumiopi5 sudo[2042]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 10:10:25 volumiopi5 sudo[2042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:25 volumiopi5 sudo[2042]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:25 volumiopi5 sudo[2044]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 10:10:25 volumiopi5 sudo[2044]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:25 volumiopi5 sudo[2044]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:25 volumiopi5 volumio[1208]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.97 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 18 10:10:25 volumiopi5 sudo[2050]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 10:10:25 volumiopi5 sudo[2050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:25 volumiopi5 sudo[2050]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:25 volumiopi5 sudo[2052]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 10:10:25 volumiopi5 sudo[2052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 18 10:10:25 volumiopi5 sudo[2052]: pam_unix(sudo:session): session closed for user root Feb 18 10:10:25 volumiopi5 volumio[1208]: verbose: New Socket.io Connection to 192.168.1.149 from 192.168.1.97 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:147.0) Gecko/20100101 Firefox/147.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetVisibleSources Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 18 10:10:25 volumiopi5 volumio[1208]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 18 10:10:25 volumiopi5 volumio[1208]: info: Received Get System Info Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 10:10:25 volumiopi5 volumio[1208]: info: Discovery: Getting this device information Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:25 volumiopi5 volumio[1208]: info: Listing playlists Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 10:10:25 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 18 10:10:26 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetBrowseSources Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 10:10:27 volumiopi5 volumio[1208]: info: Received Get System Info Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 10:10:27 volumiopi5 volumio[1208]: info: Discovery: Getting this device information Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 10:10:27 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 18 10:10:28 volumiopi5 volumio[1208]: info: Executing endpoint metavolumio Feb 18 10:10:28 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 18 10:10:30 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 10:10:30 volumiopi5 volumio[1208]: info: Received Get System Info Feb 18 10:10:30 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 10:10:30 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 10:10:30 volumiopi5 volumio[1208]: info: Discovery: Getting this device information Feb 18 10:10:30 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:30 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 10:10:35 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 18 10:10:36 volumiopi5 upmpdcli[2069]: writing RSA key Feb 18 10:10:41 volumiopi5 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 18 10:10:41 volumiopi5 systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 18 10:10:41 volumiopi5 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 18 10:10:43 volumiopi5 volumio[1208]: info: Received Get System Version Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 10:10:43 volumiopi5 volumio[1208]: info: Received Get System Info Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 18 10:10:43 volumiopi5 volumio[1208]: info: Discovery: Getting this device information Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetState Feb 18 10:10:43 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 18 10:10:48 volumiopi5 volumio[1208]: info: CoreCommandRouter::volumioGetQueue Feb 18 10:10:48 volumiopi5 volumio[1208]: info: CoreStateMachine::getQueue Feb 18 10:10:48 volumiopi5 volumio[1208]: info: CorePlayQueue::getQueue Feb 18 10:10:52 volumiopi5 volumio[1208]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 18 10:10:52 volumiopi5 volumio[1208]: info: CURURI: artists:// Feb 18 10:10:53 volumiopi5 volumio[1208]: info: Preload queue cleared Feb 18 10:11:05 volumiopi5 volumio[1208]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 10:11:05 volumiopi5 volumio[1208]: TypeError: Cannot read properties of null (reading 'slice') Feb 18 10:11:05 volumiopi5 volumio[1208]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 18 10:11:05 volumiopi5 volumio[1208]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 18 10:11:05 volumiopi5 volumio[1208]: at Object.onceWrapper (node:events:629:26) Feb 18 10:11:05 volumiopi5 volumio[1208]: at ClientRequest.emit (node:events:514:28) Feb 18 10:11:05 volumiopi5 volumio[1208]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Feb 18 10:11:05 volumiopi5 volumio[1208]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Feb 18 10:11:05 volumiopi5 volumio[1208]: at TLSSocket.socketOnData (node:_http_client:535:22) Feb 18 10:11:05 volumiopi5 volumio[1208]: at TLSSocket.emit (node:events:514:28) Feb 18 10:11:05 volumiopi5 volumio[1208]: at addChunk (node:internal/streams/readable:343:12) Feb 18 10:11:05 volumiopi5 volumio[1208]: at readableAddChunk (node:internal/streams/readable:316:9) Feb 18 10:11:05 volumiopi5 volumio[1208]: at Readable.push (node:internal/streams/readable:253:10) Feb 18 10:11:05 volumiopi5 volumio[1208]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Feb 18 10:11:05 volumiopi5 volumio[1208]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 10:11:05 volumiopi5 sudo[2206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-18 10:10' Feb 18 10:11:05 volumiopi5 sudo[2206]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"