Jun 05 00:52:00 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 00:52:00 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:00 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 00:52:00 volumio volumio-remote-updater[907]: No test mode Jun 05 00:52:00 volumio volumio-remote-updater[907]: No alpha test mode Jun 05 00:52:00 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 05 00:52:01 volumio volumio[1237]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v0.069","updateavailable":true} Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::volumioGetBrowseSources Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 00:52:01 volumio volumio[1237]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 05 00:52:01 volumio volumio[1237]: info: Completed starting MyVolumio Plugin Jun 05 00:52:01 volumio volumio[1237]: [Metrics] CommandRouter: 33s 26.92ms Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::volumiosetStartupVolume Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::Close All Modals sent Jun 05 00:52:01 volumio volumio[1237]: info: CoreCommandRouter::Close All Modals sent Jun 05 00:52:01 volumio volumio[1237]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 05 00:52:01 volumio volumio[1237]: aplay: main:831: audio open error: Unknown error 524 Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 05 00:52:02 volumio volumio[1237]: info: Version has changed, forcing UI Reload Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::Reload Ui Jun 05 00:52:02 volumio sudo[2231]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 00:52:02 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:02 volumio sudo[2233]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 00:52:02 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:02 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:02 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:02 volumio volumio[1237]: verbose: New Socket.io Connection to 10.10.20.50 from 10.10.20.76 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 05 00:52:02 volumio volumio[1237]: info: Received Get System Info Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:52:02 volumio volumio[1237]: info: Discovery: Getting this device information Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:02 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:52:02 volumio volumio-remote-updater[907]: No test mode Jun 05 00:52:02 volumio volumio-remote-updater[907]: No alpha test mode Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:02 volumio volumio[1237]: info: Retrieving Cloud Streaming UI Jun 05 00:52:02 volumio volumio[1237]: info: Getting Tidal Cloud Configuration Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:02 volumio volumio[1237]: info: Getting Qobuz Cloud Configuration Jun 05 00:52:02 volumio volumio[1237]: info: Asking plugin for UI Config Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:02 volumio volumio[1237]: info: Getting Spotify Cloud Configuration Jun 05 00:52:02 volumio volumio[1237]: info: Asking plugin for UI Config Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:02 volumio volumio[1237]: info: Saving Spotify Acccount Jun 05 00:52:02 volumio volumio[1237]: info: Got Tidal Cloud Configuration Jun 05 00:52:02 volumio volumio[1237]: info: Got it Jun 05 00:52:02 volumio volumio[1237]: info: Got it Jun 05 00:52:02 volumio volumio[1237]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 05 00:52:02 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:02 volumio volumio[1237]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 05 00:52:03 volumio volumio[1237]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v0.069","updateavailable":true} Jun 05 00:52:03 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 00:52:03 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 05 00:52:03 volumio volumio[1237]: info: Loading i18n strings for locale en Jun 05 00:52:03 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 05 00:52:03 volumio volumio[1237]: info: Setting timezone to Asia/Bangkok Jun 05 00:52:03 volumio sudo[2243]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 05 00:52:03 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:03 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:03 volumio sudo[2247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Jun 05 00:52:03 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:03 volumio sudo[2247]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:03 volumio sudo[2251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 05 00:52:03 volumio sudo[2251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:03 volumio sudo[2251]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:03 volumio sudo[2255]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Jun 05 00:52:03 volumio sudo[2255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:03 volumio dbus-daemon[898]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.28' (uid=0 pid=2256 comm="/usr/bin/timedatectl set-timezone Asia/Bangkok") Jun 05 00:52:03 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 05 00:52:04 volumio dbus-daemon[898]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 05 00:52:04 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 05 00:52:04 volumio sudo[2255]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:04 volumio volumio[1237]: Updating browse sources language Jun 05 00:52:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 00:52:04 volumio volumio[1237]: info: Fetching Streaming Services browse cache Jun 05 00:52:04 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:05 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 05 00:52:05 volumio volumio[1237]: info: MyVolumio login type: Token Jun 05 00:52:05 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:05 volumio volumio[1237]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 05 00:52:06 volumio sudo[2259]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 00:52:06 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:06 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:06 volumio sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 00:52:06 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:06 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:06 volumio volumio[1237]: verbose: New Socket.io Connection to 10.10.20.50 from 10.10.20.76 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Jun 05 00:52:06 volumio volumio[1237]: info: Received Get System Info Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:52:06 volumio volumio[1237]: info: Discovery: Getting this device information Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:06 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:52:06 volumio volumio-remote-updater[907]: No test mode Jun 05 00:52:06 volumio volumio-remote-updater[907]: No alpha test mode Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:06 volumio volumio[1237]: info: Retrieving Cloud Streaming UI Jun 05 00:52:06 volumio volumio[1237]: info: Getting Tidal Cloud Configuration Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:06 volumio volumio[1237]: info: Getting Qobuz Cloud Configuration Jun 05 00:52:06 volumio volumio[1237]: info: Asking plugin for UI Config Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:06 volumio volumio[1237]: info: Getting Spotify Cloud Configuration Jun 05 00:52:06 volumio volumio[1237]: info: Asking plugin for UI Config Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:06 volumio volumio[1237]: info: Saving Spotify Acccount Jun 05 00:52:06 volumio volumio[1237]: info: Got Tidal Cloud Configuration Jun 05 00:52:06 volumio volumio[1237]: info: Got it Jun 05 00:52:06 volumio volumio[1237]: info: Got it Jun 05 00:52:06 volumio volumio[1237]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:06 volumio volumio[1237]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 05 00:52:06 volumio volumio[1237]: info: MyVolumio token set successfully Jun 05 00:52:06 volumio volumio[1237]: info: MYVOLUMIO: Adding device Jun 05 00:52:06 volumio volumio[1237]: info: MYVOLUMIO: Evaluating Server Jun 05 00:52:06 volumio volumio[1237]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v0.069","updateavailable":true} Jun 05 00:52:06 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 00:52:06 volumio volumio[1237]: info: MyVolumio status changed Jun 05 00:52:06 volumio volumio[1237]: info: Streaming services startup Jun 05 00:52:06 volumio volumio[1237]: info: Starting Streaming Daemon Jun 05 00:52:06 volumio volumio[1237]: info: Removing browser output: myVolumio user plan is not superstar Jun 05 00:52:06 volumio volumio[1237]: info: Removing audio output: Jun 05 00:52:06 volumio volumio[1237]: info: Stoppping Tunnel 1 Jun 05 00:52:06 volumio sudo[2287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 05 00:52:06 volumio sudo[2287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:06 volumio sudo[2287]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:06 volumio volumio[1237]: error: Cannot start Volumio Streaming Daemon Jun 05 00:52:06 volumio volumio[1237]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 05 00:52:06 volumio volumio[1237]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 05 00:52:06 volumio sudo[2290]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 05 00:52:06 volumio sudo[2290]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:06 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 05 00:52:06 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 05 00:52:06 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 05 00:52:06 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 05 00:52:06 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 05 00:52:06 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 05 00:52:06 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 05 00:52:06 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 05 00:52:06 volumio sudo[2290]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:06 volumio volumio[1237]: info: Remote SSH Stopped Jun 05 00:52:07 volumio volumio[1237]: info: Setting Geolocation for MyVolumio to as1 Jun 05 00:52:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 05 00:52:07 volumio volumio[1237]: info: Loading i18n strings for locale en Jun 05 00:52:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 05 00:52:07 volumio volumio[1237]: info: Setting timezone to Asia/Bangkok Jun 05 00:52:07 volumio sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 05 00:52:07 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:07 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:07 volumio sudo[2298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Jun 05 00:52:07 volumio sudo[2298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:07 volumio sudo[2298]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:07 volumio sudo[2302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 05 00:52:07 volumio sudo[2302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:07 volumio sudo[2302]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:07 volumio sudo[2308]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Jun 05 00:52:07 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:07 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:07 volumio volumio[1237]: Updating browse sources language Jun 05 00:52:07 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 00:52:07 volumio volumio[1237]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 05 00:52:07 volumio volumio[1237]: info: Fetching Streaming Services browse cache Jun 05 00:52:08 volumio volumio[1237]: info: Updating MyVolumio device info Jun 05 00:52:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:08 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 05 00:52:08 volumio volumio[1237]: info: BOOT COMPLETED Jun 05 00:52:09 volumio volumio[1237]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 05 00:52:11 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:11 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:11 volumio volumio[1237]: info: MYVOLUMIO: Adding device Jun 05 00:52:11 volumio volumio[1237]: info: MYVOLUMIO: Evaluating Server Jun 05 00:52:12 volumio volumio[1237]: info: Setting Geolocation for MyVolumio to as1 Jun 05 00:52:12 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:12 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:12 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:12 volumio volumio[1237]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 05 00:52:13 volumio volumio[1237]: info: Updating MyVolumio device info Jun 05 00:52:13 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:13 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:13 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:13 volumio volumio[1237]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 05 00:52:20 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 05 00:52:20 volumio volumio[1237]: info: Loading i18n strings for locale en Jun 05 00:52:20 volumio volumio[1237]: Updating browse sources language Jun 05 00:52:20 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 00:52:20 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Jun 05 00:52:20 volumio volumio[1237]: info: Setting timezone to Asia/Bangkok Jun 05 00:52:20 volumio sudo[2355]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Jun 05 00:52:20 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:20 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:20 volumio sudo[2359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Bangkok /etc/localtime Jun 05 00:52:20 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:20 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:20 volumio sudo[2363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Jun 05 00:52:20 volumio sudo[2363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:20 volumio sudo[2363]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:20 volumio sudo[2369]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Bangkok Jun 05 00:52:20 volumio sudo[2369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:20 volumio sudo[2369]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:20 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 05 00:52:20 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 05 00:52:20 volumio sudo[2372]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 00:52:20 volumio sudo[2372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:21 volumio volumio[1237]: info: Fetching Streaming Services browse cache Jun 05 00:52:24 volumio sudo[2372]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 00:52:25 volumio volumio[1237]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 05 00:52:25 volumio volumio[1237]: info: Received Get System Version Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 00:52:25 volumio volumio[1237]: info: Received Get System Info Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:52:25 volumio volumio[1237]: info: Discovery: Getting this device information Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:25 volumio volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:25 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:52:28 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 05 00:52:28 volumio setdatetime-helper.sh[2374]: Time is not synchronized. Attempting to sync... Jun 05 00:52:28 volumio sudo[2382]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Wed, 04 Jun 2025 17:52:28 GMT#015' Jun 05 00:52:28 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 05 00:52:28 volumio setdatetime-helper.sh[2383]: Thu Jun 5 00:52:28 +07 2025 Jun 05 00:52:28 volumio systemd-journald[450]: Time jumped backwards, rotating. Jun 05 00:52:28 volumio sudo[2382]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:28 volumio setdatetime-helper.sh[2374]: Time synchronized successfully. Jun 05 00:52:28 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 05 00:52:28 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jun 05 00:52:31 volumio volumio[1237]: info: System name has changed, restarting Shairport Sync Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:31 volumio volumio[1237]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 00:52:31 volumio volumio[1237]: info: Updating MyVolumio device info Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:31 volumio volumio[1237]: info: Discovery: Restarting Advertising due to device name change Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jun 05 00:52:31 volumio sudo[2404]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 05 00:52:31 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:31 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:31 volumio volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 05 00:52:31 volumio volumio[1237]: info: Starting Shairport Sync Jun 05 00:52:31 volumio systemd[1]: upmpdcli.service: Deactivated successfully. Jun 05 00:52:31 volumio sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 00:52:31 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:31 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 05 00:52:31 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jun 05 00:52:31 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 05 00:52:31 volumio systemd[1]: shairport-sync.service: Consumed 1.504s CPU time. Jun 05 00:52:31 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 05 00:52:31 volumio volumio[1237]: info: Shairport-Sync Started Jun 05 00:52:31 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:31 volumio sudo[2429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jun 05 00:52:31 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:31 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:31 volumio volumio[1237]: info: Permissions for /etc/hosts set Jun 05 00:52:31 volumio volumio[1237]: info: Hostname now is volumio-pi5-0.068 Jun 05 00:52:31 volumio systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... Jun 05 00:52:31 volumio systemd[1]: fix-alpha-hosts.service: Deactivated successfully. Jun 05 00:52:31 volumio systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. Jun 05 00:52:31 volumio volumio[1237]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 05 00:52:32 volumio volumio[1237]: info: Discovery: A device disappeared from network Jun 05 00:52:32 volumio volumio[1237]: info: Discovery: Device volumio disappeared from network Jun 05 00:52:32 volumio volumio[1237]: info: Discovery: A device disappeared from network Jun 05 00:52:32 volumio volumio[1237]: info: Discovery: A device disappeared from network Jun 05 00:52:33 volumio sudo[2432]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname volumio-pi5-0.068 Jun 05 00:52:33 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:33 volumio-pi5-0.068 sudo[2435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Jun 05 00:52:33 volumio-pi5-0.068 sudo[2435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:33 volumio-pi5-0.068 sudo[2432]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:33 volumio-pi5-0.068 volumio[1237]: info: New hostname set Jun 05 00:52:33 volumio-pi5-0.068 sudo[2435]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:33 volumio-pi5-0.068 volumio[1237]: info: Permissions for /etc/avahi/services/volumio.service Jun 05 00:52:33 volumio-pi5-0.068 avahi-daemon[896]: Files changed, reloading. Jun 05 00:52:33 volumio-pi5-0.068 avahi-daemon[896]: Loading service file /services/volumio.service. Jun 05 00:52:33 volumio-pi5-0.068 volumio[1237]: info: Avahi name changed to volumio-pi5-0.068 Jun 05 00:52:33 volumio-pi5-0.068 avahi-daemon[896]: Service "Volumio-Pi5-0.068" (/services/volumio.service) successfully established. Jun 05 00:52:33 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 05 00:52:33 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 00:52:34 volumio-pi5-0.068 volumio-remote-updater[907]: No test mode Jun 05 00:52:34 volumio-pi5-0.068 volumio-remote-updater[907]: Alpha test mode enabled Jun 05 00:52:34 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 05 00:52:34 volumio-pi5-0.068 volumio[1237]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

BREAKING CHANGES

\n\n

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v4.008","updateavailable":true} Jun 05 00:52:34 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 00:52:36 volumio-pi5-0.068 volumio[1237]: info: Discovery: Started advertising with name: Volumio-Pi5-0.068 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Discovery: adding f13f8370-8692-4bff-a313-be4855d55fa6 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Discovery: Found device Volumio-Pi5-0.068 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Discovery: this is already registered, f13f8370-8692-4bff-a313-be4855d55fa6 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Discovery: Found device Volumio-Pi5-0.068 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Discovery: this is already registered, f13f8370-8692-4bff-a313-be4855d55fa6 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Discovery: Found device Volumio-Pi5-0.068 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: verbose: New Socket.io Connection to 10.10.20.50:3000 from 10.10.20.49 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:37 volumio-pi5-0.068 volumio-remote-updater[907]: No test mode Jun 05 00:52:37 volumio-pi5-0.068 volumio-remote-updater[907]: Alpha test mode enabled Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

BREAKING CHANGES

\n\n

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v4.008","updateavailable":true} Jun 05 00:52:37 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 00:52:41 volumio-pi5-0.068 sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/samba/smb.conf Jun 05 00:52:41 volumio-pi5-0.068 sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 sudo[2465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /data/INTERNAL Jun 05 00:52:41 volumio-pi5-0.068 sudo[2465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 sudo[2469]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 00:52:41 volumio-pi5-0.068 sudo[2469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 sudo[2469]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 sudo[2471]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 00:52:41 volumio-pi5-0.068 sudo[2471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 sudo[2471]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 sudo[2474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 05 00:52:41 volumio-pi5-0.068 sudo[2474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 05 00:52:41 volumio-pi5-0.068 sudo[2474]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 volumio[1237]: info: Upmpdcli Daemon Started Jun 05 00:52:41 volumio-pi5-0.068 upmpdcli[2493]: writing RSA key Jun 05 00:52:41 volumio-pi5-0.068 sudo[2465]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 volumio[1237]: info: Internal perms successfully set Jun 05 00:52:41 volumio-pi5-0.068 sudo[2463]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 volumio[1237]: info: smb.conf Permissions set Jun 05 00:52:41 volumio-pi5-0.068 sudo[2498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart nmbd.service Jun 05 00:52:41 volumio-pi5-0.068 sudo[2498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Jun 05 00:52:41 volumio-pi5-0.068 nmbd[1109]: [2025/06/05 00:52:41.417928, 0] ../../source3/nmbd/nmbd.c:59(terminate) Jun 05 00:52:41 volumio-pi5-0.068 nmbd[1109]: Got SIGTERM: going down... Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: nmbd.service: Deactivated successfully. Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Starting nmbd.service - Samba NMB Daemon... Jun 05 00:52:41 volumio-pi5-0.068 nmbd[2504]: [2025/06/05 00:52:41.537391, 0] ../../source3/nmbd/nmbd.c:901(main) Jun 05 00:52:41 volumio-pi5-0.068 nmbd[2504]: nmbd version 4.17.8-Raspbian started. Jun 05 00:52:41 volumio-pi5-0.068 nmbd[2504]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 05 00:52:41 volumio-pi5-0.068 nmbd[2504]: [2025/06/05 00:52:41.538650, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 05 00:52:41 volumio-pi5-0.068 nmbd[2504]: started asyncdns process 2505 Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Started nmbd.service - Samba NMB Daemon. Jun 05 00:52:41 volumio-pi5-0.068 sudo[2498]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 sudo[2507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart smbd.service Jun 05 00:52:41 volumio-pi5-0.068 sudo[2507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Stopping smbd.service - Samba SMB Daemon... Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: smbd.service: Deactivated successfully. Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Stopped smbd.service - Samba SMB Daemon. Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Starting smbd.service - Samba SMB Daemon... Jun 05 00:52:41 volumio-pi5-0.068 smbd[2512]: [2025/06/05 00:52:41.668559, 0] ../../source3/smbd/server.c:1741(main) Jun 05 00:52:41 volumio-pi5-0.068 smbd[2512]: smbd version 4.17.8-Raspbian started. Jun 05 00:52:41 volumio-pi5-0.068 smbd[2512]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Jun 05 00:52:41 volumio-pi5-0.068 systemd[1]: Started smbd.service - Samba SMB Daemon. Jun 05 00:52:41 volumio-pi5-0.068 sudo[2507]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:41 volumio-pi5-0.068 volumio[1237]: info: SAMBA Restarted Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Preparing to save Alsa Options, stopping services first Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioPause Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::pause Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Saving Audio Output to: {"i2s":true,"i2sid":{"value":"audiophonics-es9028q2m-dac","label":"Audiophonics I-Sabre ES9028Q2M"},"output_device":{"value":1,"label":"Audiophonics I-Sabre ES9028Q2M"},"disallowPush":true} Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Enabling I2S DAC: Audiophonics I-Sabre ES9028Q2M Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 05 00:52:45 volumio-pi5-0.068 sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2518]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: No Overlays Loaded Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 sudo[2521]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay i-sabre-q2m Jun 05 00:52:45 volumio-pi5-0.068 sudo[2521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: warn: Unable to locate the device Audiophonics I-Sabre ES9028Q2M a reboot may be required. Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/i2s-controller Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a0000/status Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#address-cells Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#size-cells Jun 05 00:52:45 volumio-pi5-0.068 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/status Jun 05 00:52:45 volumio-pi5-0.068 sudo[2521]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Found match in i2s Card Database: setting mixer Digital for card Audiophonics I-Sabre ES9028Q2M Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Disabling external Volume Control Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Preparing to generate the ALSA configuration file Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 05 00:52:45 volumio-pi5-0.068 sudo[2550]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 00:52:45 volumio-pi5-0.068 sudo[2550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jun 05 00:52:45 volumio-pi5-0.068 sudo[2553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 00:52:45 volumio-pi5-0.068 sudo[2553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: I2S Param [object Object] successfully enabled Jun 05 00:52:45 volumio-pi5-0.068 sudo[2553]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Getting Alsa Cards List without I2S DAC Jun 05 00:52:45 volumio-pi5-0.068 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000001] -121 Jun 05 00:52:45 volumio-pi5-0.068 kernel: snd-rpi-i-sabre-q2m soc@107c000000:sound: Audiophonics Device ID : FFFFFF87 Jun 05 00:52:45 volumio-pi5-0.068 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000002] -121 Jun 05 00:52:45 volumio-pi5-0.068 kernel: snd-rpi-i-sabre-q2m soc@107c000000:sound: Audiophonics API revision : FFFFFF87 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 (udev-worker)[2536]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: VolumeController:: Volume=undefined Mute =false Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::pushState Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioPushState Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: wlan0 Interface doesn't support scanning : Device or resource busy Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Cannot use regular scanning, forcing with ap-force Jun 05 00:52:45 volumio-pi5-0.068 sudo[2582]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 05 00:52:45 volumio-pi5-0.068 sudo[2582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2582]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: command failed: Device or resource busy (-16) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: command failed: Device or resource busy (-16) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Asound.conf file written Jun 05 00:52:45 volumio-pi5-0.068 sudo[2586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 05 00:52:45 volumio-pi5-0.068 sudo[2586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2586]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: No state is present for card vc4hdmi0 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: Found hardware: "vc4-hdmi" "" "" "" "" Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: Hardware is initialized using a generic method Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: No state is present for card vc4hdmi0 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: No state is present for card vc4hdmi1 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: Found hardware: "vc4-hdmi" "" "" "" "" Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: Hardware is initialized using a generic method Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: No state is present for card vc4hdmi1 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: No state is present for card DAC Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: Found hardware: "I-Sabre_Q2M_DAC" "" "" "" "" Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: Hardware is initialized using a generic method Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: No state is present for card DAC Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Output device has changed, restarting MPD Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Output device has changed, restarting Shairport Sync Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 sudo[2592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 00:52:45 volumio-pi5-0.068 sudo[2592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2592]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 sudo[2594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 00:52:45 volumio-pi5-0.068 sudo[2594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Output device has changed, restarting MPD Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Output device has changed, restarting Shairport Sync Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 sudo[2602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 05 00:52:45 volumio-pi5-0.068 sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2602]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopping mpd.service - Music Player Daemon... Jun 05 00:52:45 volumio-pi5-0.068 sudo[2604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 05 00:52:45 volumio-pi5-0.068 sudo[2604]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: MPD Permissions set Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: MPD Permissions set Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Starting Shairport Sync Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Starting Shairport Sync Jun 05 00:52:45 volumio-pi5-0.068 sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 00:52:45 volumio-pi5-0.068 sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 05 00:52:45 volumio-pi5-0.068 sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: shairport-sync.service: Consumed 1.538s CPU time. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 05 00:52:45 volumio-pi5-0.068 sudo[2622]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Shairport-Sync Started Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: mpd.service: Deactivated successfully. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: mpd.service: Consumed 1.220s CPU time. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: mpd.socket: Deactivated successfully. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 05 00:52:45 volumio-pi5-0.068 sudo[2625]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 05 00:52:45 volumio-pi5-0.068 volumio[1237]: info: Shairport-Sync Started Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 05 00:52:45 volumio-pi5-0.068 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 05 00:52:45 volumio-pi5-0.068 sudo[2630]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 05 00:52:45 volumio-pi5-0.068 sudo[2630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 05 00:52:45 volumio-pi5-0.068 sudo[2630]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:46 volumio-pi5-0.068 mpd[2646]: 2025-06-05T00:52:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 05 00:52:46 volumio-pi5-0.068 systemd[1]: Started mpd.service - Music Player Daemon. Jun 05 00:52:46 volumio-pi5-0.068 sudo[2594]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:46 volumio-pi5-0.068 sudo[2604]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: error: MPD error: The expression evaluated to a falsy value: Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: assert.ok(self.idling) Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: error: The expression evaluated to a falsy value: Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: assert.ok(self.idling) Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: error: updateQueue error: null Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Retrieving Cloud Streaming UI Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Getting Tidal Cloud Configuration Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Getting Qobuz Cloud Configuration Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Asking plugin for UI Config Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Getting Spotify Cloud Configuration Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Asking plugin for UI Config Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Saving Spotify Acccount Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Got Tidal Cloud Configuration Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Got it Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: Got it Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:46 volumio-pi5-0.068 volumio[1237]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jun 05 00:52:47 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Jun 05 00:52:47 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jun 05 00:52:47 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Jun 05 00:52:47 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Jun 05 00:52:47 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jun 05 00:52:49 volumio-pi5-0.068 sudo[2550]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::Reload Ui Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: Wizard terminated Successfully Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumiosetStartupVolume Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: Player Reboot required after I2S DAC has been enabled in wizard Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::getcurrentVolume Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: VolumeController:: Volume=100 Mute =false Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::pushState Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioPushState Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::updateTrackBlock Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrackBlock Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioRetrievevolume Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: VolumeController:: Volume=100 Mute =false Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::pushState Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioPushState Jun 05 00:52:49 volumio-pi5-0.068 sudo[2676]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 00:52:49 volumio-pi5-0.068 sudo[2676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:49 volumio-pi5-0.068 sudo[2678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 00:52:49 volumio-pi5-0.068 sudo[2678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:49 volumio-pi5-0.068 sudo[2676]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:49 volumio-pi5-0.068 sudo[2678]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: verbose: New Socket.io Connection to 10.10.20.50 from 10.10.20.76 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 05 00:52:49 volumio-pi5-0.068 sudo[2682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 00:52:49 volumio-pi5-0.068 sudo[2682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:49 volumio-pi5-0.068 sudo[2684]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 00:52:49 volumio-pi5-0.068 sudo[2684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:49 volumio-pi5-0.068 sudo[2682]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:49 volumio-pi5-0.068 sudo[2684]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: verbose: New Socket.io Connection to 10.10.20.50 from 10.10.20.76 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Edg/137.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 05 00:52:49 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetVisibleSources Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetQueue Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreStateMachine::getQueue Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getQueue Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: Listing playlists Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: Received Get System Info Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: Discovery: Getting this device information Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 05 00:52:50 volumio-pi5-0.068 volumio-remote-updater[907]: No test mode Jun 05 00:52:50 volumio-pi5-0.068 volumio-remote-updater[907]: Alpha test mode enabled Jun 05 00:52:50 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

BREAKING CHANGES

\n\n

NEW ADDITIONS

\n\n

FIXES

\n\n","title":"Update v4.008","updateavailable":true} Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: Received Get System Info Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: Discovery: Getting this device information Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:51 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: Received Get System Info Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: Discovery: Getting this device information Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:52 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:52:53 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Jun 05 00:52:56 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:52:56 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:52:57 volumio-pi5-0.068 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 05 00:52:58 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 05 00:52:58 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 05 00:52:58 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 05 00:52:58 volumio-pi5-0.068 sudo[2695]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 05 00:52:58 volumio-pi5-0.068 sudo[2695]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:58 volumio-pi5-0.068 sudo[2700]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 05 00:52:58 volumio-pi5-0.068 sudo[2700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:58 volumio-pi5-0.068 sudo[2706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 05 00:52:58 volumio-pi5-0.068 sudo[2706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:58 volumio-pi5-0.068 sudo[2700]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:58 volumio-pi5-0.068 sudo[2706]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:58 volumio-pi5-0.068 sudo[2695]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:58 volumio-pi5-0.068 sudo[2712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 05 00:52:58 volumio-pi5-0.068 sudo[2712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:58 volumio-pi5-0.068 sudo[2712]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:58 volumio-pi5-0.068 sudo[2718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 05 00:52:58 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 05 00:52:58 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 05 00:52:58 volumio-pi5-0.068 sudo[2718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:58 volumio-pi5-0.068 sudo[2718]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:58 volumio-pi5-0.068 sudo[2723]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 05 00:52:58 volumio-pi5-0.068 sudo[2723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:58 volumio-pi5-0.068 sudo[2723]: pam_unix(sudo:session): session closed for user root Jun 05 00:52:58 volumio-pi5-0.068 sudo[2726]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 05 00:52:58 volumio-pi5-0.068 sudo[2726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:52:59 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 05 00:53:01 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::Close All Modals sent Jun 05 00:53:01 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Jun 05 00:53:01 volumio-pi5-0.068 sudo[2726]: pam_unix(sudo:session): session closed for user root Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Jun 05 00:53:04 volumio-pi5-0.068 sudo[2747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jun 05 00:53:04 volumio-pi5-0.068 sudo[2747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:53:04 volumio-pi5-0.068 sudo[2747]: pam_unix(sudo:session): session closed for user root Jun 05 00:53:04 volumio-pi5-0.068 sudo[2749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jun 05 00:53:04 volumio-pi5-0.068 sudo[2749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:53:04 volumio-pi5-0.068 sudo[2749]: pam_unix(sudo:session): session closed for user root Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: Permissions for /etc/network/interfaces set Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: Restarting Wireless Network Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: Discovery: Restarting Advertising due to device name change Jun 05 00:53:04 volumio-pi5-0.068 sudo[2752]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 05 00:53:04 volumio-pi5-0.068 sudo[2752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Starting wireless.service - Wireless Services... Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Jun 05 00:53:04 volumio-pi5-0.068 dnsmasq[1981]: exiting on receipt of SIGTERM Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: dnsmasq.service: Deactivated successfully. Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopping hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Jun 05 00:53:04 volumio-pi5-0.068 dhcpcd[960]: wlan0: carrier lost Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: hostapd.service: Deactivated successfully. Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Jun 05 00:53:04 volumio-pi5-0.068 sudo[2785]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jun 05 00:53:04 volumio-pi5-0.068 sudo[2785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 05 00:53:04 volumio-pi5-0.068 avahi-daemon[896]: Withdrawing address record for 169.254.52.136 on wlan0. Jun 05 00:53:04 volumio-pi5-0.068 dhcpcd[960]: wlan0: deleting route to 169.254.0.0/16 Jun 05 00:53:04 volumio-pi5-0.068 avahi-daemon[896]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 05 00:53:04 volumio-pi5-0.068 avahi-daemon[896]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 05 00:53:04 volumio-pi5-0.068 avahi-daemon[896]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 05 00:53:04 volumio-pi5-0.068 sudo[2785]: pam_unix(sudo:session): session closed for user root Jun 05 00:53:04 volumio-pi5-0.068 volumio[1237]: info: Discovery: A device disappeared from network Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: welcome.service: Deactivated successfully. Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 05 00:53:04 volumio-pi5-0.068 sudo[2795]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jun 05 00:53:04 volumio-pi5-0.068 sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 05 00:53:04 volumio-pi5-0.068 systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 05 00:53:05 volumio-pi5-0.068 volumio[1237]: info: Discovery: A device disappeared from network Jun 05 00:53:05 volumio-pi5-0.068 volumio[1237]: info: Discovery: A device disappeared from network Jun 05 00:53:05 volumio-pi5-0.068 welcome[2801]: Resolved ip:[1] 10.10.20.50 Jun 05 00:53:05 volumio-pi5-0.068 sudo[2795]: pam_unix(sudo:session): session closed for user root Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: welcome.service: Deactivated successfully. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 05 00:53:05 volumio-pi5-0.068 welcome[2833]: Resolved ip:[1] 10.10.20.50 Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: wireless.service: Deactivated successfully. Jun 05 00:53:05 volumio-pi5-0.068 systemd[1]: Started wireless.service - Wireless Services. Jun 05 00:53:05 volumio-pi5-0.068 sudo[2752]: pam_unix(sudo:session): session closed for user root Jun 05 00:53:06 volumio-pi5-0.068 ntpd[1040]: IO: Deleting interface #5 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=89 secs Jun 05 00:53:06 volumio-pi5-0.068 ntpd[1040]: IO: Deleting interface #6 wlan0, 169.254.52.136#123, interface stats: received=0, sent=0, dropped=0, active_time=77 secs Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: Received Get System Version Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: Received Get System Info Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: Discovery: Getting this device information Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::volumioGetState Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CorePlayQueue::getTrack 0 Jun 05 00:53:08 volumio-pi5-0.068 volumio[1237]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: info: Discovery: Started advertising with name: Volumio-Pi5-0.068 Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: compat.c: read() failed: Resource temporarily unavailable Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: Error: dns service error: unknown Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: errorCode: -65537 Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: } Jun 05 00:53:09 volumio-pi5-0.068 volumio[1237]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 05 00:53:10 volumio-pi5-0.068 sudo[2870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-05 00:52' Jun 05 00:53:10 volumio-pi5-0.068 sudo[2870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="26d819930af572ef184e636a18ef1127de78f391" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"