Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::initPlayerControls Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:30:00 volumio volumio[1203]: Express server listening on port 3000 Dec 26 22:30:00 volumio volumio[1203]: [Metrics] WebUI: 15s 28.64ms Dec 26 22:30:00 volumio volumio[1203]: info: CoreStateMachine::resetVolumioState Dec 26 22:30:00 volumio volumio[1203]: info: CoreStateMachine::getcurrentVolume Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 22:30:00 volumio volumio[1203]: info: CoreStateMachine::pushState Dec 26 22:30:00 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 22:30:00 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 26 22:30:00 volumio volumio[1203]: info: Cannot read play queue from file Dec 26 22:30:00 volumio volumio[1203]: info: Volumio Network Manager: Network status updated: 1 Dec 26 22:30:00 volumio volumio[1272]: Forking 3 albumart workers Dec 26 22:30:01 volumio volumio[1203]: verbose: New Socket.io Connection to 192.168.1.160 from 192.168.1.183 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 26 22:30:01 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:01 volumio volumio[1203]: info: CoreStateMachine::setRepeat null single undefined Dec 26 22:30:01 volumio volumio[1203]: info: CoreStateMachine::pushState Dec 26 22:30:01 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:01 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 26 22:30:01 volumio volumio[1203]: info: CoreStateMachine::setRandom null Dec 26 22:30:01 volumio volumio[1203]: info: CoreStateMachine::pushState Dec 26 22:30:01 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:01 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Dec 26 22:30:01 volumio volumio[1203]: info: Setting Device type: Raspberry PI Dec 26 22:30:01 volumio sudo[1337]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 22:30:01 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:01 volumio sudo[1340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 22:30:01 volumio sudo[1340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:01 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:01 volumio sudo[1340]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:02 volumio volumio[1203]: info: Completed loading Core Plugins Dec 26 22:30:02 volumio volumio[1203]: info: Preparing to generate the ALSA configuration file Dec 26 22:30:02 volumio volumio[1203]: verbose: New Socket.io Connection to 192.168.1.160 from 192.168.1.183 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 26 22:30:03 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 26 22:30:03 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 22:30:03 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 22:30:03 volumio upmpdcli[1345]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.160 port 49152 IPV6 port 49152 Dec 26 22:30:03 volumio upmpdcli[1345]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Cannot assign requested address Dec 26 22:30:03 volumio upmpdcli[1345]: :2:../src/main.cxx:728::MPD connection failed Dec 26 22:30:03 volumio volumio[1203]: verbose: New Socket.io Connection to 192.168.1.160 from 192.168.1.183 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 26 22:30:03 volumio volumio[1203]: info: Asound.conf file unchanged, so no further update is needed Dec 26 22:30:03 volumio volumio[1203]: info: Output device has changed, restarting MPD Dec 26 22:30:03 volumio volumio[1203]: info: Output device has changed, restarting Shairport Sync Dec 26 22:30:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:03 volumio sudo[1357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 22:30:03 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:03 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:03 volumio sudo[1359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 22:30:03 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:03 volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 22:30:03 volumio volumio[1203]: info: ___________ START PLUGINS ___________ Dec 26 22:30:03 volumio volumio[1203]: info: ControllerMpd::onStart: Initializing MPD Dec 26 22:30:03 volumio volumio[1203]: info: Creating MPD Configuration file Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 22:30:04 volumio volumio[1203]: info: [1766788204098] CoreMusicLibrary::Adding element Media Servers Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 22:30:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 22:30:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 22:30:04 volumio sudo[1370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 22:30:04 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:04 volumio volumio[1203]: info: UPNP Browser: Client initialized successfully Dec 26 22:30:04 volumio sudo[1367]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 22:30:04 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:04 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:04 volumio sudo[1371]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 22:30:04 volumio sudo[1371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 22:30:04 volumio sudo[1374]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 22:30:04 volumio sudo[1371]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 22:30:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 22:30:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 22:30:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 22:30:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 22:30:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 22:30:04 volumio sudo[1376]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 22:30:04 volumio sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 22:30:04 volumio sudo[1381]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 22:30:04 volumio sudo[1376]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:04 volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:04 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:05 volumio volumio[1203]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 22:30:05 volumio volumio[1203]: info: [1766788205089] CoreMusicLibrary::Adding element Last_100 Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 22:30:05 volumio volumio[1203]: info: [1766788205164] CoreMusicLibrary::Adding element Webradio Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 22:30:05 volumio volumio[1203]: info: Initializing BBC Radios Dec 26 22:30:05 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 22:30:05 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:06 volumio volumio[1203]: info: Creating Spotify config file Dec 26 22:30:06 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:07 volumio upmpdcli[1345]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 22:30:07 volumio upmpdcli[1345]: :2:../src/main.cxx:728::MPD connection failed Dec 26 22:30:09 volumio volumio[1203]: info: Volumio Calling Home Dec 26 22:30:10 volumio volumio-remote-updater[675]: [2025-12-26 22:30:10] [connect] Successful connection Dec 26 22:30:12 volumio volumio[1285]: Starting albumart workers Dec 26 22:30:12 volumio volumio[1288]: Starting albumart workers Dec 26 22:30:12 volumio mpd[1382]: 2025-12-26T22:30:12 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 22:30:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 22:30:12 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:12 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:12 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 26 22:30:12 volumio volumio[1289]: Starting albumart workers Dec 26 22:30:13 volumio volumio[1203]: info: Discovery: adding 0df5477c-a6dd-42cb-a77d-c7bacdefe56d Dec 26 22:30:13 volumio volumio[1203]: info: Discovery: Found device Volumio Dec 26 22:30:13 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:13 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:13 volumio volumio[1203]: info: Discovery: this is already registered, 0df5477c-a6dd-42cb-a77d-c7bacdefe56d Dec 26 22:30:13 volumio volumio[1203]: info: Discovery: Found device Volumio Dec 26 22:30:13 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:13 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:14 volumio volumio[1203]: info: Completed starting Core Plugins Dec 26 22:30:14 volumio volumio[1203]: info: ------------------------------------------- Dec 26 22:30:14 volumio volumio[1203]: info: ----- MyVolumio plugins startup ---- Dec 26 22:30:14 volumio volumio[1203]: info: ------------------------------------------- Dec 26 22:30:14 volumio volumio[1203]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 26 22:30:14 volumio volumio[1203]: info: MPD Permissions set Dec 26 22:30:14 volumio volumio[1203]: info: MPD Permissions set Dec 26 22:30:15 volumio sudo[1445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 22:30:15 volumio sudo[1445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:15 volumio sudo[1445]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:15 volumio volumio[1203]: info: Volumio called home Dec 26 22:30:15 volumio volumio[1203]: info: Spotify config file written Dec 26 22:30:15 volumio sudo[1451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 22:30:15 volumio sudo[1451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:16 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:16 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:16 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 22:30:16 volumio go-librespot[1453]: go-librespot daemon starting... Dec 26 22:30:16 volumio sudo[1451]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:16 volumio go-librespot[1454]: time="2025-12-26T22:30:16Z" level=info msg="running go-librespot 0.4.0" Dec 26 22:30:16 volumio go-librespot[1454]: time="2025-12-26T22:30:16Z" level=debug msg="app state loaded" Dec 26 22:30:16 volumio go-librespot[1454]: time="2025-12-26T22:30:16Z" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 22:30:17 volumio volumio[1203]: error: MPD error: The expression evaluated to a falsy value: Dec 26 22:30:17 volumio volumio[1203]: assert.ok(self.idling) Dec 26 22:30:17 volumio volumio[1203]: error: The expression evaluated to a falsy value: Dec 26 22:30:17 volumio volumio[1203]: assert.ok(self.idling) Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 22:30:17 volumio volumio[1203]: info: Upmpdcli Daemon Started Dec 26 22:30:17 volumio volumio[1203]: info: MPD running with PID1382 Dec 26 22:30:17 volumio volumio[1203]: ,establishing connection Dec 26 22:30:17 volumio go-librespot[1454]: time="2025-12-26T22:30:17Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Dec 26 22:30:17 volumio go-librespot[1454]: time="2025-12-26T22:30:17Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 26 22:30:17 volumio go-librespot[1454]: time="2025-12-26T22:30:17Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 26 22:30:17 volumio go-librespot[1454]: time="2025-12-26T22:30:17Z" level=info msg="zeroconf server listening on port 38251" Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:17 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio go-librespot[1454]: time="2025-12-26T22:30:18Z" level=debug msg="obtained new client token: AAA2/mS0Rr2Bgzp7ix5In7GVC/0B4tvG0mdBTA2E3VsIcPFtSHBW7Rl6eOLydKwx/dIMuvlAW+EQAZNhldGnfUECZVSKRfuaVSVgvNnbKv1OIEVz6zw6URbgFJQKNOjOivoihJXz7J/x1r1ex36sn8+sj7R/vFJGlywmsnGxFuep+3VaKahpCOh93fpPyp5n1sPdbbb7hY9Gj2qIFbctcphv2yPPwG9EHKv8jABw2+NEz+a5NWgcxI0=" Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:18 volumio volumio[1203]: info: No need to fix Spotify hosts Dec 26 22:30:18 volumio volumio[1203]: error: updateQueue error: null Dec 26 22:30:18 volumio go-librespot[1454]: time="2025-12-26T22:30:18Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:18 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:18 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 22:30:18 volumio go-librespot[1454]: time="2025-12-26T22:30:18Z" level=debug msg="completed keyexchange" Dec 26 22:30:18 volumio go-librespot[1454]: time="2025-12-26T22:30:18Z" level=debug msg="completed challenge" Dec 26 22:30:18 volumio volumio[1203]: info: Received Get System Info Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:30:18 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:18 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:30:18 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:18 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:18 volumio volumio[1203]: info: Listing playlists Dec 26 22:30:19 volumio go-librespot[1454]: time="2025-12-26T22:30:19Z" level=info msg="authenticated AP" username="si*****oy" Dec 26 22:30:19 volumio volumio[1203]: info: CoreCommandRouter::volumioGetQueue Dec 26 22:30:19 volumio volumio[1203]: info: CoreStateMachine::getQueue Dec 26 22:30:19 volumio volumio[1203]: info: CorePlayQueue::getQueue Dec 26 22:30:20 volumio sudo[1492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 22:30:20 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:20 volumio volumio[1203]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 22:30:20 volumio volumio[1203]: SPOTIFY: BQDsSbuDoHhWKvLHu1nfvO-gWErw0v8k7t0_N90XbMNQAMnyMReB5CbufQ_VifIagFAUQOiTTM068zak4lyfl0rd9t7ufEO04mcyfw90MVyKb3NLDoPsoZBQcvIEyLyU-kplpdqlA847cQS6NWNF_FtKr795Xdsm4A-pP5Nahd4JjOZY2F84KscbagboUmwt_xWhPgDo9HAsKnjQJPwzM13hoADjVT6Ajf_BB3b00eUYe-X_YRbGOWdhlE_mjRfst0rp57dqvsaRMatUFJ7wk37xa3XgGQ2Vh8I Dec 26 22:30:20 volumio volumio[1203]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Dec 26 22:30:20 volumio volumio[1203]: info: New Spotify access token = BQDsSbuDoHhWKvLHu1nfvO-gWErw0v8k7t0_N90XbMNQAMnyMReB5CbufQ_VifIagFAUQOiTTM068zak4lyfl0rd9t7ufEO04mcyfw90MVyKb3NLDoPsoZBQcvIEyLyU-kplpdqlA847cQS6NWNF_FtKr795Xdsm4A-pP5Nahd4JjOZY2F84KscbagboUmwt_xWhPgDo9HAsKnjQJPwzM13hoADjVT6Ajf_BB3b00eUYe-X_YRbGOWdhlE_mjRfst0rp57dqvsaRMatUFJ7wk37xa3XgGQ2Vh8I Dec 26 22:30:20 volumio volumio[1203]: info: Spotify credentials grant success - running version from March 24, 2019 Dec 26 22:30:20 volumio go-librespot[1454]: time="2025-12-26T22:30:20Z" level=info msg="authenticated Login5" username="si*****oy" Dec 26 22:30:20 volumio go-librespot[1454]: time="2025-12-26T22:30:20Z" level=debug msg="initializing zeroconf session" username="si*****oy" Dec 26 22:30:20 volumio sudo[1493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 26 22:30:20 volumio sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:20 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 22:30:20 volumio mpd_monitor.sh[1496]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 22:30:20 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:20 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 26 22:30:20 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 26 22:30:20 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 26 22:30:20 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 26 22:30:20 volumio sudo[1493]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:20 volumio mpd_monitor.sh[1500]: MPD Monitor Service: Starting MPD Monitor Service Dec 26 22:30:21 volumio volumio[1203]: error: updateQueue error: null Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="dealer connection opened" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=trace msg="starting accesspoint recv loop" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=trace msg="starting dealer recv loop" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=trace msg="received accesspoint ping" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="received connection id: YTA5NDc1Y2EtMTY5...OEM5MkZGNDhEQg==" Dec 26 22:30:21 volumio volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 22:30:21 volumio volumio[1203]: info: Successfully started MPD Monitor Dec 26 22:30:21 volumio volumio[1203]: info: Successfully started MPD Monitor Dec 26 22:30:21 volumio volumio[1203]: info: Starting Shairport Sync Dec 26 22:30:21 volumio volumio[1203]: info: Starting Shairport Sync Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=trace msg="received accesspoint pong ack" Dec 26 22:30:21 volumio volumio[1203]: info: Starting Shairport Sync Dec 26 22:30:21 volumio sudo[1509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 22:30:21 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:21 volumio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 22:30:21 volumio volumio[1203]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 26 22:30:21 volumio sudo[1513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 22:30:21 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:21 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 22:30:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 22:30:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 22:30:21 volumio systemd[1]: shairport-sync.service: Consumed 2.148s CPU time. Dec 26 22:30:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 22:30:21 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="put connect state because NEW_DEVICE" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="update volume requested to 62914/65535" Dec 26 22:30:21 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 22:30:21 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 22:30:21 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:21 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 22:30:21 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:21 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=debug msg="put connect state because VOLUME_CHANGED" Dec 26 22:30:21 volumio go-librespot[1454]: time="2025-12-26T22:30:21Z" level=trace msg="emitting websocket event: volume" Dec 26 22:30:22 volumio volumio[1203]: info: Shairport-Sync Started Dec 26 22:30:22 volumio volumio[1203]: Error adding Membership: Error: addMembership EINVAL Dec 26 22:30:22 volumio volumio[1203]: info: Shairport-Sync Started Dec 26 22:30:22 volumio volumio[1203]: info: Shairport-Sync Started Dec 26 22:30:22 volumio volumio[1203]: info: go-librespot daemon successfully initialized Dec 26 22:30:23 volumio sudo[1553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 22:30:23 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:23 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:23 volumio sudo[1555]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 22:30:23 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:23 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:23 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:23 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:23 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 22:30:23 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 22:30:23 volumio systemd[1]: setdatetime-helper.service: Consumed 1.398s CPU time. Dec 26 22:30:24 volumio volumio[1203]: verbose: New Socket.io Connection to 192.168.1.160 from 192.168.1.183 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 26 22:30:24 volumio volumio[1203]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 26 22:30:24 volumio volumio[1203]: info: Adding plugin bluetooth to MyMusic Plugins Dec 26 22:30:24 volumio volumio[1203]: info: Adding plugin multiroom to MyMusic Plugins Dec 26 22:30:24 volumio volumio[1203]: info: Adding plugin metavolumio to MyMusic Plugins Dec 26 22:30:24 volumio volumio[1203]: info: Adding plugin cd_controller to MyMusic Plugins Dec 26 22:30:25 volumio volumio-remote-updater[675]: [2025-12-26 22:30:25] [connect] Successful connection Dec 26 22:30:25 volumio volumio[1203]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 26 22:30:25 volumio volumio[1203]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 26 22:30:25 volumio volumio[1203]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 26 22:30:25 volumio volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 26 22:30:32 volumio upmpdcli[1576]: writing RSA key Dec 26 22:30:34 volumio volumio[1203]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 26 22:30:34 volumio volumio[1203]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 26 22:30:34 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:34 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:34 volumio volumio[1203]: info: Starting MyVolumio Remote Streaming Endpoints Dec 26 22:30:34 volumio volumio[1203]: info: MyVolumio login type: Token Dec 26 22:30:34 volumio volumio[1203]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 26 22:30:34 volumio volumio[1203]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 26 22:30:40 volumio volumio-remote-updater[675]: [2025-12-26 22:30:40] [connect] Successful connection Dec 26 22:30:42 volumio volumio[1203]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 26 22:30:42 volumio volumio[1203]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 26 22:30:42 volumio volumio[1203]: info: Streaming services startup Dec 26 22:30:42 volumio volumio[1203]: info: Starting Streaming Daemon Dec 26 22:30:42 volumio sudo[1584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 22:30:42 volumio volumio[1203]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 26 22:30:42 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:42 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:42 volumio volumio[1203]: info: Initializing connection to go-librespot Websocket Dec 26 22:30:42 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 22:30:42 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:42 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 22:30:43 volumio volumio[1203]: error: Cannot start Volumio Streaming Daemon Dec 26 22:30:43 volumio volumio[1203]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 22:30:43 volumio volumio[1203]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 22:30:43 volumio go-librespot[1454]: time="2025-12-26T22:30:43Z" level=debug msg="new websocket client" Dec 26 22:30:43 volumio volumio[1203]: info: Connection to go-librespot Websocket established Dec 26 22:30:43 volumio volumio[1203]: SPOTIFY: User informations: {"country":"GB","display_name":"KC","email":"keith555@live.ie","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/sinnerboy"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/sinnerboy","id":"sinnerboy","images":[],"product":"premium","type":"user","uri":"spotify:user:sinnerboy"} Dec 26 22:30:43 volumio volumio[1203]: info: Spotify Successfully logged in Dec 26 22:30:43 volumio volumio[1203]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 22:30:43 volumio volumio[1203]: info: [1766788243524] CoreMusicLibrary::Adding element Spotify Dec 26 22:30:43 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 22:30:43 volumio volumio[1203]: Cannot find translation for source Spotify Dec 26 22:30:43 volumio volumio-remote-updater[675]: [2025-12-26 22:30:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766788240 101 Dec 26 22:30:44 volumio volumio[1203]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 6 Dec 26 22:30:44 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 22:30:44 volumio sudo[1608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 22:30:44 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:44 volumio sudo[1610]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 22:30:44 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:44 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:44 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:44 volumio volumio[1203]: verbose: New Socket.io Connection to 192.168.1.160 from 192.168.1.183 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:45 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 22:30:45 volumio volumio[1203]: info: Received Get System Info Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:30:45 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:45 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:45 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:45 volumio volumio[1203]: info: Listing playlists Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 26 22:30:45 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 22:30:46 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:46 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:46 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 26 22:30:46 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 26 22:30:46 volumio volumio-remote-updater[675]: Test mode disabled Dec 26 22:30:46 volumio volumio-remote-updater[675]: Alpha mode disabled Dec 26 22:30:46 volumio volumio-remote-updater[675]: Alpha legacy test mode disabled Dec 26 22:30:46 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 26 22:30:46 volumio volumio[1203]: info: Getting Spotify volume Dec 26 22:30:46 volumio volumio[1203]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 26 22:30:47 volumio volumio[1203]: info: Spotify volume: 96 Dec 26 22:30:47 volumio volumio[1203]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 22:30:47 volumio volumio[1203]: info: Received Get System Info Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:30:47 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:47 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 22:30:47 volumio volumio[1203]: info: Received Get System Info Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:30:47 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:47 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:47 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:30:47 volumio volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 26 22:30:48 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:48 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:48 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 26 22:30:49 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 22:30:49 volumio volumio[1203]: info: MyVolumio token set successfully Dec 26 22:30:49 volumio volumio[1203]: info: MYVOLUMIO: Adding device Dec 26 22:30:49 volumio volumio[1203]: info: MYVOLUMIO: Evaluating Server Dec 26 22:30:49 volumio volumio[1203]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 26 22:30:50 volumio volumio[1203]: info: MyVolumio status changed Dec 26 22:30:50 volumio volumio[1203]: info: Streaming services startup Dec 26 22:30:50 volumio volumio[1203]: info: Starting Streaming Daemon Dec 26 22:30:50 volumio volumio[1203]: info: Removing browser output: myVolumio user plan is not superstar Dec 26 22:30:50 volumio volumio[1203]: info: Removing audio output: Dec 26 22:30:50 volumio volumio[1203]: info: Stoppping Tunnel 1 Dec 26 22:30:50 volumio sudo[1635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 22:30:50 volumio sudo[1635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:50 volumio sudo[1637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 26 22:30:50 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:51 volumio sudo[1635]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:51 volumio go-librespot[1454]: time="2025-12-26T22:30:51Z" level=trace msg="sent dealer ping" Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 26 22:30:51 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:51 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:30:51 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:30:51 volumio volumio[1203]: error: Cannot start Volumio Streaming Daemon Dec 26 22:30:51 volumio volumio[1203]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 22:30:51 volumio volumio[1203]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 22:30:51 volumio volumio[1203]: info: Setting Geolocation for MyVolumio to eu10 Dec 26 22:30:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:51 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:51 volumio go-librespot[1454]: time="2025-12-26T22:30:51Z" level=trace msg="received dealer pong" Dec 26 22:30:51 volumio volumio[1203]: info: Remote SSH Stopped Dec 26 22:30:52 volumio volumio[1203]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 22:30:52 volumio volumio[1203]: info: Updating MyVolumio device info Dec 26 22:30:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:52 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:52 volumio volumio[1203]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::volumioGetBrowseSources Dec 26 22:30:54 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 22:30:55 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 26 22:30:56 volumio volumio[1203]: info: MYVOLUMIO: Adding device Dec 26 22:30:56 volumio volumio[1203]: info: MYVOLUMIO: Evaluating Server Dec 26 22:30:57 volumio volumio[1203]: info: Setting Geolocation for MyVolumio to eu2 Dec 26 22:30:57 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:57 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:57 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:57 volumio volumio[1203]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 26 22:30:57 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 26 22:30:58 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 26 22:30:58 volumio sudo[1677]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 26 22:30:58 volumio sudo[1677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio sudo[1682]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 22:30:58 volumio sudo[1682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio sudo[1677]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:58 volumio sudo[1682]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:58 volumio sudo[1689]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 22:30:58 volumio volumio[1203]: info: Updating MyVolumio device info Dec 26 22:30:58 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:58 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:58 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:30:58 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio sudo[1693]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 26 22:30:58 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio sudo[1698]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 22:30:58 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:58 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 26 22:30:58 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 26 22:30:58 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:58 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:58 volumio sudo[1702]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 22:30:58 volumio sudo[1702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio sudo[1702]: pam_unix(sudo:session): session closed for user root Dec 26 22:30:58 volumio volumio[1203]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 26 22:30:58 volumio sudo[1709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 22:30:58 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:30:58 volumio volumio[1203]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 26 22:31:01 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root Dec 26 22:31:02 volumio volumio[1203]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 26 22:31:02 volumio volumio[1203]: info: Completed starting MyVolumio Plugin Dec 26 22:31:02 volumio volumio[1203]: [Metrics] CommandRouter: 75s 592.17ms Dec 26 22:31:02 volumio volumio[1203]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 22:31:02 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 22:31:02 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 22:31:02 volumio volumio[1203]: info: CoreCommandRouter::Close All Modals sent Dec 26 22:31:02 volumio volumio[1203]: info: CoreCommandRouter::Close All Modals sent Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 26 22:31:03 volumio volumio[1203]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 26 22:31:03 volumio volumio[1203]: info: Received Get System Version Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 22:31:03 volumio volumio[1203]: info: Received Get System Info Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:31:03 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:31:03 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 22:31:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 22:31:09 volumio volumio[1203]: info: BOOT COMPLETED Dec 26 22:31:14 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 22:31:14 volumio volumio[1203]: info: Received Get System Info Dec 26 22:31:14 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:31:14 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:31:14 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:31:14 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:31:14 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:31:14 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:31:15 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 22:31:15 volumio volumio[1203]: info: Received Get System Info Dec 26 22:31:15 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 22:31:15 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 22:31:15 volumio volumio[1203]: info: Discovery: Getting this device information Dec 26 22:31:15 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Dec 26 22:31:15 volumio volumio[1203]: info: CorePlayQueue::getTrack 0 Dec 26 22:31:15 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 22:31:19 volumio volumio[1203]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Dec 26 22:31:19 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Dec 26 22:31:20 volumio sudo[1746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Dec 26 22:31:20 volumio sudo[1746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:31:20 volumio sudo[1746]: pam_unix(sudo:session): session closed for user root Dec 26 22:31:20 volumio sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Dec 26 22:31:20 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:31:20 volumio sudo[1748]: pam_unix(sudo:session): session closed for user root Dec 26 22:31:20 volumio volumio[1203]: info: Permissions for /etc/network/interfaces set Dec 26 22:31:20 volumio volumio[1203]: info: Restarting Wireless Network Dec 26 22:31:20 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Dec 26 22:31:20 volumio volumio[1203]: info: Discovery: Restarting Advertising due to device name change Dec 26 22:31:20 volumio sudo[1751]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Dec 26 22:31:20 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 22:31:20 volumio systemd[1]: Stopping wireless.service - Wireless Services... Dec 26 22:31:20 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 26 22:31:20 volumio systemd[1]: Stopped wireless.service - Wireless Services. Dec 26 22:31:20 volumio systemd[1]: wireless.service: Consumed 3min 13.752s CPU time. Dec 26 22:31:20 volumio systemd[1]: Starting wireless.service - Wireless Services... Dec 26 22:31:21 volumio go-librespot[1454]: time="2025-12-26T22:31:21Z" level=trace msg="sent dealer ping" Dec 26 22:31:21 volumio go-librespot[1454]: time="2025-12-26T22:31:21Z" level=trace msg="received dealer pong" Dec 26 22:31:21 volumio volumio[1203]: info: Discovery: A device disappeared from network Dec 26 22:31:21 volumio volumio[1203]: info: Discovery: Device volumio disappeared from network Dec 26 22:31:21 volumio volumio[1203]: info: Discovery: A device disappeared from network Dec 26 22:31:22 volumio wireless.js[1759]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Dec 26 22:31:22 volumio wireless.js[1759]: WIRELESS.JS: Wired network status changed to: ---connected--- Dec 26 22:31:22 volumio wireless.js[1759]: WIRELESS.JS: Wireless.js initializing wireless flow Dec 26 22:31:22 volumio wireless.js[1759]: WIRELESS.JS: Cleaning previous... Dec 26 22:31:22 volumio sudo[1777]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Dec 26 22:31:22 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 22:31:22 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Dec 26 22:31:22 volumio sudo[1779]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Dec 26 22:31:22 volumio sudo[1779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 22:31:23 volumio sudo[1779]: pam_unix(sudo:session): session closed for user root Dec 26 22:31:23 volumio wireless.js[1759]: WIRELESS.JS: Stopped aP Dec 26 22:31:23 volumio wireless.js[1759]: WIRELESS.JS: Wireless Networking DISABLED, not starting wireless flow Dec 26 22:31:23 volumio wireless.js[1759]: WIRELESS.JS: Notified systemd about wireless ready Dec 26 22:31:23 volumio systemd[1]: Started wireless.service - Wireless Services. Dec 26 22:31:23 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Dec 26 22:31:25 volumio volumio[1203]: info: Discovery: Started advertising with name: Volumio Dec 26 22:31:26 volumio volumio[1203]: compat.c: read() failed: Resource temporarily unavailable Dec 26 22:31:26 volumio volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 22:31:26 volumio volumio[1203]: Error: dns service error: unknown Dec 26 22:31:26 volumio volumio[1203]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Dec 26 22:31:26 volumio volumio[1203]: errorCode: -65537 Dec 26 22:31:26 volumio volumio[1203]: } Dec 26 22:31:26 volumio volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 22:31:33 volumio sudo[1819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 22:30' Dec 26 22:31:33 volumio sudo[1819]: 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"