-- Logs begin at Thu 2025-05-29 19:54:59 BST, end at Thu 2025-05-29 20:02:51 BST. -- May 29 20:01:22 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:01:22 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 20:01:22 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 20:01:22 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 20:01:22 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs May 29 20:01:22 volumio volumio[1091]: info: Received Get System Version May 29 20:01:22 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:01:28 volumio volumio[1091]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] May 29 20:01:28 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 29 20:01:30 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard May 29 20:01:30 volumio volumio[1091]: info: CoreCommandRouter::Reload Ui May 29 20:01:31 volumio sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:01:31 volumio sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:31 volumio sudo[2117]: pam_unix(sudo:session): session closed for user root May 29 20:01:31 volumio sudo[2119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:01:31 volumio sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:31 volumio sudo[2119]: pam_unix(sudo:session): session closed for user root May 29 20:01:31 volumio volumio[1091]: verbose: New Socket.io Connection to 192.168.1.78 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 29 20:01:31 volumio volumio[1091]: info: Received Get System Info May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:01:31 volumio volumio[1091]: info: Discovery: Getting this device information May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState May 29 20:01:31 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:01:31 volumio volumio-remote-updater[603]: No test mode May 29 20:01:31 volumio volumio-remote-updater[603]: No alpha test mode May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 20:01:31 volumio volumio[1091]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:01:31 volumio volumio[1091]: info: Retrieving Cloud Streaming UI May 29 20:01:31 volumio volumio[1091]: info: Getting Tidal Cloud Configuration May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:01:31 volumio volumio[1091]: info: Getting Qobuz Cloud Configuration May 29 20:01:31 volumio volumio[1091]: info: Asking plugin for UI Config May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:01:31 volumio volumio[1091]: info: Getting Spotify Cloud Configuration May 29 20:01:31 volumio volumio[1091]: info: Asking plugin for UI Config May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:01:31 volumio volumio[1091]: info: Saving Spotify Acccount May 29 20:01:31 volumio volumio[1091]: info: Got Tidal Cloud Configuration May 29 20:01:31 volumio volumio[1091]: info: Got it May 29 20:01:31 volumio volumio[1091]: info: Got it May 29 20:01:31 volumio volumio[1091]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 29 20:01:31 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:01:31 volumio volumio[1091]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 29 20:01:32 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 29 20:01:32 volumio volumio[1091]: info: Loading i18n strings for locale en May 29 20:01:32 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 29 20:01:32 volumio volumio[1091]: info: Setting timezone to Europe/London May 29 20:01:32 volumio sudo[2127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 29 20:01:32 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:32 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root May 29 20:01:32 volumio sudo[2130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime May 29 20:01:32 volumio sudo[2130]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:32 volumio sudo[2130]: pam_unix(sudo:session): session closed for user root May 29 20:01:32 volumio sudo[2133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 29 20:01:32 volumio sudo[2133]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:32 volumio sudo[2133]: pam_unix(sudo:session): session closed for user root May 29 20:01:32 volumio sudo[2136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London May 29 20:01:32 volumio sudo[2136]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:32 volumio dbus-daemon[639]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.17' (uid=0 pid=2137 comm="/usr/bin/timedatectl set-timezone Europe/London ") May 29 20:01:32 volumio systemd[1]: Starting Time & Date Service... May 29 20:01:32 volumio dbus-daemon[639]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 20:01:32 volumio systemd[1]: Started Time & Date Service. May 29 20:01:32 volumio sudo[2136]: pam_unix(sudo:session): session closed for user root May 29 20:01:32 volumio volumio[1091]: Updating browse sources language May 29 20:01:32 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:01:33 volumio volumio[1091]: info: Fetching Streaming Services browse cache May 29 20:01:33 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 29 20:01:33 volumio volumio[1091]: info: Loading i18n strings for locale en May 29 20:01:33 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 29 20:01:33 volumio volumio[1091]: info: Setting timezone to Europe/London May 29 20:01:33 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 29 20:01:33 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:33 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root May 29 20:01:33 volumio sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime May 29 20:01:33 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:33 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root May 29 20:01:33 volumio sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 29 20:01:33 volumio sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:33 volumio sudo[2146]: pam_unix(sudo:session): session closed for user root May 29 20:01:33 volumio sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London May 29 20:01:33 volumio sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:33 volumio sudo[2149]: pam_unix(sudo:session): session closed for user root May 29 20:01:33 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 29 20:01:33 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 29 20:01:33 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:01:33 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 29 20:01:33 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:33 volumio volumio[1091]: Updating browse sources language May 29 20:01:33 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:01:33 volumio volumio[1091]: info: Fetching Streaming Services browse cache May 29 20:01:36 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root May 29 20:01:41 volumio sudo[2159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:01:41 volumio sudo[2159]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:41 volumio sudo[2159]: pam_unix(sudo:session): session closed for user root May 29 20:01:41 volumio sudo[2162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:01:41 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:01:41 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root May 29 20:01:41 volumio volumio[1091]: verbose: New Socket.io Connection to 192.168.1.78 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 29 20:01:41 volumio volumio[1091]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:01:41 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:01:41 volumio volumio[1091]: info: Received Get System Info May 29 20:01:41 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:01:41 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:01:41 volumio volumio[1091]: info: Discovery: Getting this device information May 29 20:01:41 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState May 29 20:01:41 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:01:41 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 20:01:49 volumio volumio[1091]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 29 20:01:49 volumio volumio[1091]: info: Received Get System Version May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 20:01:49 volumio volumio[1091]: info: Received Get System Info May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:01:49 volumio volumio[1091]: info: Discovery: Getting this device information May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState May 29 20:01:49 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:01:49 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:02:02 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted May 29 20:02:02 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 29 20:02:02 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 20:02:03 volumio volumio-remote-updater[603]: No test mode May 29 20:02:03 volumio volumio-remote-updater[603]: No alpha test mode May 29 20:02:03 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 20:02:03 volumio volumio[1091]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 20:02:03 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 20:02:03 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 29 20:02:03 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 20:02:03 volumio systemd[1]: systemd-timedated.service: Succeeded. May 29 20:02:03 volumio volumio-remote-updater[603]: No test mode May 29 20:02:03 volumio volumio-remote-updater[603]: No alpha test mode May 29 20:02:03 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 20:02:03 volumio volumio[1091]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 20:02:03 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 20:02:04 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:02:04 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 20:02:05 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 29 20:02:05 volumio volumio[1091]: info: MyVolumio login type: Token May 29 20:02:05 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 29 20:02:05 volumio volumio[1091]: info: MyVolumio login type: Token May 29 20:02:05 volumio volumio[1091]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 20:02:06 volumio volumio[1091]: info: MyVolumio token set successfully May 29 20:02:06 volumio volumio[1091]: info: MYVOLUMIO: Adding device May 29 20:02:06 volumio volumio[1091]: info: MYVOLUMIO: Evaluating Server May 29 20:02:06 volumio volumio[1091]: info: MyVolumio status changed May 29 20:02:06 volumio volumio[1091]: info: Streaming services startup May 29 20:02:06 volumio volumio[1091]: info: Starting Streaming Daemon May 29 20:02:06 volumio volumio[1091]: info: Removing browser output: myVolumio user plan is not superstar May 29 20:02:06 volumio volumio[1091]: info: Removing audio output: May 29 20:02:06 volumio volumio[1091]: info: Stoppping Tunnel 1 May 29 20:02:06 volumio sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 20:02:06 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:06 volumio sudo[2240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 20:02:06 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root May 29 20:02:06 volumio sudo[2240]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:06 volumio volumio[1091]: error: Cannot start Volumio Streaming Daemon May 29 20:02:06 volumio volumio[1091]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 20:02:06 volumio volumio[1091]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 20:02:06 volumio sudo[2240]: pam_unix(sudo:session): session closed for user root May 29 20:02:06 volumio volumio[1091]: info: Remote SSH Stopped May 29 20:02:06 volumio volumio[1091]: info: Setting Geolocation for MyVolumio to eu3 May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:06 volumio sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:02:06 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:06 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root May 29 20:02:06 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 20:02:06 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:06 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root May 29 20:02:06 volumio volumio[1091]: verbose: New Socket.io Connection to 192.168.1.78 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::volumioGetVisibleSources May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones May 29 20:02:06 volumio volumio[1091]: info: Received Get System Info May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 20:02:06 volumio volumio[1091]: info: Discovery: Getting this device information May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState May 29 20:02:06 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 20:02:06 volumio volumio-remote-updater[603]: No test mode May 29 20:02:06 volumio volumio-remote-updater[603]: No alpha test mode May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:02:06 volumio volumio[1091]: info: Retrieving Cloud Streaming UI May 29 20:02:06 volumio volumio[1091]: info: Getting Tidal Cloud Configuration May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:02:06 volumio volumio[1091]: info: Getting Qobuz Cloud Configuration May 29 20:02:06 volumio volumio[1091]: info: Asking plugin for UI Config May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:02:06 volumio volumio[1091]: info: Getting Spotify Cloud Configuration May 29 20:02:06 volumio volumio[1091]: info: Asking plugin for UI Config May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:02:06 volumio volumio[1091]: info: Saving Spotify Acccount May 29 20:02:06 volumio volumio[1091]: info: Got Tidal Cloud Configuration May 29 20:02:06 volumio volumio[1091]: info: Got it May 29 20:02:06 volumio volumio[1091]: info: Got it May 29 20:02:06 volumio volumio[1091]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:02:06 volumio volumio[1091]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 29 20:02:06 volumio volumio[1091]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 20:02:06 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 20:02:06 volumio volumio[1091]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 20:02:07 volumio volumio[1091]: info: Updating MyVolumio device info May 29 20:02:07 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:07 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage May 29 20:02:07 volumio volumio[1091]: info: Loading i18n strings for locale en May 29 20:02:07 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone May 29 20:02:07 volumio volumio[1091]: info: Setting timezone to Europe/London May 29 20:02:07 volumio sudo[2253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime May 29 20:02:07 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:07 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root May 29 20:02:07 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/London /etc/localtime May 29 20:02:07 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:07 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root May 29 20:02:07 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime May 29 20:02:07 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:07 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root May 29 20:02:07 volumio sudo[2262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/London May 29 20:02:07 volumio sudo[2262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:08 volumio dbus-daemon[639]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=2263 comm="/usr/bin/timedatectl set-timezone Europe/London ") May 29 20:02:08 volumio systemd[1]: Starting Time & Date Service... May 29 20:02:08 volumio dbus-daemon[639]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 20:02:08 volumio systemd[1]: Started Time & Date Service. May 29 20:02:08 volumio sudo[2262]: pam_unix(sudo:session): session closed for user root May 29 20:02:08 volumio volumio[1091]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 20:02:08 volumio volumio[1091]: Updating browse sources language May 29 20:02:08 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 20:02:08 volumio volumio[1091]: info: Fetching Streaming Services browse cache May 29 20:02:10 volumio volumio[1091]: info: MYVOLUMIO: Adding device May 29 20:02:10 volumio volumio[1091]: info: MYVOLUMIO: Evaluating Server May 29 20:02:11 volumio volumio[1091]: info: Setting Geolocation for MyVolumio to eu3 May 29 20:02:11 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:11 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:11 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:11 volumio volumio[1091]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 20:02:12 volumio volumio[1091]: info: Updating MyVolumio device info May 29 20:02:12 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:12 volumio volumio[1091]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 20:02:14 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings May 29 20:02:14 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices May 29 20:02:14 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 29 20:02:14 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:14 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 29 20:02:16 volumio volumio[1091]: info: Preparing to save Alsa Options, stopping services first May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState May 29 20:02:16 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::volumioPause May 29 20:02:16 volumio volumio[1091]: info: CoreStateMachine::pause May 29 20:02:16 volumio volumio[1091]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"label":"IQaudIO DAC Plus"},"output_device":{"value":1,"label":"IQaudIO DAC Plus"},"disallowPush":true} May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:16 volumio volumio[1091]: warn: Unable to locate the device IQaudIO DAC Plus a reboot may be required. May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:16 volumio volumio[1091]: info: Found match in i2s Card Database: setting mixer Digital for card IQaudIO DAC Plus May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 29 20:02:16 volumio volumio[1091]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 29 20:02:16 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 29 20:02:16 volumio volumio[1091]: info: Disabling external Volume Control May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 20:02:17 volumio volumio[1091]: info: Enable softmixer device for audio device undefined May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 29 20:02:17 volumio sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 29 20:02:17 volumio sudo[2304]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio volumio[1091]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 29 20:02:17 volumio sudo[2307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 29 20:02:17 volumio sudo[2307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio sudo[2307]: pam_unix(sudo:session): session closed for user root May 29 20:02:17 volumio volumio[1091]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan May 29 20:02:17 volumio volumio[1091]: wlan0 Interface doesn't support scanning : Device or resource busy May 29 20:02:17 volumio volumio[1091]: info: Cannot use regular scanning, forcing with ap-force May 29 20:02:17 volumio sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force May 29 20:02:17 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root May 29 20:02:17 volumio volumio[1091]: command failed: Device or resource busy (-16) May 29 20:02:17 volumio volumio[1091]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force May 29 20:02:17 volumio volumio[1091]: command failed: Device or resource busy (-16) May 29 20:02:17 volumio volumio[1091]: info: Software Volume ALSA configuration written May 29 20:02:17 volumio volumio[1091]: info: Preparing to generate the ALSA configuration file May 29 20:02:17 volumio volumio[1091]: info: VolumeController:: Volume=undefined Mute =false May 29 20:02:17 volumio volumio[1091]: info: CoreStateMachine::pushState May 29 20:02:17 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::volumioPushState May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 29 20:02:17 volumio volumio[1091]: info: Getting Alsa Cards List without I2S DAC May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:17 volumio volumio[1091]: Invalid card number. May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 29 20:02:17 volumio volumio[1091]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 29 20:02:17 volumio volumio[1091]: info: Reading ALSA contributions from plugins. May 29 20:02:17 volumio volumio[1091]: info: Asound.conf file unchanged, so no further update is needed May 29 20:02:17 volumio volumio[1091]: info: Output device has changed, restarting MPD May 29 20:02:17 volumio sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 20:02:17 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio volumio[1091]: info: Output device has changed, restarting Shairport Sync May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:17 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root May 29 20:02:17 volumio sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 20:02:17 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio systemd[1]: Stopping Music Player Daemon... May 29 20:02:17 volumio volumio[1091]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 20:02:17 volumio volumio[1091]: info: MPD Permissions set May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio systemd[1]: mpd.service: Succeeded. May 29 20:02:17 volumio systemd[1]: Stopped Music Player Daemon. May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 20:02:17 volumio volumio[1091]: info: Starting Shairport Sync May 29 20:02:17 volumio sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 20:02:17 volumio sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio systemd[1]: Starting Music Player Daemon... May 29 20:02:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 29 20:02:17 volumio systemd[1]: shairport-sync.service: Succeeded. May 29 20:02:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 29 20:02:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 29 20:02:17 volumio sudo[2336]: pam_unix(sudo:session): session closed for user root May 29 20:02:17 volumio volumio[1091]: info: Shairport-Sync Started May 29 20:02:17 volumio sudo[2334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 20:02:17 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:17 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root May 29 20:02:18 volumio mpd[2354]: May 29 20:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 20:02:18 volumio systemd[1]: Started Music Player Daemon. May 29 20:02:18 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root May 29 20:02:18 volumio volumio[1091]: error: updateQueue error: null May 29 20:02:20 volumio sudo[2304]: pam_unix(sudo:session): session closed for user root May 29 20:02:21 volumio volumio[1091]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 29 20:02:21 volumio volumio[1091]: info: CoreStateMachine::getcurrentVolume May 29 20:02:21 volumio volumio[1091]: info: CoreCommandRouter::volumioRetrievevolume May 29 20:02:21 volumio volumio[1091]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 29 20:02:21 volumio volumio[1091]: info: VolumeController:: Volume=undefined Mute =false May 29 20:02:21 volumio volumio[1091]: info: CoreStateMachine::pushState May 29 20:02:21 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:02:21 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 20:02:21 volumio volumio[1091]: info: CoreCommandRouter::volumioPushState May 29 20:02:21 volumio volumio[1091]: info: CoreStateMachine::updateTrackBlock May 29 20:02:21 volumio volumio[1091]: info: CorePlayQueue::getTrackBlock May 29 20:02:21 volumio volumio[1091]: info: CoreCommandRouter::volumioRetrievevolume May 29 20:02:21 volumio volumio[1091]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 29 20:02:21 volumio volumio[1091]: info: VolumeController:: Volume=undefined Mute =false May 29 20:02:21 volumio volumio[1091]: info: CoreStateMachine::pushState May 29 20:02:21 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:02:21 volumio volumio[1091]: info: CoreCommandRouter::volumioPushState May 29 20:02:38 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule May 29 20:02:38 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 20:02:38 volumio systemd[1]: systemd-timedated.service: Succeeded. May 29 20:02:38 volumio volumio-remote-updater[603]: No test mode May 29 20:02:38 volumio volumio-remote-updater[603]: No alpha test mode May 29 20:02:38 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 20:02:38 volumio volumio[1091]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 20:02:38 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 20:02:44 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork May 29 20:02:44 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo May 29 20:02:44 volumio sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 20:02:44 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:44 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root May 29 20:02:44 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings May 29 20:02:44 volumio volumio[1091]: info: Saving new wireless network May 29 20:02:44 volumio sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf May 29 20:02:44 volumio sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:44 volumio sudo[2445]: pam_unix(sudo:session): session closed for user root May 29 20:02:44 volumio volumio[1091]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 29 20:02:44 volumio volumio[1091]: info: Discovery: Restarting Advertising due to device name change May 29 20:02:44 volumio sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 29 20:02:44 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:44 volumio dhcpcd[991]: received SIGTERM, stopping May 29 20:02:44 volumio dhcpcd[991]: wlan0: removing interface May 29 20:02:44 volumio systemd[1]: Stopping Wireless Services... May 29 20:02:45 volumio dhcpcd[991]: eth0: removing interface May 29 20:02:45 volumio dhcpcd[991]: dhcpcd exited May 29 20:02:45 volumio systemd[1]: wireless.service: Succeeded. May 29 20:02:45 volumio systemd[1]: Stopped Wireless Services. May 29 20:02:45 volumio systemd[1]: Starting Wireless Services... May 29 20:02:45 volumio wireless.js[2452]: Cleaning previous... May 29 20:02:45 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... May 29 20:02:45 volumio volumio[1091]: info: Discovery: A device disappeared from network May 29 20:02:45 volumio volumio[1091]: info: Discovery: Device volumio disappeared from network May 29 20:02:45 volumio volumio[1091]: info: Discovery: A device disappeared from network May 29 20:02:46 volumio systemd[1]: hostapd.service: Succeeded. May 29 20:02:46 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. May 29 20:02:46 volumio sudo[2468]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 29 20:02:46 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:46 volumio avahi-daemon[612]: Withdrawing address record for 192.168.211.1 on wlan0. May 29 20:02:46 volumio avahi-daemon[612]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 29 20:02:46 volumio avahi-daemon[612]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.182.217. May 29 20:02:46 volumio avahi-daemon[612]: Withdrawing address record for 169.254.182.217 on wlan0. May 29 20:02:46 volumio avahi-daemon[612]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.182.217. May 29 20:02:46 volumio avahi-daemon[612]: Interface wlan0.IPv4 no longer relevant for mDNS. May 29 20:02:46 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root May 29 20:02:46 volumio sudo[2470]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 29 20:02:46 volumio sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:46 volumio sudo[2470]: pam_unix(sudo:session): session closed for user root May 29 20:02:46 volumio wireless.js[2452]: Stopped aP May 29 20:02:46 volumio sudo[2477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 29 20:02:46 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:46 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root May 29 20:02:46 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 29 20:02:46 volumio sudo[2479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 29 20:02:46 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:46 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root May 29 20:02:46 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 29 20:02:46 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:46 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root May 29 20:02:46 volumio sudo[2488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 29 20:02:46 volumio sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:47 volumio ntpd[780]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=359 secs May 29 20:02:47 volumio ntpd[780]: Deleting interface #5 wlan0, 169.254.182.217#123, interface stats: received=0, sent=0, dropped=0, active_time=349 secs May 29 20:02:49 volumio sudo[2488]: pam_unix(sudo:session): session closed for user root May 29 20:02:49 volumio wireless.js[2452]: SETTING APPROPRIATE REG DOMAIN: GB May 29 20:02:49 volumio sudo[2493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 29 20:02:49 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:49 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root May 29 20:02:49 volumio sudo[2495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB May 29 20:02:49 volumio sudo[2495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:49 volumio sudo[2495]: pam_unix(sudo:session): session closed for user root May 29 20:02:49 volumio wireless.js[2452]: SUCCESSFULLY SET NEW REGDOMAIN: GB May 29 20:02:49 volumio wireless.js[2452]: Start wireless flow May 29 20:02:49 volumio wireless.js[2452]: Stopped hotspot (if there).. May 29 20:02:49 volumio sudo[2500]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 29 20:02:49 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:49 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root May 29 20:02:49 volumio sudo[2502]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 29 20:02:49 volumio sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:49 volumio sudo[2502]: pam_unix(sudo:session): session closed for user root May 29 20:02:49 volumio wireless.js[2452]: DHCP IP May 29 20:02:49 volumio wireless.js[2452]: Start ap May 29 20:02:49 volumio wpa_supplicant[2504]: Successfully initialized wpa_supplicant May 29 20:02:49 volumio sudo[2505]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 29 20:02:49 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:49 volumio dhcpcd[2506]: dev: loaded udev May 29 20:02:49 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 29 20:02:49 volumio volumio[1091]: info: Discovery: Started advertising with name: Volumio May 29 20:02:49 volumio dhcpcd-run-hooks[2515]: wlan0: starting wpa_supplicant May 29 20:02:50 volumio dhcpcd-run-hooks[2521]: wlan0: failed to start wpa_supplicant May 29 20:02:50 volumio dhcpcd-run-hooks[2522]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 29 20:02:50 volumio dhcpcd[2506]: wlan0: connected to Access Point `' May 29 20:02:50 volumio dhcpcd[2506]: DUID 00:01:00:01:2f:b6:20:2f:e4:5f:01:44:15:c7 May 29 20:02:50 volumio dhcpcd[2506]: eth0: IAID 01:44:15:c7 May 29 20:02:50 volumio dhcpcd[2506]: eth0: adding address fe80::a54d:5131:e0d8:9741 May 29 20:02:50 volumio dhcpcd[2506]: ipv6_addaddr1: Permission denied May 29 20:02:50 volumio dhcpcd[2506]: wlan0: waiting for carrier May 29 20:02:50 volumio dhcpcd[2506]: eth0: soliciting an IPv6 router May 29 20:02:50 volumio dhcpcd[2506]: eth0: rebinding lease of 192.168.1.78 May 29 20:02:50 volumio dhcpcd[2506]: eth0: leased 192.168.1.78 for infinity May 29 20:02:50 volumio dhcpcd[2506]: eth0: adding route to 192.168.1.0/24 May 29 20:02:50 volumio dhcpcd[2506]: eth0: adding default route via 192.168.1.1 May 29 20:02:50 volumio dhcpcd[2506]: forked to background, child pid 2562 May 29 20:02:50 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root May 29 20:02:50 volumio volumio[1091]: info: Discovery: adding 9343bdd8-920b-4fde-aa8d-4d25bef126e0 May 29 20:02:50 volumio volumio[1091]: info: Discovery: Found device Volumio May 29 20:02:50 volumio volumio[1091]: info: CoreCommandRouter::volumioGetState May 29 20:02:50 volumio volumio[1091]: info: CorePlayQueue::getTrack 0 May 29 20:02:50 volumio volumio[1091]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:02:50 volumio volumio[1091]: Error: The value NaN is not a number May 29 20:02:50 volumio volumio[1091]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 29 20:02:50 volumio volumio[1091]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 29 20:02:50 volumio volumio[1091]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 29 20:02:50 volumio volumio[1091]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 29 20:02:50 volumio volumio[1091]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 29 20:02:50 volumio volumio[1091]: at Browser.emit (events.js:315:20) May 29 20:02:50 volumio volumio[1091]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 29 20:02:50 volumio volumio[1091]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 29 20:02:50 volumio volumio[1091]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 29 20:02:50 volumio volumio[1091]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 29 20:02:50 volumio volumio[1091]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 29 20:02:50 volumio volumio[1091]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 29 20:02:50 volumio volumio[1091]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 29 20:02:50 volumio volumio[1091]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 20:02:50 volumio wireless.js[2452]: trying... May 29 20:02:50 volumio sudo[2573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 29 20:02:50 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root by (uid=0) May 29 20:02:50 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root May 29 20:02:51 volumio sudo[2576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 20:01 May 29 20:02:51 volumio sudo[2576]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"