Jun 13 12:43:01 volumio volumio[1036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 13 12:43:01 volumio volumio[1036]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 13 12:43:01 volumio volumio[1036]: info: Streaming services startup Jun 13 12:43:01 volumio volumio[1036]: info: Starting Streaming Daemon Jun 13 12:43:01 volumio dhcpcd[666]: wlan0: probing for an IPv4LL address Jun 13 12:43:01 volumio sudo[2017]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 13 12:43:01 volumio sudo[2017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:01 volumio sudo[2017]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:01 volumio volumio[1036]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 13 12:43:01 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 13 12:43:01 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 13 12:43:01 volumio volumio[1036]: error: Cannot start Volumio Streaming Daemon Jun 13 12:43:01 volumio volumio[1036]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 13 12:43:01 volumio volumio[1036]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 13 12:43:01 volumio sudo[2024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 12:43:01 volumio sudo[2024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:01 volumio sudo[2024]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 13 12:43:04 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 13 12:43:04 volumio volumio[1036]: info: Refreshing Cached IP Addresses Jun 13 12:43:04 volumio sudo[2029]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 12:43:04 volumio sudo[2027]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 13 12:43:04 volumio sudo[2027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:04 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:04 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:04 volumio sudo[2027]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:04 volumio sudo[2033]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 12:43:04 volumio sudo[2033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:04 volumio sudo[2033]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:06 volumio dhcpcd[666]: wlan0: using IPv4LL address 169.254.88.245 Jun 13 12:43:06 volumio avahi-daemon[562]: Registering new address record for 169.254.88.245 on wlan0.IPv4. Jun 13 12:43:06 volumio dhcpcd[666]: wlan0: adding route to 169.254.0.0/16 Jun 13 12:43:06 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 13 12:43:06 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 13 12:43:06 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 13 12:43:06 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 13 12:43:06 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 13 12:43:06 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 13 12:43:06 volumio welcome[2049]: Resolved ip:[3] 192.168.1.33 192.168.211.1 169.254.88.245 Jun 13 12:43:06 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 13 12:43:06 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 13 12:43:07 volumio ntpd[863]: IO: Listen normally on 7 wlan0 169.254.88.245:123 Jun 13 12:43:07 volumio ntpd[863]: IO: new interface(s) found: waking up resolver Jun 13 12:43:07 volumio ntpd[863]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 13 12:43:07 volumio ntpd[863]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Jun 13 12:43:07 volumio ntpd[863]: DNS: Pool skipping: 202.80.227.130 Jun 13 12:43:07 volumio ntpd[863]: DNS: Pool skipping: 171.103.24.86 Jun 13 12:43:07 volumio ntpd[863]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Jun 13 12:43:08 volumio ntpd[863]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 13 12:43:08 volumio ntpd[863]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 13 12:43:08 volumio ntpd[863]: DNS: Pool skipping: 202.12.97.45 Jun 13 12:43:08 volumio ntpd[863]: DNS: Pool skipping: 202.28.92.208 Jun 13 12:43:08 volumio ntpd[863]: DNS: Pool skipping: 240b:400f:8:3a07:400:115:974f:c952 Jun 13 12:43:08 volumio ntpd[863]: DNS: Pool skipping: 2401:5700:100:508::135:5 Jun 13 12:43:08 volumio ntpd[863]: DNS: Pool skipping: 2406:3100:1010:c03::149 Jun 13 12:43:08 volumio ntpd[863]: DNS: Pool skipping: 2001:c38:2000:180:d82a:1aff:fe38:f84b Jun 13 12:43:08 volumio ntpd[863]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 13 12:43:09 volumio ntpd[863]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 13 12:43:09 volumio ntpd[863]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 13 12:43:09 volumio ntpd[863]: DNS: Pool skipping: 202.28.116.236 Jun 13 12:43:09 volumio ntpd[863]: DNS: Pool skipping: 202.28.117.7 Jun 13 12:43:09 volumio ntpd[863]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 13 12:43:10 volumio ntpd[863]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 13 12:43:10 volumio ntpd[863]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 13 12:43:10 volumio ntpd[863]: DNS: Pool skipping: 203.159.70.33 Jun 13 12:43:10 volumio ntpd[863]: DNS: Pool skipping: 122.155.180.231 Jun 13 12:43:10 volumio ntpd[863]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 13 12:43:13 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:43:13 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:13 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 12:43:14 volumio volumio-remote-updater[594]: Test mode disabled Jun 13 12:43:14 volumio volumio-remote-updater[594]: Alpha mode disabled Jun 13 12:43:14 volumio volumio-remote-updater[594]: Alpha legacy test mode disabled Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:43:14 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:14 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:43:14 volumio volumio[1036]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 13 12:43:14 volumio volumio[1036]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 13 12:43:14 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:14 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:14 volumio sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 12:43:14 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:14 volumio sudo[2082]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 12:43:14 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:14 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:14 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:14 volumio sudo[2088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 13 12:43:14 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:14 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:14 volumio volumio[1036]: info: Upmpdcli Daemon Started Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::volumioGetBrowseSources Jun 13 12:43:19 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:43:21 volumio volumio[1036]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 13 12:43:21 volumio volumio[1036]: info: Completed starting MyVolumio Plugin Jun 13 12:43:21 volumio volumio[1036]: [Metrics] CommandRouter: 46s 919.09ms Jun 13 12:43:21 volumio volumio[1036]: info: CoreCommandRouter::volumiosetStartupVolume Jun 13 12:43:21 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:43:21 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:21 volumio volumio[1036]: info: CoreCommandRouter::Close All Modals sent Jun 13 12:43:21 volumio volumio[1036]: info: CoreCommandRouter::Close All Modals sent Jun 13 12:43:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 13 12:43:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 12:43:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 13 12:43:22 volumio volumio[1036]: info: Version has changed, forcing UI Reload Jun 13 12:43:22 volumio volumio[1036]: info: CoreCommandRouter::Reload Ui Jun 13 12:43:28 volumio volumio[1036]: info: BOOT COMPLETED Jun 13 12:43:32 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 13 12:43:32 volumio dbus-daemon[583]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.27' (uid=0 pid=2124 comm="timedatectl show --property=NTPSynchronized --valu") Jun 13 12:43:32 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 13 12:43:32 volumio dbus-daemon[583]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 13 12:43:32 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 13 12:43:32 volumio setdatetime-helper.sh[2123]: Time is not synchronized. Attempting to sync... Jun 13 12:43:32 volumio sudo[2132]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 13 Jun 2025 05:43:32 GMT#015' Jun 13 12:43:32 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:43:32 volumio setdatetime-helper.sh[2133]: Fri Jun 13 05:43:32 UTC 2025 Jun 13 12:43:32 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:32 volumio setdatetime-helper.sh[2123]: Time synchronized successfully. Jun 13 12:43:32 volumio systemd-journald[350]: Time jumped backwards, rotating. Jun 13 12:43:32 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 13 12:43:32 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 13 12:43:38 volumio sudo[2156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 12:43:38 volumio sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 12:43:38 volumio sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:38 volumio sudo[2156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:38 volumio sudo[2156]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:38 volumio sudo[2155]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:39 volumio sudo[2161]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 12:43:39 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:39 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:39 volumio sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 12:43:39 volumio sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:39 volumio sudo[2162]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:39 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.230 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 13 12:43:39 volumio volumio[1036]: info: Received Get System Info Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:43:39 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:39 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:43:39 volumio volumio-remote-updater[594]: Test mode disabled Jun 13 12:43:39 volumio volumio-remote-updater[594]: Alpha mode disabled Jun 13 12:43:39 volumio volumio-remote-updater[594]: Alpha legacy test mode disabled Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:39 volumio volumio[1036]: info: Retrieving Cloud Streaming UI Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 13 12:43:39 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:43:40 volumio volumio[1036]: info: Getting Tidal Cloud Configuration Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:40 volumio volumio[1036]: info: Getting Qobuz Cloud Configuration Jun 13 12:43:40 volumio volumio[1036]: info: Asking plugin for UI Config Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:40 volumio volumio[1036]: info: Getting Spotify Cloud Configuration Jun 13 12:43:40 volumio volumio[1036]: info: Asking plugin for UI Config Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:40 volumio volumio[1036]: info: Saving Spotify Acccount Jun 13 12:43:40 volumio volumio[1036]: info: Got Tidal Cloud Configuration Jun 13 12:43:40 volumio volumio[1036]: info: Got it Jun 13 12:43:40 volumio volumio[1036]: info: Got it Jun 13 12:43:40 volumio volumio[1036]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:40 volumio volumio[1036]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 13 12:43:40 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.230 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 13 12:43:40 volumio volumio[1036]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:40 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.228 UA: Mozilla/5.0 (Linux; Android 15; A059 Build/AQ3A.241015.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 13 12:43:40 volumio volumio[1036]: info: Received Get System Info Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:43:40 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:40 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:40 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:40 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetQueue Jun 13 12:43:40 volumio volumio[1036]: info: CoreStateMachine::getQueue Jun 13 12:43:40 volumio volumio[1036]: info: CorePlayQueue::getQueue Jun 13 12:43:40 volumio volumio[1036]: info: Listing playlists Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 13 12:43:40 volumio volumio[1036]: info: Received Get System Info Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:43:40 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:40 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:43:40 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:40 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:41 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:41 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 13 12:43:41 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 13 12:43:41 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 13 12:43:41 volumio volumio[1036]: info: MyVolumio login type: Token Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:42 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.230 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:42 volumio volumio[1036]: info: Retrieving Cloud Streaming UI Jun 13 12:43:42 volumio volumio[1036]: info: Getting Tidal Cloud Configuration Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:42 volumio volumio[1036]: info: Getting Qobuz Cloud Configuration Jun 13 12:43:42 volumio volumio[1036]: info: Asking plugin for UI Config Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:42 volumio volumio[1036]: info: Getting Spotify Cloud Configuration Jun 13 12:43:42 volumio volumio[1036]: info: Asking plugin for UI Config Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:42 volumio volumio[1036]: info: Saving Spotify Acccount Jun 13 12:43:42 volumio volumio[1036]: info: Got Tidal Cloud Configuration Jun 13 12:43:42 volumio volumio[1036]: info: Got it Jun 13 12:43:42 volumio volumio[1036]: info: Got it Jun 13 12:43:42 volumio volumio[1036]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:42 volumio volumio[1036]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 13 12:43:42 volumio volumio[1036]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 13 12:43:42 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 13 12:43:42 volumio volumio[1036]: info: MyVolumio login type: Token Jun 13 12:43:43 volumio volumio[1036]: info: MyVolumio token set successfully Jun 13 12:43:43 volumio volumio[1036]: info: MYVOLUMIO: Adding device Jun 13 12:43:43 volumio volumio[1036]: info: MYVOLUMIO: Evaluating Server Jun 13 12:43:44 volumio volumio[1036]: info: MyVolumio status changed Jun 13 12:43:44 volumio volumio[1036]: info: Streaming services startup Jun 13 12:43:44 volumio volumio[1036]: info: Starting Streaming Daemon Jun 13 12:43:44 volumio volumio[1036]: info: Removing browser output: myVolumio user plan is not superstar Jun 13 12:43:44 volumio volumio[1036]: info: Removing audio output: Jun 13 12:43:44 volumio volumio[1036]: info: Stoppping Tunnel 1 Jun 13 12:43:44 volumio sudo[2188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 13 12:43:44 volumio sudo[2188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:44 volumio sudo[2190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 13 12:43:44 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:44 volumio sudo[2188]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:44 volumio volumio[1036]: error: Cannot start Volumio Streaming Daemon Jun 13 12:43:44 volumio volumio[1036]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 13 12:43:44 volumio volumio[1036]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 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. Jun 13 12:43:44 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:44 volumio volumio[1036]: info: Remote SSH Stopped Jun 13 12:43:44 volumio volumio[1036]: info: Setting Geolocation for MyVolumio to as1 Jun 13 12:43:44 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:44 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:44 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:45 volumio volumio[1036]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 13 12:43:45 volumio volumio[1036]: info: Updating MyVolumio device info Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:45 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:46 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:46 volumio volumio[1036]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 13 12:43:48 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:49 volumio volumio[1036]: info: MYVOLUMIO: Adding device Jun 13 12:43:49 volumio volumio[1036]: info: MYVOLUMIO: Evaluating Server Jun 13 12:43:50 volumio sudo[2223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 12:43:50 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:50 volumio sudo[2225]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 13 12:43:50 volumio sudo[2225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:50 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:50 volumio volumio[1036]: info: Setting Geolocation for MyVolumio to as1 Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:50 volumio sudo[2225]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:50 volumio volumio[1036]: verbose: New Socket.io Connection to 192.168.1.33 from 192.168.1.230 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::volumioGetVisibleSources Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 13 12:43:50 volumio volumio[1036]: info: Received Get System Info Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:43:50 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:50 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:43:50 volumio volumio-remote-updater[594]: Test mode disabled Jun 13 12:43:50 volumio volumio-remote-updater[594]: Alpha mode disabled Jun 13 12:43:50 volumio volumio-remote-updater[594]: Alpha legacy test mode disabled Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:50 volumio volumio[1036]: info: Retrieving Cloud Streaming UI Jun 13 12:43:50 volumio volumio[1036]: info: Getting Tidal Cloud Configuration Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:50 volumio volumio[1036]: info: Getting Qobuz Cloud Configuration Jun 13 12:43:50 volumio volumio[1036]: info: Asking plugin for UI Config Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:50 volumio volumio[1036]: info: Getting Spotify Cloud Configuration Jun 13 12:43:50 volumio volumio[1036]: info: Asking plugin for UI Config Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:50 volumio volumio[1036]: info: Saving Spotify Acccount Jun 13 12:43:50 volumio volumio[1036]: info: Got Tidal Cloud Configuration Jun 13 12:43:50 volumio volumio[1036]: info: Got it Jun 13 12:43:50 volumio volumio[1036]: info: Got it Jun 13 12:43:50 volumio volumio[1036]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:43:50 volumio volumio[1036]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 13 12:43:50 volumio volumio[1036]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 13 12:43:50 volumio volumio[1036]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 13 12:43:50 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 13 12:43:51 volumio volumio[1036]: info: Updating MyVolumio device info Jun 13 12:43:51 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:51 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:51 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:51 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 13 12:43:51 volumio volumio[1036]: info: Loading i18n strings for locale en Jun 13 12:43:51 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 13 12:43:51 volumio volumio[1036]: info: Setting timezone to Asia/Bangkok Jun 13 12:43:51 volumio sudo[2236]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 13 12:43:51 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:51 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:51 volumio sudo[2240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Jun 13 12:43:51 volumio sudo[2240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:51 volumio sudo[2240]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:51 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Jun 13 12:43:51 volumio sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 13 12:43:51 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:51 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:51 volumio sudo[2251]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Jun 13 12:43:51 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:51 volumio systemd-timedated[2126]: Changed time zone to 'Asia/Bangkok' (+07). Jun 13 12:43:51 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:51 volumio volumio[1036]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 13 12:43:51 volumio volumio[1036]: info: Fetching Streaming Services browse cache Jun 13 12:43:52 volumio volumio[1036]: Updating browse sources language Jun 13 12:43:52 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:52 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:52 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Jun 13 12:43:52 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Jun 13 12:43:53 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:53 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 13 12:43:53 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 13 12:43:53 volumio volumio[1036]: info: Loading i18n strings for locale en Jun 13 12:43:53 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 13 12:43:53 volumio volumio[1036]: info: Setting timezone to Asia/Bangkok Jun 13 12:43:53 volumio sudo[2310]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 13 12:43:53 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:53 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:53 volumio sudo[2314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Jun 13 12:43:53 volumio sudo[2314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:53 volumio sudo[2314]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:54 volumio sudo[2318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 13 12:43:54 volumio sudo[2318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:54 volumio sudo[2318]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:54 volumio sudo[2322]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Jun 13 12:43:54 volumio sudo[2322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:54 volumio sudo[2322]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 13 12:43:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 13 12:43:54 volumio sudo[2325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 13 12:43:54 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:43:54 volumio volumio[1036]: Updating browse sources language Jun 13 12:43:54 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 13 12:43:54 volumio volumio[1036]: info: Fetching Streaming Services browse cache Jun 13 12:43:57 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:43:58 volumio volumio[1036]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 13 12:43:58 volumio volumio[1036]: info: Received Get System Version Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:43:58 volumio volumio[1036]: info: Received Get System Info Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:43:58 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:43:58 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:43:58 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:43:59 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jun 13 12:43:59 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jun 13 12:43:59 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 13 12:43:59 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:43:59 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 13 12:44:05 volumio volumio[1036]: info: Preparing to save Alsa Options, stopping services first Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:44:05 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::volumioPause Jun 13 12:44:05 volumio volumio[1036]: info: CoreStateMachine::pause Jun 13 12:44:05 volumio volumio[1036]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"Headphones"},"disallowPush":true} Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 12:44:05 volumio volumio[1036]: info: Found match in Cards Database: setting mixer PCM for card Headphones Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 13 12:44:05 volumio volumio[1036]: info: Updating Volume Controller Parameters: Device: 1 Name: Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 13 12:44:05 volumio volumio[1036]: info: Disabling external Volume Control Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 13 12:44:05 volumio volumio[1036]: info: Preparing to generate the ALSA configuration file Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 13 12:44:05 volumio sudo[2356]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 13 12:44:05 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:05 volumio sudo[2358]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 13 12:44:05 volumio sudo[2358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:05 volumio sudo[2358]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 13 12:44:05 volumio volumio[1036]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 13 12:44:05 volumio volumio[1036]: wlan0 Interface doesn't support scanning : Device or resource busy Jun 13 12:44:05 volumio volumio[1036]: info: Cannot use regular scanning, forcing with ap-force Jun 13 12:44:05 volumio sudo[2374]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 13 12:44:05 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:05 volumio sudo[2374]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:05 volumio volumio[1036]: command failed: Device or resource busy (-16) Jun 13 12:44:05 volumio volumio[1036]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 13 12:44:05 volumio volumio[1036]: command failed: Device or resource busy (-16) Jun 13 12:44:05 volumio volumio[1036]: info: VolumeController:: Volume=86 Mute =false Jun 13 12:44:05 volumio volumio[1036]: info: CoreStateMachine::pushState Jun 13 12:44:05 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:44:05 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Jun 13 12:44:05 volumio volumio[1036]: info: Asound.conf file written Jun 13 12:44:05 volumio sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 13 12:44:05 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:05 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:06 volumio volumio[1036]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 13 12:44:06 volumio volumio[1036]: No state is present for card b1 Jun 13 12:44:06 volumio volumio[1036]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 13 12:44:06 volumio volumio[1036]: /usr/share/alsa/init/bcm2835:14: control element not found Jun 13 12:44:06 volumio volumio[1036]: No state is present for card b1 Jun 13 12:44:06 volumio volumio[1036]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 13 12:44:06 volumio volumio[1036]: No state is present for card Headphones Jun 13 12:44:06 volumio volumio[1036]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 13 12:44:06 volumio volumio[1036]: /usr/share/alsa/init/bcm2835:20: control element not found Jun 13 12:44:06 volumio volumio[1036]: No state is present for card Headphones Jun 13 12:44:06 volumio volumio[1036]: info: Output device has changed, restarting MPD Jun 13 12:44:06 volumio volumio[1036]: info: Output device has changed, restarting Shairport Sync Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:44:06 volumio sudo[2384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 13 12:44:06 volumio sudo[2384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:06 volumio sudo[2384]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:06 volumio volumio[1036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 13 12:44:06 volumio volumio[1036]: info: Output device has changed, restarting MPD Jun 13 12:44:06 volumio sudo[2388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 13 12:44:06 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:06 volumio sudo[2393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 13 12:44:06 volumio volumio[1036]: info: Output device has changed, restarting Shairport Sync Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:44:06 volumio sudo[2393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:06 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 13 12:44:06 volumio sudo[2393]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:06 volumio sudo[2396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 13 12:44:06 volumio sudo[2396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:06 volumio volumio[1036]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 13 12:44:06 volumio volumio[1036]: info: MPD Permissions set Jun 13 12:44:06 volumio volumio[1036]: info: MPD Permissions set Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 13 12:44:06 volumio volumio[1036]: info: Starting Shairport Sync Jun 13 12:44:06 volumio volumio[1036]: info: Starting Shairport Sync Jun 13 12:44:06 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 13 12:44:06 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 13 12:44:06 volumio systemd[1]: mpd.service: Consumed 3.080s CPU time. Jun 13 12:44:06 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 13 12:44:06 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 13 12:44:06 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:06 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 13 12:44:06 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 13 12:44:06 volumio sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 13 12:44:06 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:06 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 13 12:44:06 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 13 12:44:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 13 12:44:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 13 12:44:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 13 12:44:06 volumio systemd[1]: shairport-sync.service: Consumed 1.605s CPU time. Jun 13 12:44:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 13 12:44:06 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:06 volumio volumio[1036]: info: Shairport-Sync Started Jun 13 12:44:06 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 13 12:44:06 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 13 12:44:06 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 13 12:44:06 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 13 12:44:06 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:06 volumio sudo[2420]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 13 12:44:06 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:06 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:06 volumio volumio[1036]: info: Shairport-Sync Started Jun 13 12:44:08 volumio mpd[2424]: 2025-06-13T12:44:08 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 13 12:44:08 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 13 12:44:08 volumio sudo[2396]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:08 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:08 volumio volumio[1036]: error: MPD error: The expression evaluated to a falsy value: Jun 13 12:44:08 volumio volumio[1036]: assert.ok(self.idling) Jun 13 12:44:08 volumio volumio[1036]: error: The expression evaluated to a falsy value: Jun 13 12:44:08 volumio volumio[1036]: assert.ok(self.idling) Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 13 12:44:08 volumio volumio[1036]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 13 12:44:08 volumio volumio[1036]: info: Received Get System Version Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 13 12:44:08 volumio volumio[1036]: info: Received Get System Info Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 13 12:44:08 volumio volumio[1036]: info: Discovery: Getting this device information Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::volumioGetState Jun 13 12:44:08 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:44:08 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 13 12:44:08 volumio volumio[1036]: error: updateQueue error: null Jun 13 12:44:09 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:09 volumio volumio[1036]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 13 12:44:09 volumio volumio[1036]: info: CoreStateMachine::getcurrentVolume Jun 13 12:44:09 volumio volumio[1036]: info: CoreCommandRouter::volumioRetrievevolume Jun 13 12:44:09 volumio volumio[1036]: info: VolumeController:: Volume=86 Mute =false Jun 13 12:44:09 volumio volumio[1036]: info: CoreStateMachine::pushState Jun 13 12:44:09 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:44:09 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Jun 13 12:44:09 volumio volumio[1036]: info: CoreStateMachine::updateTrackBlock Jun 13 12:44:09 volumio volumio[1036]: info: CorePlayQueue::getTrackBlock Jun 13 12:44:09 volumio volumio[1036]: info: CoreCommandRouter::volumioRetrievevolume Jun 13 12:44:09 volumio volumio[1036]: info: VolumeController:: Volume=86 Mute =false Jun 13 12:44:09 volumio volumio[1036]: info: CoreStateMachine::pushState Jun 13 12:44:09 volumio volumio[1036]: info: CorePlayQueue::getTrack 0 Jun 13 12:44:09 volumio volumio[1036]: info: CoreCommandRouter::volumioPushState Jun 13 12:44:21 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 13 12:44:21 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 12:44:22 volumio volumio-remote-updater[594]: Test mode disabled Jun 13 12:44:22 volumio volumio-remote-updater[594]: Alpha mode disabled Jun 13 12:44:22 volumio volumio-remote-updater[594]: Alpha legacy test mode disabled Jun 13 12:44:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 13 12:44:22 volumio volumio[1036]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 13 12:44:22 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 13 12:44:24 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 13 12:44:24 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 13 12:44:24 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 13 12:44:24 volumio volumio-remote-updater[594]: Test mode disabled Jun 13 12:44:24 volumio volumio-remote-updater[594]: Alpha mode disabled Jun 13 12:44:24 volumio volumio-remote-updater[594]: Alpha legacy test mode disabled Jun 13 12:44:24 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 13 12:44:24 volumio volumio[1036]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 13 12:44:24 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 13 12:44:26 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Jun 13 12:44:26 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Jun 13 12:44:26 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 13 12:44:26 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:26 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:26 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 13 12:44:26 volumio volumio[1036]: info: Saving new wireless network Jun 13 12:44:26 volumio sudo[2481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 13 12:44:26 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:26 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:26 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 13 12:44:26 volumio volumio[1036]: info: Discovery: Restarting Advertising due to device name change Jun 13 12:44:26 volumio volumio[1036]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 13 12:44:26 volumio sudo[2484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 13 12:44:26 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:26 volumio systemd[1]: Starting wireless.service - Wireless Services... Jun 13 12:44:27 volumio systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 13 12:44:27 volumio dnsmasq[1990]: exiting on receipt of SIGTERM Jun 13 12:44:27 volumio systemd[1]: dnsmasq.service: Deactivated successfully. Jun 13 12:44:27 volumio systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 13 12:44:27 volumio systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 13 12:44:27 volumio volumio[1036]: info: Discovery: A device disappeared from network Jun 13 12:44:27 volumio volumio[1036]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Jun 13 12:44:27 volumio volumio[1036]: info: Discovery: A device disappeared from network Jun 13 12:44:27 volumio volumio[1036]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Jun 13 12:44:27 volumio volumio[1036]: info: Discovery: A device disappeared from network Jun 13 12:44:27 volumio volumio[1036]: info: Discovery: Browse raised the following error TypeError: Cannot read properties of undefined (reading 'toLowerCase') Jun 13 12:44:28 volumio dhcpcd[666]: wlan0: carrier lost Jun 13 12:44:28 volumio avahi-daemon[562]: Withdrawing address record for 169.254.88.245 on wlan0. Jun 13 12:44:28 volumio dhcpcd[666]: wlan0: deleting route to 169.254.0.0/16 Jun 13 12:44:28 volumio systemd[1]: hostapd.service: Deactivated successfully. Jun 13 12:44:28 volumio systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 13 12:44:28 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 13 12:44:28 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 13 12:44:28 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 13 12:44:28 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 13 12:44:28 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 13 12:44:28 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 13 12:44:28 volumio welcome[2542]: Resolved ip:[2] 192.168.1.33 192.168.211.1 Jun 13 12:44:28 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 13 12:44:28 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 13 12:44:28 volumio sudo[2567]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 13 12:44:28 volumio sudo[2567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:28 volumio avahi-daemon[562]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 13 12:44:28 volumio avahi-daemon[562]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 13 12:44:28 volumio avahi-daemon[562]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 13 12:44:28 volumio sudo[2567]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:28 volumio sudo[2570]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 13 12:44:28 volumio sudo[2570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:28 volumio systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 13 12:44:28 volumio systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 13 12:44:28 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 13 12:44:28 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 13 12:44:28 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 13 12:44:28 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 13 12:44:28 volumio sudo[2570]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:28 volumio welcome[2572]: Resolved ip:[1] 192.168.1.33 Jun 13 12:44:28 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 13 12:44:28 volumio systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 13 12:44:29 volumio sudo[2583]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 12:44:29 volumio sudo[2583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:29 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 13 12:44:29 volumio sudo[2583]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:29 volumio sudo[2585]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 13 12:44:29 volumio sudo[2585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:29 volumio sudo[2585]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:29 volumio sudo[2593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 12:44:29 volumio sudo[2593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:29 volumio sudo[2593]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:29 volumio sudo[2595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 13 12:44:29 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:30 volumio ntpd[863]: IO: Deleting interface #6 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=96 secs Jun 13 12:44:30 volumio ntpd[863]: IO: Deleting interface #7 wlan0, 169.254.88.245#123, interface stats: received=0, sent=0, dropped=0, active_time=84 secs Jun 13 12:44:31 volumio volumio[1036]: info: Discovery: Started advertising with name: Volumio Jun 13 12:44:31 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:31 volumio sudo[2602]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 13 12:44:31 volumio sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:31 volumio sudo[2602]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:31 volumio sudo[2604]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set TH Jun 13 12:44:31 volumio sudo[2604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:31 volumio sudo[2604]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:32 volumio sudo[2612]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 13 12:44:32 volumio sudo[2612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:32 volumio sudo[2612]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:32 volumio sudo[2614]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 13 12:44:32 volumio sudo[2614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:32 volumio volumio[1036]: compat.c: read() failed: Resource temporarily unavailable Jun 13 12:44:32 volumio volumio[1036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 12:44:32 volumio volumio[1036]: Error: dns service error: unknown Jun 13 12:44:32 volumio volumio[1036]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 13 12:44:32 volumio volumio[1036]: errorCode: -65537 Jun 13 12:44:32 volumio volumio[1036]: } Jun 13 12:44:32 volumio volumio[1036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 13 12:44:32 volumio sudo[2614]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:32 volumio wpa_supplicant[2623]: Successfully initialized wpa_supplicant Jun 13 12:44:32 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 13 12:44:32 volumio sudo[2632]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 13 12:44:32 volumio sudo[2632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:32 volumio dhcpcd[666]: control command: /sbin/dhcpcd Jun 13 12:44:32 volumio sudo[2632]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:32 volumio dhcpcd[666]: control_free: No such file or directory Jun 13 12:44:32 volumio dhcpcd[666]: lo: IAID 00:00:00:00 Jun 13 12:44:32 volumio dhcpcd[666]: lo: adding address fe80::880a:9a18:fdd7:420a Jun 13 12:44:32 volumio dhcpcd[666]: ipv6_addaddr1: Permission denied Jun 13 12:44:32 volumio dhcpcd[666]: lo: using static address 127.0.0.1/8 Jun 13 12:44:32 volumio dhcpcd[666]: lo: adding route to 127.0.0.0/8 Jun 13 12:44:32 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 13 12:44:32 volumio systemd[1]: Reached target ip-changed@lo.target - IP Address changed on lo. Jun 13 12:44:32 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Jun 13 12:44:32 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Jun 13 12:44:32 volumio systemd[1]: welcome.service: Deactivated successfully. Jun 13 12:44:32 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 13 12:44:32 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 13 12:44:32 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 13 12:44:32 volumio dbus-daemon[583]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.35' (uid=0 pid=2661 comm="timedatectl show --property=NTPSynchronized --valu") Jun 13 12:44:32 volumio welcome[2674]: Resolved ip:[1] 192.168.1.33 Jun 13 12:44:32 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 13 12:44:32 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 13 12:44:32 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Jun 13 12:44:33 volumio wpa_supplicant[2623]: nl80211: kernel reports: Registration to specific type not supported Jun 13 12:44:33 volumio dbus-daemon[583]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 13 12:44:33 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 13 12:44:33 volumio setdatetime-helper.sh[2649]: Time is not synchronized. Attempting to sync... Jun 13 12:44:33 volumio sudo[2717]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Fri, 13 Jun 2025 05:44:34 GMT#015' Jun 13 12:44:33 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 13 12:44:34 volumio setdatetime-helper.sh[2718]: Fri Jun 13 12:44:34 +07 2025 Jun 13 12:44:34 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:34 volumio setdatetime-helper.sh[2649]: Time synchronized successfully. Jun 13 12:44:34 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 13 12:44:34 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 13 12:44:34 volumio sudo[2721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 12:44:34 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:34 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:35 volumio sudo[2725]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 12:44:35 volumio sudo[2725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:35 volumio sudo[2725]: pam_unix(sudo:session): session closed for user root Jun 13 12:44:36 volumio sudo[2729]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-13 12:43' Jun 13 12:44:36 volumio sudo[2729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:36 volumio wpa_supplicant[2704]: wlan0: Trying to associate with f8:2e:3f:14:80:c8 (SSID='pikoonplaninsod2.4' freq=2447 MHz) Jun 13 12:44:36 volumio sudo[2733]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 13 12:44:36 volumio sudo[2733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 13 12:44:36 volumio sudo[2733]: pam_unix(sudo:session): session closed for user root 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="ecd46641d85b721d58d1c7edd71dd0a358c8514c" 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 Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"