Dec 14 12:36:00 volumio volumio[1088]: info: Starting Shairport Sync Dec 14 12:36:00 volumio volumio[1088]: info: Starting Shairport Sync Dec 14 12:36:00 volumio volumio[1088]: info: Starting Shairport Sync Dec 14 12:36:00 volumio sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 12:36:00 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:00 volumio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 12:36:00 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:00 volumio sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 12:36:00 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 12:36:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 12:36:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:36:00 volumio systemd[1]: shairport-sync.service: Consumed 2.135s CPU time. Dec 14 12:36:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:36:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 12:36:00 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 12:36:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:36:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:36:00 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:00 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 14 12:36:00 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 14 12:36:00 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:36:00 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 14 12:36:00 volumio volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Dec 14 12:36:00 volumio volumio[1088]: assert.ok(self.idling) Dec 14 12:36:00 volumio volumio[1088]: error: The expression evaluated to a falsy value: Dec 14 12:36:00 volumio volumio[1088]: assert.ok(self.idling) Dec 14 12:36:00 volumio volumio[1088]: error: MPD error: The expression evaluated to a falsy value: Dec 14 12:36:00 volumio volumio[1088]: assert.ok(self.idling) Dec 14 12:36:00 volumio volumio[1088]: error: The expression evaluated to a falsy value: Dec 14 12:36:00 volumio volumio[1088]: assert.ok(self.idling) Dec 14 12:36:00 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:01 volumio volumio[1088]: info: Shairport-Sync Started Dec 14 12:36:01 volumio volumio[1088]: Error adding Membership: Error: addMembership EINVAL Dec 14 12:36:01 volumio volumio[1088]: info: Shairport-Sync Started Dec 14 12:36:01 volumio volumio[1088]: info: Shairport-Sync Started Dec 14 12:36:01 volumio volumio[1088]: error: updateQueue error: null Dec 14 12:36:01 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:01 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:02 volumio sudo[1558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 12:36:02 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:02 volumio sudo[1561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 12:36:02 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:02 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 12:36:02 volumio mpd_monitor.sh[1565]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 12:36:02 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:02 volumio volumio[1088]: info: Successfully started MPD Monitor Dec 14 12:36:02 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:02 volumio sudo[1564]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:36:02 volumio sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:02 volumio sudo[1564]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:02 volumio sudo[1566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 14 12:36:02 volumio sudo[1566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:02 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 14 12:36:02 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 14 12:36:02 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 14 12:36:02 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.2.150 from 192.168.2.197 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 14 12:36:03 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 14 12:36:03 volumio mpd_monitor.sh[1573]: MPD Monitor Service: Starting MPD Monitor Service Dec 14 12:36:03 volumio sudo[1566]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:03 volumio volumio[1088]: info: Successfully started MPD Monitor Dec 14 12:36:03 volumio sudo[1578]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 12:36:03 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:03 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:03 volumio sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:36:03 volumio sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:03 volumio sudo[1580]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:03 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.2.150 from 192.168.2.197 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:04 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 12:36:04 volumio volumio[1088]: info: Received Get System Info Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:36:04 volumio volumio[1088]: info: Discovery: Getting this device information Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:04 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:04 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:04 volumio volumio[1088]: info: Listing playlists Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 12:36:04 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:36:05 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:05 volumio volumio[1088]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 14 12:36:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 12:36:05 volumio volumio[1088]: info: Received Get System Info Dec 14 12:36:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:36:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:36:05 volumio volumio[1088]: info: Discovery: Getting this device information Dec 14 12:36:05 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:05 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:05 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:36:06 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 14 12:36:08 volumio volumio[1088]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 14 12:36:08 volumio volumio[1088]: info: Adding plugin bluetooth to MyMusic Plugins Dec 14 12:36:08 volumio volumio[1088]: info: Adding plugin multiroom to MyMusic Plugins Dec 14 12:36:09 volumio volumio[1088]: info: Adding plugin metavolumio to MyMusic Plugins Dec 14 12:36:09 volumio volumio[1088]: info: Adding plugin cd_controller to MyMusic Plugins Dec 14 12:36:09 volumio volumio[1088]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 14 12:36:09 volumio volumio[1088]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 14 12:36:09 volumio volumio[1088]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 14 12:36:09 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 14 12:36:10 volumio upmpdcli[1603]: writing RSA key Dec 14 12:36:16 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 14 12:36:16 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 14 12:36:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:36:16 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:36:16 volumio volumio[1088]: info: Starting MyVolumio Remote Streaming Endpoints Dec 14 12:36:17 volumio volumio[1088]: info: MyVolumio not started Dec 14 12:36:17 volumio volumio[1088]: info: Initializing device activation check Dec 14 12:36:17 volumio volumio[1088]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 14 12:36:17 volumio volumio[1088]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 14 12:36:23 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 14 12:36:23 volumio volumio[1088]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 14 12:36:23 volumio volumio[1088]: info: Streaming services startup Dec 14 12:36:23 volumio volumio[1088]: info: Starting Streaming Daemon Dec 14 12:36:23 volumio sudo[1625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 14 12:36:23 volumio volumio[1088]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 14 12:36:23 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:23 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:23 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 12:36:23 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:36:23 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:24 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:36:24 volumio volumio[1088]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 14 12:36:24 volumio volumio[1088]: info: Received Get System Version Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 12:36:24 volumio volumio[1088]: info: Received Get System Info Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:36:24 volumio volumio[1088]: info: Discovery: Getting this device information Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:24 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:36:24 volumio volumio[1088]: error: Cannot start Volumio Streaming Daemon Dec 14 12:36:24 volumio volumio[1088]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 14 12:36:24 volumio volumio[1088]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 14 12:36:24 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 12:36:26 volumio sudo[1654]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 12:36:26 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:26 volumio sudo[1656]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:36:26 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:26 volumio sudo[1656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:26 volumio sudo[1656]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:26 volumio volumio-remote-updater[675]: Test mode disabled Dec 14 12:36:26 volumio volumio-remote-updater[675]: Alpha mode disabled Dec 14 12:36:26 volumio volumio-remote-updater[675]: Alpha legacy test mode disabled Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 14 12:36:26 volumio volumio[1088]: verbose: New Socket.io Connection to 192.168.2.150 from 192.168.2.197 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::volumioGetVisibleSources Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:26 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 14 12:36:26 volumio volumio[1088]: info: Received Get System Info Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:36:26 volumio volumio[1088]: info: Discovery: Getting this device information Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:26 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:26 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:26 volumio volumio[1088]: info: Listing playlists Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 14 12:36:26 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 14 12:36:27 volumio volumio[1088]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:27 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 14 12:36:27 volumio volumio[1088]: info: Received Get System Info Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:36:27 volumio volumio[1088]: info: Discovery: Getting this device information Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:27 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:27 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:36:28 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 14 12:36:30 volumio volumio[1088]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 12:36:30 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 14 12:36:30 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 14 12:36:30 volumio sudo[1665]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 14 12:36:30 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:30 volumio sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 12:36:30 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:30 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:30 volumio sudo[1672]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 12:36:30 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 14 12:36:30 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 14 12:36:30 volumio sudo[1672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:30 volumio sudo[1680]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 14 12:36:30 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:30 volumio sudo[1672]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:30 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:30 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:30 volumio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 12:36:30 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:30 volumio sudo[1689]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:36:30 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 14 12:36:31 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:31 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:36:31 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:31 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:31 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Dec 14 12:36:31 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 14 12:36:31 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:36:32 volumio volumio[1088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 14 12:36:32 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:32 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 14 12:36:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:36:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:36:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 14 12:36:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 14 12:36:36 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 14 12:36:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 14 12:36:37 volumio volumio[1088]: info: CoreCommandRouter::volumioGetBrowseSources Dec 14 12:36:37 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 12:36:38 volumio kernel: hwmon hwmon1: Voltage normalised Dec 14 12:36:43 volumio volumio[1088]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 14 12:36:43 volumio volumio[1088]: info: Completed starting MyVolumio Plugin Dec 14 12:36:43 volumio volumio[1088]: [Metrics] CommandRouter: 76s 340.57ms Dec 14 12:36:43 volumio volumio[1088]: info: CoreCommandRouter::volumiosetStartupVolume Dec 14 12:36:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 12:36:43 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 12:36:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 14 12:36:43 volumio volumio[1088]: info: CoreCommandRouter::Close All Modals sent Dec 14 12:36:43 volumio volumio[1088]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 14 12:36:43 volumio volumio[1088]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for DAC Dec 14 12:36:43 volumio volumio[1088]: aplay: main:831: audio open error: No such device Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 14 12:36:44 volumio volumio[1088]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 14 12:36:44 volumio volumio[1088]: info: Received Get System Version Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 12:36:44 volumio volumio[1088]: info: Received Get System Info Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 14 12:36:44 volumio volumio[1088]: info: Discovery: Getting this device information Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::volumioGetState Dec 14 12:36:44 volumio volumio[1088]: info: CorePlayQueue::getTrack 0 Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 12:36:44 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 14 12:36:50 volumio volumio[1088]: info: BOOT COMPLETED Dec 14 12:37:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Dec 14 12:37:11 volumio volumio[1088]: info: Saving new wireless network Dec 14 12:37:11 volumio sudo[1766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Dec 14 12:37:11 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:11 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:11 volumio volumio[1088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 14 12:37:11 volumio volumio[1088]: info: Discovery: Restarting Advertising due to device name change Dec 14 12:37:12 volumio sudo[1769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 14 12:37:12 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:12 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 14 12:37:12 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 14 12:37:12 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 14 12:37:12 volumio systemd[1]: wireless.service: Consumed 3min 52.374s CPU time. Dec 14 12:37:12 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 14 12:37:13 volumio volumio[1088]: info: Discovery: A device disappeared from network Dec 14 12:37:13 volumio volumio[1088]: info: Discovery: Device volumio disappeared from network Dec 14 12:37:13 volumio volumio[1088]: info: Discovery: A device disappeared from network Dec 14 12:37:13 volumio sudo[1784]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:37:13 volumio sudo[1784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:13 volumio sudo[1784]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:13 volumio wireless.js[1773]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 14 12:37:13 volumio wireless.js[1773]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 14 12:37:13 volumio wireless.js[1773]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 14 12:37:13 volumio wireless.js[1773]: WIRELESS.JS: Cleaning previous... Dec 14 12:37:14 volumio sudo[1794]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:37:14 volumio sudo[1796]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 14 12:37:14 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 12:37:14 volumio sudo[1794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:14 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:14 volumio sudo[1794]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:14 volumio sudo[1799]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 14 12:37:14 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 14 12:37:14 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:15 volumio wireless.js[1773]: WIRELESS.JS: Stopped aP Dec 14 12:37:15 volumio sudo[1809]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 14 12:37:15 volumio sudo[1809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:15 volumio sudo[1806]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:37:15 volumio sudo[1806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:15 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 14 12:37:15 volumio sudo[1809]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:15 volumio sudo[1806]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:15 volumio sudo[1812]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Dec 14 12:37:15 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:15 volumio sudo[1812]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:15 volumio sudo[1820]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 14 12:37:15 volumio sudo[1820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:15 volumio sudo[1820]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:15 volumio sudo[1822]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Dec 14 12:37:15 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:15 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:16 volumio wireless.js[1773]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US Dec 14 12:37:16 volumio sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Dec 14 12:37:16 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:16 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:16 volumio sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Dec 14 12:37:16 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:16 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:16 volumio sudo[1827]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:37:16 volumio sudo[1827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:16 volumio wireless.js[1773]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US Dec 14 12:37:16 volumio sudo[1827]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:16 volumio wireless.js[1773]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Dec 14 12:37:16 volumio wireless.js[1773]: WIRELESS.JS: Notified systemd about wireless ready Dec 14 12:37:16 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 14 12:37:16 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 14 12:37:16 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:16 volumio volumio[1088]: info: Discovery: Started advertising with name: Volumio Dec 14 12:37:17 volumio sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 12:37:17 volumio sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 14 12:37:17 volumio sudo[1856]: pam_unix(sudo:session): session closed for user root Dec 14 12:37:17 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 14 12:37:18 volumio volumio[1088]: compat.c: read() failed: Resource temporarily unavailable Dec 14 12:37:18 volumio volumio[1088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 12:37:18 volumio volumio[1088]: Error: dns service error: unknown Dec 14 12:37:18 volumio volumio[1088]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 14 12:37:18 volumio volumio[1088]: errorCode: -65537 Dec 14 12:37:18 volumio volumio[1088]: } Dec 14 12:37:18 volumio volumio[1088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 12:37:21 volumio kernel: hwmon hwmon1: Voltage normalised Dec 14 12:37:24 volumio sudo[1878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-14 12:36' Dec 14 12:37:24 volumio sudo[1878]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"