May 31 09:53:34 volumio ntpd[1185]: CLOCK: time stepped by 13441.424282 May 31 09:53:34 volumio ntpd[1185]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 31 09:53:34 volumio volumio[1361]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 31 09:53:34 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 31 09:53:34 volumio volumio[1361]: info: Streaming services startup May 31 09:53:34 volumio volumio[1361]: info: Starting Streaming Daemon May 31 09:53:34 volumio sudo[2156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 09:53:34 volumio sudo[2156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:34 volumio volumio[1361]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 31 09:53:34 volumio volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:34 volumio volumio[1361]: info: CorePlayQueue::getTrack 0 May 31 09:53:34 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 31 09:53:34 volumio volumio[1361]: info: CoreCommandRouter::volumioGetQueue May 31 09:53:34 volumio volumio[1361]: info: CoreStateMachine::getQueue May 31 09:53:34 volumio volumio[1361]: info: CorePlayQueue::getQueue May 31 09:53:34 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 31 09:53:34 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel May 31 09:53:34 volumio volumio[1361]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 31 09:53:34 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 31 09:53:34 volumio volumio[1361]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs May 31 09:53:34 volumio volumio[1361]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 31 09:53:34 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 31 09:53:34 volumio sudo[2156]: pam_unix(sudo:session): session closed for user root May 31 09:53:34 volumio volumio[1361]: error: Cannot start Volumio Streaming Daemon May 31 09:53:34 volumio volumio[1361]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 09:53:34 volumio volumio[1361]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 09:53:34 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 31 09:53:34 volumio volumio[1361]: info: Discovery: A device disappeared from network May 31 09:53:34 volumio volumio[1361]: info: Discovery: Device volumio disappeared from network May 31 09:53:34 volumio volumio[1361]: info: Discovery: A device disappeared from network May 31 09:53:34 volumio volumio[1361]: info: Discovery: A device disappeared from network May 31 09:53:36 volumio dhcpcd[1042]: wlan0: using IPv4LL address 169.254.240.25 May 31 09:53:36 volumio avahi-daemon[968]: Registering new address record for 169.254.240.25 on wlan0.IPv4. May 31 09:53:36 volumio dhcpcd[1042]: wlan0: adding route to 169.254.0.0/16 May 31 09:53:36 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. May 31 09:53:36 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... May 31 09:53:36 volumio systemd[1]: welcome.service: Deactivated successfully. May 31 09:53:36 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 31 09:53:36 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 31 09:53:36 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 31 09:53:36 volumio welcome[2188]: Resolved ip:[3] 192.168.1.92 192.168.211.1 169.254.240.25 May 31 09:53:36 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 31 09:53:36 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. May 31 09:53:36 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart May 31 09:53:36 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart May 31 09:53:36 volumio volumio[1361]: info: Refreshing Cached IP Addresses May 31 09:53:36 volumio sudo[2194]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 31 09:53:36 volumio sudo[2194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:36 volumio sudo[2196]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:53:36 volumio sudo[2196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:36 volumio sudo[2196]: pam_unix(sudo:session): session closed for user root May 31 09:53:36 volumio sudo[2199]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:53:36 volumio sudo[2199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:36 volumio sudo[2194]: pam_unix(sudo:session): session closed for user root May 31 09:53:36 volumio sudo[2199]: pam_unix(sudo:session): session closed for user root May 31 09:53:37 volumio sudo[2209]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:53:37 volumio sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:37 volumio sudo[2209]: pam_unix(sudo:session): session closed for user root May 31 09:53:37 volumio sudo[2211]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:53:37 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:37 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root May 31 09:53:38 volumio volumio[1361]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::volumioGetVisibleSources May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 31 09:53:38 volumio volumio[1361]: info: Received Get System Info May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:53:38 volumio volumio[1361]: info: Discovery: Getting this device information May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:38 volumio volumio[1361]: info: CorePlayQueue::getTrack 0 May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:53:38 volumio volumio-remote-updater[979]: Test mode disabled May 31 09:53:38 volumio volumio-remote-updater[979]: Alpha mode disabled May 31 09:53:38 volumio volumio-remote-updater[979]: Alpha legacy test mode disabled May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:38 volumio volumio[1361]: info: Retrieving Cloud Streaming UI May 31 09:53:38 volumio volumio[1361]: info: Getting Tidal Cloud Configuration May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:38 volumio volumio[1361]: info: Getting Qobuz Cloud Configuration May 31 09:53:38 volumio volumio[1361]: info: Asking plugin for UI Config May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:38 volumio volumio[1361]: info: Getting Spotify Cloud Configuration May 31 09:53:38 volumio volumio[1361]: info: Asking plugin for UI Config May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:38 volumio volumio[1361]: info: Saving Spotify Acccount May 31 09:53:38 volumio volumio[1361]: info: Got Tidal Cloud Configuration May 31 09:53:38 volumio volumio[1361]: info: Got it May 31 09:53:38 volumio volumio[1361]: info: Got it May 31 09:53:38 volumio volumio[1361]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 09:53:38 volumio volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:38 volumio volumio[1361]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') May 31 09:53:38 volumio ntpd[1185]: IO: Listen normally on 6 wlan0 169.254.240.25:123 May 31 09:53:38 volumio ntpd[1185]: IO: new interface(s) found: waking up resolver May 31 09:53:39 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 31 09:53:39 volumio volumio[1361]: info: Loading i18n strings for locale en May 31 09:53:39 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 31 09:53:39 volumio volumio[1361]: info: Setting timezone to Europe/Amsterdam May 31 09:53:39 volumio sudo[2221]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 31 09:53:39 volumio sudo[2221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:39 volumio sudo[2221]: pam_unix(sudo:session): session closed for user root May 31 09:53:39 volumio sudo[2225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 31 09:53:39 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:39 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root May 31 09:53:39 volumio sudo[2229]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 31 09:53:39 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:39 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root May 31 09:53:39 volumio sudo[2233]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 31 09:53:39 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:39 volumio systemd-timedated[1187]: Changed time zone to 'Europe/Amsterdam' (CEST). May 31 09:53:39 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root May 31 09:53:39 volumio volumio[1361]: Updating browse sources language May 31 09:53:39 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:53:39 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:53:39 volumio volumio[1361]: info: Fetching Streaming Services browse cache May 31 09:53:40 volumio volumio[1361]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 09:53:40 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 09:53:40 volumio volumio[1361]: info: Discovery: adding 8a3b6753-aa82-40ed-b999-046a18cc0690 May 31 09:53:40 volumio volumio[1361]: info: Discovery: Found device Volumio May 31 09:53:40 volumio volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:40 volumio volumio[1361]: info: CorePlayQueue::getTrack 0 May 31 09:53:40 volumio volumio[1361]: info: Discovery: this is already registered, 8a3b6753-aa82-40ed-b999-046a18cc0690 May 31 09:53:40 volumio volumio[1361]: info: Discovery: Found device Volumio May 31 09:53:40 volumio volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:40 volumio volumio[1361]: info: CorePlayQueue::getTrack 0 May 31 09:53:41 volumio volumio[1361]: info: Discovery: this is already registered, 8a3b6753-aa82-40ed-b999-046a18cc0690 May 31 09:53:41 volumio volumio[1361]: info: Discovery: Found device Volumio May 31 09:53:41 volumio volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:41 volumio volumio[1361]: info: CorePlayQueue::getTrack 0 May 31 09:53:41 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 31 09:53:41 volumio volumio[1361]: info: Loading i18n strings for locale en May 31 09:53:41 volumio volumio[1361]: Updating browse sources language May 31 09:53:41 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:53:41 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 31 09:53:41 volumio volumio[1361]: info: Setting timezone to Europe/Amsterdam May 31 09:53:41 volumio sudo[2251]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 31 09:53:41 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:41 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root May 31 09:53:41 volumio sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 31 09:53:41 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:41 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root May 31 09:53:41 volumio sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 31 09:53:41 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:41 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root May 31 09:53:41 volumio sudo[2265]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 31 09:53:41 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:41 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root May 31 09:53:41 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 31 09:53:41 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 31 09:53:41 volumio sudo[2268]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 09:53:41 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:41 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 31 09:53:41 volumio volumio[1361]: info: Fetching Streaming Services browse cache May 31 09:53:43 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 31 09:53:45 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:53:45 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root May 31 09:53:45 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 31 09:53:45 volumio volumio[1361]: info: MyVolumio login type: Token May 31 09:53:45 volumio volumio[1361]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 09:53:46 volumio volumio[1361]: info: MyVolumio token set successfully May 31 09:53:46 volumio volumio[1361]: info: MYVOLUMIO: Adding device May 31 09:53:46 volumio volumio[1361]: info: MYVOLUMIO: Evaluating Server May 31 09:53:46 volumio volumio[1361]: info: MyVolumio Plan changed: premium May 31 09:53:46 volumio volumio[1361]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 31 09:53:46 volumio volumio[1361]: info: Removing browser output: myVolumio user plan is not superstar May 31 09:53:46 volumio volumio[1361]: info: Removing audio output: May 31 09:53:46 volumio volumio[1361]: info: MYVOLUMIO: Adding device May 31 09:53:46 volumio volumio[1361]: info: MYVOLUMIO: Evaluating Server May 31 09:53:46 volumio volumio[1361]: info: Remote config written successfully May 31 09:53:46 volumio volumio[1361]: info: Starting Tunnel 1 May 31 09:53:46 volumio volumio[1361]: info: Starting Tunnel Connection Checker May 31 09:53:46 volumio volumio[1361]: info: Completed starting MyVolumio Plugin May 31 09:53:46 volumio volumio[1361]: info: MYVolumio Device enabled May 31 09:53:46 volumio volumio[1361]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 31 09:53:46 volumio volumio[1361]: info: MyVolumio status changed May 31 09:53:46 volumio volumio[1361]: info: Streaming services startup May 31 09:53:46 volumio volumio[1361]: info: Starting Streaming Daemon May 31 09:53:46 volumio volumio[1361]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' May 31 09:53:46 volumio sudo[2306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 09:53:46 volumio sudo[2306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:46 volumio sudo[2306]: pam_unix(sudo:session): session closed for user root May 31 09:53:46 volumio volumio[1361]: error: Cannot start Volumio Streaming Daemon May 31 09:53:46 volumio volumio[1361]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 09:53:46 volumio volumio[1361]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 09:53:46 volumio sudo[2309]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:53:46 volumio sudo[2309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:46 volumio sudo[2311]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:53:46 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:46 volumio sudo[2309]: pam_unix(sudo:session): session closed for user root May 31 09:53:46 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root May 31 09:53:46 volumio sudo[2315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 09:53:46 volumio sudo[2315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:46 volumio sudo[2315]: pam_unix(sudo:session): session closed for user root May 31 09:53:46 volumio volumio[1361]: info: Upmpdcli Daemon Started May 31 09:53:46 volumio volumio[1361]: info: Setting Geolocation for MyVolumio to eu2 May 31 09:53:46 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:46 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:46 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:47 volumio volumio[1361]: info: Successfully Added MyVolumio device May 31 09:53:47 volumio volumio[1361]: info: Updating MyVolumio device info May 31 09:53:47 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:47 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:47 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:47 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:53:47 volumio volumio[1361]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! May 31 09:53:47 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 31 09:53:48 volumio sudo[2319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 31 09:53:48 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: info: MRS: MultiRoom plugin initialized May 31 09:53:48 volumio volumio[1361]: info: MRS: STOPPING SNAPCLIENT May 31 09:53:48 volumio volumio[1361]: info: MRS: Snap server stop May 31 09:53:48 volumio volumio[1361]: info: MRS: STOPPING volumioStreaming May 31 09:53:48 volumio sudo[2336]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 31 09:53:48 volumio sudo[2336]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 31 09:53:48 volumio sudo[2338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 31 09:53:48 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 31 09:53:48 volumio sudo[2343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2343]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 31 09:53:48 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 31 09:53:48 volumio sudo[2338]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 31 09:53:48 volumio sudo[2336]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio sudo[2341]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 31 09:53:48 volumio volumio[1361]: info: Preparing to generate the ALSA configuration file May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 31 09:53:48 volumio volumio[1361]: info: Setting Geolocation for MyVolumio to us3 May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: Successfully Updated MyVolumio device May 31 09:53:48 volumio volumio[1361]: info: MRS: Removed streaming files May 31 09:53:48 volumio volumio[1361]: info: MRS: volumioStreaming STOPPED May 31 09:53:48 volumio volumio[1361]: info: MRS: SNAPSERVER STOPPED May 31 09:53:48 volumio volumio[1361]: info: MRS: SNAPCLIENT STOPPED May 31 09:53:48 volumio volumio[1361]: info: Asound.conf file unchanged, so no further update is needed May 31 09:53:48 volumio volumio[1361]: info: Output device has changed, restarting MPD May 31 09:53:48 volumio volumio[1361]: info: Output device has changed, restarting Shairport Sync May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 09:53:48 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 09:53:48 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:53:48 volumio volumio[1361]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: [FUNC] onStart May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service May 31 09:53:48 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 31 09:53:48 volumio volumio[1361]: info: MRS: Pushing multiroomSync output for this device May 31 09:53:48 volumio volumio[1361]: info: MRS: Pushing multiroomSync output May 31 09:53:48 volumio volumio[1361]: info: Adding audio output: May 31 09:53:48 volumio volumio[1361]: info: Adding audio output: May 31 09:53:48 volumio volumio[1361]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 31 09:53:48 volumio volumio[1361]: info: Adding METAVOLUMIO REST API Endpoints May 31 09:53:48 volumio volumio[1361]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 31 09:53:48 volumio volumio[1361]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 31 09:53:48 volumio volumio[1361]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 31 09:53:48 volumio volumio[1361]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 31 09:53:48 volumio volumio[1361]: info: Preparing CD Folders May 31 09:53:48 volumio volumio[1361]: info: Adding CD REST API Endpoints May 31 09:53:48 volumio volumio[1361]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 31 09:53:48 volumio volumio[1361]: info: Starting UDEV Watcher for CD May 31 09:53:48 volumio volumio[1361]: info: Detecting CD presence with UDEV May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 31 09:53:48 volumio bluetoothd[970]: Path / reserved for Adv Monitor app :1.30 May 31 09:53:48 volumio bluetoothd[970]: Adv Monitor app :1.30 disconnected from D-Bus May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 31 09:53:48 volumio volumio[1361]: info: Adding inputs REST Endpoints May 31 09:53:48 volumio volumio[1361]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 31 09:53:48 volumio volumio[1361]: info: Scanning Audio Inputs May 31 09:53:48 volumio volumio[1361]: info: Checking against Known Cards name May 31 09:53:48 volumio volumio[1361]: info: Adding Server instance for streaming May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 31 09:53:48 volumio volumio[1361]: error: Hi Res Audio Failed Login: Missing Login Data May 31 09:53:48 volumio volumio[1361]: info: Adding HIGHRESAUDIO REST API Endpoints May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 31 09:53:48 volumio volumio[1361]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 31 09:53:48 volumio volumio[1361]: info: Stopping AccessToken refresher cron May 31 09:53:48 volumio sudo[2368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 09:53:48 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio volumio[1361]: info: AccessToken refresher cron started May 31 09:53:48 volumio volumio[1361]: info: Stopping AccessToken refresher cron for QOBUZ May 31 09:53:48 volumio volumio[1361]: info: AccessToken refresher cron started for QOBUZ May 31 09:53:48 volumio volumio[1361]: info: Adding TIDAL REST API Endpoints May 31 09:53:48 volumio volumio[1361]: info: Adding QOBUZ REST API Endpoints May 31 09:53:48 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on May 31 09:53:48 volumio volumio[1361]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 09:53:48 volumio volumio[1361]: info: MPD Permissions set May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio sudo[2372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 31 09:53:48 volumio sudo[2372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: Starting Shairport Sync May 31 09:53:48 volumio systemd[1]: mpd.service: Deactivated successfully. May 31 09:53:48 volumio volumio[1361]: info: Preparing to generate the ALSA configuration file May 31 09:53:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 09:53:48 volumio systemd[1]: mpd.service: Consumed 1.258s CPU time. May 31 09:53:48 volumio systemd[1]: mpd.socket: Deactivated successfully. May 31 09:53:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 09:53:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 09:53:48 volumio volumio[1361]: info: Asound.conf file unchanged, so no further update is needed May 31 09:53:48 volumio volumio[1361]: info: Output device has changed, restarting MPD May 31 09:53:48 volumio sudo[2381]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:53:48 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio volumio[1361]: info: Output device has changed, restarting Shairport Sync May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 09:53:48 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2387]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 09:53:48 volumio sudo[2387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2384]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 09:53:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 31 09:53:48 volumio volumio[1361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:53:48 volumio volumio[1361]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: Preparing to generate the ALSA configuration file May 31 09:53:48 volumio volumio[1361]: info: MPD Permissions set May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: Asound.conf file unchanged, so no further update is needed May 31 09:53:48 volumio volumio[1361]: info: Output device has changed, restarting MPD May 31 09:53:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 09:53:48 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. May 31 09:53:48 volumio sudo[2372]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 09:53:48 volumio volumio[1361]: info: Output device has changed, restarting Shairport Sync May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:48 volumio systemd[1]: shairport-sync.service: Consumed 1.449s CPU time. May 31 09:53:48 volumio systemd[1]: mpd.service: Deactivated successfully. May 31 09:53:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 09:53:48 volumio volumiobt[2408]: INFO [BTSTART] Ensuring Bluetooth directory exists... May 31 09:53:48 volumio systemd[1]: mpd.socket: Deactivated successfully. May 31 09:53:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 09:53:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 09:53:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 09:53:48 volumio sudo[2410]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth May 31 09:53:48 volumio sudo[2406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 09:53:48 volumio sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 09:53:48 volumio sudo[2406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 31 09:53:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:48 volumio sudo[2410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2381]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:53:48 volumio volumio[1361]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio sudo[2410]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumio[1361]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 09:53:48 volumio systemd[1]: mpd.service: Deactivated successfully. May 31 09:53:48 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 09:53:48 volumio sudo[2420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth May 31 09:53:48 volumio systemd[1]: mpd.socket: Deactivated successfully. May 31 09:53:48 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 09:53:48 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 09:53:48 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumiobt[2436]: INFO [BTSTART] Powering on Bluetooth if needed... May 31 09:53:48 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 09:53:48 volumio systemd[1]: Starting mpd.service - Music Player Daemon... May 31 09:53:48 volumio volumio[1361]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') May 31 09:53:48 volumio volumio[1361]: info: MPD Permissions set May 31 09:53:48 volumio volumio[1361]: info: Shairport-Sync Started May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::volumioStop May 31 09:53:48 volumio volumio[1361]: info: CoreStateMachine::stop May 31 09:53:48 volumio volumio[1361]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 09:53:48 volumio volumio[1361]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart May 31 09:53:48 volumio volumio[1361]: info: CoreStateMachine::setConsumeUpdateService undefined May 31 09:53:48 volumio volumio[1361]: info: Starting Shairport Sync May 31 09:53:48 volumio bluetoothd[970]: Adv Monitor app :1.33 disconnected from D-Bus May 31 09:53:48 volumio volumiobt[2444]: INFO [BTSTART] Making Bluetooth discoverable and pairable... May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio sudo[2443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:53:48 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio volumiobt[2445]: [75B blob data] May 31 09:53:48 volumio volumiobt[2445]: [bluetooth]# pairable on May 31 09:53:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 09:53:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 09:53:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:48 volumio bluetoothd[970]: Adv Monitor app :1.34 disconnected from D-Bus May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:48 volumio volumiobt[2445]: [bluetooth]# May 31 09:53:48 volumio volumiobt[2453]: INFO [BTSTART] Registering Bluetooth agent... May 31 09:53:48 volumio volumio[1361]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 31 09:53:48 volumio volumio[1361]: info: Starting Shairport Sync May 31 09:53:48 volumio sudo[2440]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 09:53:48 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 09:53:48 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:48 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:53:48 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:48 volumio bluetoothd[970]: Adv Monitor app :1.35 disconnected from D-Bus May 31 09:53:48 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 09:53:48 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 09:53:48 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:48 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:48 volumio volumio[1361]: info: Shairport-Sync Started May 31 09:53:48 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root May 31 09:53:48 volumio volumiobt[2460]: No agent is registered May 31 09:53:48 volumio bluetoothd[970]: Adv Monitor app :1.36 disconnected from D-Bus May 31 09:53:48 volumio volumiobt[2464]: INFO [BTSTART] Agent registered successfully. May 31 09:53:48 volumio volumiobt[2465]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... May 31 09:53:48 volumio volumio[1361]: info: Shairport-Sync Started May 31 09:53:48 volumio volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:49 volumio volumio[1361]: info: Successfully Added MyVolumio device May 31 09:53:49 volumio volumiobt[2466]: INFO:a2dp-agent:Connecting to system bus. May 31 09:53:49 volumio volumiobt[2466]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 May 31 09:53:49 volumio volumiobt[2466]: INFO:a2dp-agent:Enabling infinite discovery on adapter. May 31 09:53:49 volumio volumiobt[2466]: INFO:a2dp-agent:Registering agent. May 31 09:53:49 volumio volumiobt[2466]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... May 31 09:53:49 volumio volumiobt[2466]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. May 31 09:53:49 volumio mpd[2458]: 2025-05-31T09:53:49 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 09:53:49 volumio systemd[1]: Started mpd.service - Music Player Daemon. May 31 09:53:49 volumio sudo[2406]: pam_unix(sudo:session): session closed for user root May 31 09:53:49 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root May 31 09:53:49 volumio sudo[2387]: pam_unix(sudo:session): session closed for user root May 31 09:53:49 volumio volumio[1361]: error: MPD error: The expression evaluated to a falsy value: May 31 09:53:49 volumio volumio[1361]: assert.ok(self.idling) May 31 09:53:49 volumio volumio[1361]: error: The expression evaluated to a falsy value: May 31 09:53:49 volumio volumio[1361]: assert.ok(self.idling) May 31 09:53:49 volumio volumio[1361]: error: MPD error: The expression evaluated to a falsy value: May 31 09:53:49 volumio volumio[1361]: assert.ok(self.idling) May 31 09:53:49 volumio volumio[1361]: error: The expression evaluated to a falsy value: May 31 09:53:49 volumio volumio[1361]: assert.ok(self.idling) May 31 09:53:49 volumio volumio[1361]: error: updateQueue error: null May 31 09:53:49 volumio volumio[1361]: info: Updating MyVolumio device info May 31 09:53:49 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:49 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:49 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:50 volumio volumio[1361]: info: Successfully Updated MyVolumio device May 31 09:53:51 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:53:51 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:51 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 09:53:51 volumio sudo[2506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 31 09:53:51 volumio sudo[2506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:51 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 31 09:53:51 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 31 09:53:51 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. May 31 09:53:51 volumio sudo[2506]: pam_unix(sudo:session): session closed for user root May 31 09:53:51 volumio volumio[1361]: info: Remote SSH Started May 31 09:53:51 volumio autossh[2509]: port set to 0, monitoring disabled May 31 09:53:51 volumio autossh[2509]: starting ssh (count 1) May 31 09:53:51 volumio autossh[2509]: ssh child pid is 2512 May 31 09:53:51 volumio volumio[1361]: info: TidalConnect service stoped! May 31 09:53:51 volumio volumio[1361]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 09:53:51 volumio volumio[1361]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 09:53:51 volumio sudo[2522]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 09:53:51 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:51 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 09:53:51 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root May 31 09:53:51 volumio volumio-remote-updater[979]: Test mode disabled May 31 09:53:51 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 09:53:51 volumio volumio-remote-updater[979]: Alpha mode disabled May 31 09:53:51 volumio volumio-remote-updater[979]: Alpha legacy test mode disabled May 31 09:53:51 volumio vtcs[2525]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 31 09:53:51 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 31 09:53:51 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:51 volumio volumio[1361]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 09:53:51 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 09:53:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 31 09:53:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio vtcs[2529]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 31 09:53:52 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 31 09:53:52 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:52 volumio volumiossh-tunnel[2512]: Warning: Permanently added '[us3.myvolumio.org]:2222' (RSA) to the list of known hosts. May 31 09:53:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 31 09:53:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio vtcs[2531]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 31 09:53:52 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 31 09:53:52 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. May 31 09:53:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio vtcs[2533]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 31 09:53:52 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 31 09:53:52 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:52 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. May 31 09:53:52 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. May 31 09:53:52 volumio vtcs[2535]: /usr/bin/vtcs: error while loading shared libraries: libssl.so.1.1: cannot open shared object file: No such file or directory May 31 09:53:52 volumio systemd[1]: vtcs.service: Main process exited, code=exited, status=127/n/a May 31 09:53:52 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:53 volumio systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. May 31 09:53:53 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. May 31 09:53:53 volumio systemd[1]: vtcs.service: Start request repeated too quickly. May 31 09:53:53 volumio systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:53 volumio systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. May 31 09:53:53 volumio volumio[1361]: info: MRS: Getting audio outputs on start May 31 09:53:53 volumio volumio[1361]: info: MRS: Requesting all other devices output May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::volumioGetBrowseSources May 31 09:53:53 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 31 09:53:54 volumio volumio[1361]: info: System name has changed, restarting Shairport Sync May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:54 volumio volumio[1361]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 09:53:54 volumio volumio[1361]: info: Updating MyVolumio device info May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:54 volumio volumio[1361]: error: Error starting TidalConnect Cannot read properties of undefined (reading 'sendRevoke') {"stack":"TypeError: Cannot read properties of undefined (reading 'sendRevoke')\n at ControllerTidalconnect.stopTidalConnect (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8524)\n at ControllerTidalconnect.restartService (/myvolumio/plugins/music_service/tidalconnect/tidalconnect_real:1:8816)\n at /volumio/node_modules/v-conf/index.js:166:13\n at /volumio/node_modules/multimap/index.js:140:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:139:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:163:20)\n at ControllerSystem.saveGeneralSettings (/volumio/app/plugins/system_controller/system/index.js:443:35)\n at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32)\n at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1704:39)\n at Socket.emit (node:events:514:28)\n at /volumio/node_modules/socket.io/lib/socket.js:503:12\n at process.processTicksAndRejections (node:internal/process/task_queues:77:11)"} May 31 09:53:54 volumio volumio[1361]: info: Discovery: Restarting Advertising due to device name change May 31 09:53:54 volumio sudo[2541]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli May 31 09:53:54 volumio sudo[2541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 31 09:53:54 volumio sudo[2541]: pam_unix(sudo:session): session closed for user root May 31 09:53:54 volumio sudo[2543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 09:53:54 volumio sudo[2543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:54 volumio systemd[1]: upmpdcli.service: Deactivated successfully. May 31 09:53:54 volumio systemd[1]: upmpdcli.service: Consumed 12.472s CPU time. May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:54 volumio volumio[1361]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 09:53:54 volumio volumio[1361]: info: Starting Shairport Sync May 31 09:53:54 volumio sudo[2543]: pam_unix(sudo:session): session closed for user root May 31 09:53:54 volumio sudo[2550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts May 31 09:53:54 volumio sudo[2550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:54 volumio sudo[2550]: pam_unix(sudo:session): session closed for user root May 31 09:53:54 volumio sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 09:53:54 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:54 volumio volumio[1361]: info: Permissions for /etc/hosts set May 31 09:53:54 volumio volumio[1361]: info: Hostname now is rp5-ws800 May 31 09:53:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 09:53:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. May 31 09:53:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:54 volumio systemd[1]: shairport-sync.service: Consumed 1.521s CPU time. May 31 09:53:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 09:53:54 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root May 31 09:53:54 volumio volumio[1361]: info: Shairport-Sync Started May 31 09:53:54 volumio sudo[2557]: volumio : unable to resolve host volumio: System error May 31 09:53:54 volumio sudo[2557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname rp5-ws800 May 31 09:53:54 volumio sudo[2557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:54 rp5-ws800 sudo[2557]: pam_unix(sudo:session): session closed for user root May 31 09:53:54 rp5-ws800 volumio[1361]: info: New hostname set May 31 09:53:54 rp5-ws800 sudo[2559]: volumio : unable to resolve host volumio: System error May 31 09:53:54 rp5-ws800 sudo[2559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ May 31 09:53:54 rp5-ws800 sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:54 rp5-ws800 sudo[2559]: pam_unix(sudo:session): session closed for user root May 31 09:53:54 rp5-ws800 volumio[1361]: info: Permissions for /etc/avahi/services/volumio.service May 31 09:53:54 rp5-ws800 avahi-daemon[968]: Files changed, reloading. May 31 09:53:54 rp5-ws800 avahi-daemon[968]: Loading service file /services/volumio.service. May 31 09:53:54 rp5-ws800 volumio[1361]: info: Avahi name changed to rp5-ws800 May 31 09:53:54 rp5-ws800 volumio[1361]: info: Successfully Updated MyVolumio device May 31 09:53:54 rp5-ws800 volumio[1361]: info: TidalConnect service started! May 31 09:53:54 rp5-ws800 volumio[1361]: [Metrics] CommandRouter: 35s 693.63ms May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::volumiosetStartupVolume May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::Close All Modals sent May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::Close All Modals sent May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Retrieving Cloud Streaming UI May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Retrieving Cloud Streaming UI May 31 09:53:54 rp5-ws800 volumio[1361]: info: Getting Tidal Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Getting Qobuz Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: Asking plugin for UI Config May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Getting Spotify Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: Asking plugin for UI Config May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Saving Spotify Acccount May 31 09:53:54 rp5-ws800 volumio[1361]: info: Got it May 31 09:53:54 rp5-ws800 volumio[1361]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 09:53:54 rp5-ws800 volumio[1361]: info: Getting Tidal Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Getting Qobuz Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: Asking plugin for UI Config May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Getting Spotify Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: Asking plugin for UI Config May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Saving Spotify Acccount May 31 09:53:54 rp5-ws800 volumio[1361]: info: Got it May 31 09:53:54 rp5-ws800 volumio[1361]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 09:53:54 rp5-ws800 volumio[1361]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 31 09:53:54 rp5-ws800 volumio[1361]: aplay: main:831: audio open error: Unknown error 524 May 31 09:53:54 rp5-ws800 volumio[1361]: info: Got Tidal Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: Got Tidal Cloud Configuration May 31 09:53:54 rp5-ws800 volumio[1361]: info: Got it May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:54 rp5-ws800 volumio[1361]: info: Got it May 31 09:53:54 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:55 rp5-ws800 volumio[1361]: info: Discovery: A device disappeared from network May 31 09:53:55 rp5-ws800 volumio[1361]: info: Discovery: Device volumio disappeared from network May 31 09:53:55 rp5-ws800 volumio[1361]: info: Discovery: A device disappeared from network May 31 09:53:55 rp5-ws800 volumio[1361]: info: Discovery: A device disappeared from network May 31 09:53:55 rp5-ws800 avahi-daemon[968]: Service "rP5-WS800" (/services/volumio.service) successfully established. May 31 09:53:55 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 31 09:53:55 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 09:53:55 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 31 09:53:55 rp5-ws800 volumio[1361]: info: Version has changed, forcing UI Reload May 31 09:53:55 rp5-ws800 volumio[1361]: info: CoreCommandRouter::Reload Ui May 31 09:53:55 rp5-ws800 sudo[2585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 09:53:55 rp5-ws800 sudo[2585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:55 rp5-ws800 sudo[2585]: pam_unix(sudo:session): session closed for user root May 31 09:53:55 rp5-ws800 sudo[2587]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 09:53:55 rp5-ws800 sudo[2587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:55 rp5-ws800 sudo[2587]: pam_unix(sudo:session): session closed for user root May 31 09:53:56 rp5-ws800 volumio[1361]: verbose: New Socket.io Connection to 192.168.1.92 from 192.168.1.106 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::volumioGetVisibleSources May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 31 09:53:56 rp5-ws800 volumio[1361]: info: Received Get System Info May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 09:53:56 rp5-ws800 volumio[1361]: info: Discovery: Getting this device information May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:56 rp5-ws800 volumio[1361]: info: Retrieving Cloud Streaming UI May 31 09:53:56 rp5-ws800 volumio-remote-updater[979]: Test mode disabled May 31 09:53:56 rp5-ws800 volumio-remote-updater[979]: Alpha mode disabled May 31 09:53:56 rp5-ws800 volumio-remote-updater[979]: Alpha legacy test mode disabled May 31 09:53:56 rp5-ws800 volumio[1361]: info: Getting Tidal Cloud Configuration May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:56 rp5-ws800 volumio[1361]: info: Getting Qobuz Cloud Configuration May 31 09:53:56 rp5-ws800 volumio[1361]: info: Asking plugin for UI Config May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:56 rp5-ws800 volumio[1361]: info: Getting Spotify Cloud Configuration May 31 09:53:56 rp5-ws800 volumio[1361]: info: Asking plugin for UI Config May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:56 rp5-ws800 volumio[1361]: info: Saving Spotify Acccount May 31 09:53:56 rp5-ws800 volumio[1361]: info: Got it May 31 09:53:56 rp5-ws800 volumio[1361]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 31 09:53:56 rp5-ws800 volumio[1361]: info: Got Tidal Cloud Configuration May 31 09:53:56 rp5-ws800 volumio[1361]: info: Got it May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 09:53:56 rp5-ws800 volumio[1361]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 09:53:56 rp5-ws800 volumio[1361]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 31 09:53:56 rp5-ws800 volumio[1361]: info: CoreCommandRouter::volumioGetState May 31 09:53:56 rp5-ws800 volumio[1361]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Xcif8gt3U4YVmbitb0UO4zv3Gom2.c23868cc5dab46edad653b2b9b22c496.state.status' May 31 09:53:57 rp5-ws800 volumio[1361]: info: TidalConnect service stoped! May 31 09:53:57 rp5-ws800 volumio[1361]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 09:53:57 rp5-ws800 volumio[1361]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 09:53:57 rp5-ws800 sudo[2604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 09:53:57 rp5-ws800 sudo[2604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:57 rp5-ws800 systemd[1]: vtcs.service: Start request repeated too quickly. May 31 09:53:57 rp5-ws800 systemd[1]: vtcs.service: Failed with result 'exit-code'. May 31 09:53:57 rp5-ws800 systemd[1]: Failed to start vtcs.service - Volumio Tidal Connect Service. May 31 09:53:57 rp5-ws800 sudo[2604]: pam_unix(sudo:session): session closed for user root May 31 09:53:57 rp5-ws800 volumio[1361]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 May 31 09:53:57 rp5-ws800 volumio[1361]: Job for vtcs.service failed because the control process exited with error code. May 31 09:53:57 rp5-ws800 volumio[1361]: See "systemctl status vtcs.service" and "journalctl -xeu vtcs.service" for details. May 31 09:53:57 rp5-ws800 volumio[1361]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service failed because the control process exited with error code.\nSee \"systemctl status vtcs.service\" and \"journalctl -xeu vtcs.service\" for details.\n","stdout":""} May 31 09:53:57 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 31 09:53:57 rp5-ws800 volumio[1361]: info: Loading i18n strings for locale en May 31 09:53:57 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 31 09:53:57 rp5-ws800 volumio[1361]: info: Setting timezone to Europe/Amsterdam May 31 09:53:57 rp5-ws800 sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 31 09:53:57 rp5-ws800 sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:57 rp5-ws800 sudo[2608]: pam_unix(sudo:session): session closed for user root May 31 09:53:57 rp5-ws800 sudo[2612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 31 09:53:57 rp5-ws800 sudo[2612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:57 rp5-ws800 sudo[2612]: pam_unix(sudo:session): session closed for user root May 31 09:53:57 rp5-ws800 sudo[2616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 31 09:53:57 rp5-ws800 sudo[2616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:57 rp5-ws800 sudo[2616]: pam_unix(sudo:session): session closed for user root May 31 09:53:57 rp5-ws800 sudo[2620]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 31 09:53:57 rp5-ws800 sudo[2620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:57 rp5-ws800 sudo[2620]: pam_unix(sudo:session): session closed for user root May 31 09:53:57 rp5-ws800 volumio[1361]: Updating browse sources language May 31 09:53:57 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:53:57 rp5-ws800 volumio[1361]: info: Fetching Streaming Services browse cache May 31 09:53:57 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:53:58 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 31 09:53:58 rp5-ws800 volumio[1361]: info: Loading i18n strings for locale en May 31 09:53:58 rp5-ws800 volumio[1361]: Updating browse sources language May 31 09:53:58 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 09:53:58 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 31 09:53:58 rp5-ws800 volumio[1361]: info: Setting timezone to Europe/Amsterdam May 31 09:53:58 rp5-ws800 sudo[2624]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 31 09:53:58 rp5-ws800 sudo[2624]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:58 rp5-ws800 sudo[2624]: pam_unix(sudo:session): session closed for user root May 31 09:53:58 rp5-ws800 sudo[2628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime May 31 09:53:58 rp5-ws800 sudo[2628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:58 rp5-ws800 sudo[2628]: pam_unix(sudo:session): session closed for user root May 31 09:53:58 rp5-ws800 sudo[2632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 31 09:53:58 rp5-ws800 sudo[2632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:58 rp5-ws800 sudo[2632]: pam_unix(sudo:session): session closed for user root May 31 09:53:58 rp5-ws800 sudo[2636]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam May 31 09:53:58 rp5-ws800 sudo[2636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:58 rp5-ws800 sudo[2636]: pam_unix(sudo:session): session closed for user root May 31 09:53:58 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 31 09:53:58 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 31 09:53:58 rp5-ws800 sudo[2639]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 09:53:58 rp5-ws800 sudo[2639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 09:53:58 rp5-ws800 volumio[1361]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 09:53:59 rp5-ws800 volumio[1361]: info: Discovery: Started advertising with name: rP5-WS800 May 31 09:53:59 rp5-ws800 volumio[1361]: info: Fetching Streaming Services browse cache May 31 09:53:59 rp5-ws800 volumio[1361]: compat.c: read() failed: Resource temporarily unavailable May 31 09:53:59 rp5-ws800 volumio[1361]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:53:59 rp5-ws800 volumio[1361]: Error: dns service error: unknown May 31 09:53:59 rp5-ws800 volumio[1361]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { May 31 09:53:59 rp5-ws800 volumio[1361]: errorCode: -65537 May 31 09:53:59 rp5-ws800 volumio[1361]: } May 31 09:53:59 rp5-ws800 volumio[1361]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 09:54:00 rp5-ws800 sudo[2671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 09:53' May 31 09:54:00 rp5-ws800 sudo[2671]: 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"