Dec 26 10:41:01 volumio volumio-remote-updater[1016]: [2025-12-26 10:41:01] [connect] Successful connection Dec 26 10:41:03 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Dec 26 10:41:03 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:03 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:03 volumio upmpdcli[2483]: Could not open config: /tmp/upmpdcli.conf Dec 26 10:41:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 10:41:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 10:41:16 volumio volumio-remote-updater[1016]: [2025-12-26 10:41:16] [connect] Successful connection Dec 26 10:41:19 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Dec 26 10:41:19 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:19 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:19 volumio upmpdcli[2524]: Could not open config: /tmp/upmpdcli.conf Dec 26 10:41:19 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 10:41:19 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 10:41:31 volumio volumio-remote-updater[1016]: [2025-12-26 10:41:31] [connect] Successful connection Dec 26 10:41:34 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Dec 26 10:41:34 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:34 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:34 volumio upmpdcli[2551]: Could not open config: /tmp/upmpdcli.conf Dec 26 10:41:34 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 26 10:41:34 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::initPlayerControls Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: Express server listening on port 3000 Dec 26 10:41:39 volumio volumio[1343]: [Metrics] WebUI: 90s 528.36ms Dec 26 10:41:39 volumio volumio[1343]: info: Setting Device type: x86 Dec 26 10:41:39 volumio volumio[1343]: info: CoreStateMachine::resetVolumioState Dec 26 10:41:39 volumio volumio[1343]: info: CoreStateMachine::getcurrentVolume Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:41:39 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:39 volumio volumio[1343]: error: Plugin miscellanea appearance failed to complete 'onVolumioStart' in a timely fashion Dec 26 10:41:39 volumio volumio[1343]: error: Plugin system_controller network failed to complete 'onVolumioStart' in a timely fashion Dec 26 10:41:39 volumio volumio[1343]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Dec 26 10:41:39 volumio volumio[1343]: error: Plugin system_controller volumio_command_line_client failed to complete 'onVolumioStart' in a timely fashion Dec 26 10:41:39 volumio volumio[1343]: error: Plugin system_controller i2s_dacs failed to complete 'onVolumioStart' in a timely fashion Dec 26 10:41:39 volumio volumio[1343]: info: Preparing to generate the ALSA configuration file Dec 26 10:41:39 volumio volumio[1343]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 26 10:41:39 volumio volumio[1343]: info: Volumio Network Manager: Network status updated: 1 Dec 26 10:41:39 volumio volumio[1343]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 26 10:41:39 volumio volumio[1343]: wlan0 Interface doesn't support scanning. Dec 26 10:41:39 volumio volumio[1343]: info: Cannot use regular scanning, forcing with ap-force Dec 26 10:41:41 volumio sudo[2580]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 26 10:41:41 volumio sudo[2575]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 10:41:41 volumio sudo[2573]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 10:41:41 volumio sudo[2580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:41 volumio sudo[2575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:41 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:41 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:45 volumio sudo[2580]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:45 volumio sudo[2575]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:45 volumio volumio[1343]: command failed: No such device (-19) Dec 26 10:41:45 volumio volumio[1343]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 26 10:41:45 volumio volumio[1343]: command failed: No such device (-19) Dec 26 10:41:45 volumio volumio[1343]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 26 10:41:46 volumio volumio-remote-updater[1016]: [2025-12-26 10:41:46] [connect] Successful connection Dec 26 10:41:47 volumio volumio[1343]: info: VolumeController:: Volume=49 Mute =false Dec 26 10:41:47 volumio volumio[1343]: info: CoreStateMachine::pushState Dec 26 10:41:47 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::volumioPushState Dec 26 10:41:47 volumio volumio[1343]: info: CoreStateMachine::updateTrackBlock Dec 26 10:41:47 volumio volumio[1343]: info: CorePlayQueue::getTrackBlock Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 10:41:47 volumio volumio[1343]: info: Asound.conf file unchanged, so no further update is needed Dec 26 10:41:47 volumio volumio[1343]: info: Output device has changed, restarting MPD Dec 26 10:41:47 volumio volumio[1343]: info: Output device has changed, restarting Shairport Sync Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:47 volumio sudo[2619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:41:47 volumio sudo[2619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:47 volumio sudo[2619]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:47 volumio sudo[2621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:41:47 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:47 volumio volumio[1343]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:41:47 volumio volumio[1343]: info: ___________ START PLUGINS ___________ Dec 26 10:41:47 volumio volumio[1343]: info: ControllerMpd::onStart: Initializing MPD Dec 26 10:41:47 volumio volumio[1343]: info: Creating MPD Configuration file Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 10:41:47 volumio volumio[1343]: info: [1766716907823] CoreMusicLibrary::Adding element 媒体服务器 Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 10:41:47 volumio sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:41:47 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:47 volumio volumio[1343]: info: UPNP Browser: Client initialized successfully Dec 26 10:41:47 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:47 volumio sudo[2631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:41:47 volumio sudo[2631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:47 volumio volumio[1343]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:47 volumio volumio[1343]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 10:41:47 volumio volumio[1343]: info: [1766716907841] CoreMusicLibrary::Adding element Last_100 Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 10:41:47 volumio volumio[1343]: info: [1766716907842] CoreMusicLibrary::Adding element Webradio Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 10:41:47 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 10:41:47 volumio volumio[1343]: info: Initializing BBC Radios Dec 26 10:41:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:41:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:41:47 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:41:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:41:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:41:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:41:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:41:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:41:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:41:48 volumio sudo[2648]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:41:48 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:41:48 volumio sudo[2652]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 10:41:48 volumio sudo[2648]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:49 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 10:41:49 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:49 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Dec 26 10:41:49 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:49 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 26 10:41:53 volumio volumio[1343]: info: Creating Spotify config file Dec 26 10:41:53 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:53 volumio upmpdcli[2663]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.86 port 49152 IPV6 port 49152 Dec 26 10:41:53 volumio volumio[1343]: info: Volumio Calling Home Dec 26 10:41:55 volumio volumio[1343]: info: MPD Permissions set Dec 26 10:41:55 volumio volumio[1343]: info: MPD Permissions set Dec 26 10:41:55 volumio volumio[1343]: info: Reloading queue from file Dec 26 10:41:55 volumio volumio[1343]: info: CoreStateMachine::setRepeat null single undefined Dec 26 10:41:55 volumio volumio[1343]: info: CoreStateMachine::pushState Dec 26 10:41:55 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:55 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 10:41:55 volumio volumio[1343]: info: CoreCommandRouter::volumioPushState Dec 26 10:41:55 volumio volumio[1343]: info: CoreStateMachine::setRandom null Dec 26 10:41:55 volumio volumio[1343]: info: CoreStateMachine::pushState Dec 26 10:41:55 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:55 volumio volumio[1343]: info: CoreCommandRouter::volumioPushState Dec 26 10:41:55 volumio volumio[1343]: info: VolumeController:: Volume=49 Mute =false Dec 26 10:41:55 volumio volumio[1343]: info: CoreStateMachine::pushState Dec 26 10:41:55 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:55 volumio volumio[1343]: info: CoreCommandRouter::volumioPushState Dec 26 10:41:55 volumio volumio[1343]: info: Spotify config file written Dec 26 10:41:55 volumio volumio[1343]: info: No need to fix Spotify hosts Dec 26 10:41:55 volumio volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 10:41:55 volumio volumio[1343]: info: Discovery: adding 0b15e9ce-462f-45fc-9e9f-eb266702c54c Dec 26 10:41:55 volumio volumio[1343]: info: Discovery: Found device Volumio Dec 26 10:41:55 volumio volumio[1343]: info: CoreCommandRouter::volumioGetState Dec 26 10:41:55 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:55 volumio volumio[1343]: info: Discovery: this is already registered, 0b15e9ce-462f-45fc-9e9f-eb266702c54c Dec 26 10:41:55 volumio volumio[1343]: info: Discovery: Found device Volumio Dec 26 10:41:55 volumio volumio[1343]: info: CoreCommandRouter::volumioGetState Dec 26 10:41:55 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:56 volumio sudo[2692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 10:41:56 volumio sudo[2694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 26 10:41:56 volumio sudo[2692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:56 volumio sudo[2694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:57 volumio upmpdcli[2663]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 10:41:57 volumio upmpdcli[2663]: :2:../src/main.cxx:728::MPD connection failed Dec 26 10:41:57 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 10:41:57 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 10:41:57 volumio volumio[1343]: info: Volumio called home Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 26 10:41:57 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 10:41:57 volumio sudo[2694]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:57 volumio sudo[2692]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:57 volumio go-librespot[2715]: go-librespot daemon starting... Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:41:57 volumio volumio[1343]: info: Upmpdcli Daemon Started Dec 26 10:41:57 volumio volumio[1343]: info: CoreCommandRouter::volumioGetState Dec 26 10:41:57 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:41:57 volumio volumio[1343]: info: Starting Shairport Sync Dec 26 10:41:57 volumio volumio[1343]: info: Starting Shairport Sync Dec 26 10:41:57 volumio volumio[1343]: info: Starting Shairport Sync Dec 26 10:41:59 volumio sudo[2747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:41:59 volumio sudo[2743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:41:59 volumio sudo[2745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:41:59 volumio sudo[2747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:59 volumio sudo[2743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:59 volumio sudo[2745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:41:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:41:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:41:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:41:59 volumio systemd[1]: shairport-sync.service: Consumed 2.766s CPU time. Dec 26 10:41:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:41:59 volumio sudo[2743]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:59 volumio sudo[2745]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:59 volumio sudo[2747]: pam_unix(sudo:session): session closed for user root Dec 26 10:41:59 volumio volumio[1343]: info: Shairport-Sync Started Dec 26 10:41:59 volumio volumio[1343]: Error adding Membership: Error: addMembership EINVAL Dec 26 10:41:59 volumio volumio[1343]: info: Shairport-Sync Started Dec 26 10:41:59 volumio volumio[1343]: info: Shairport-Sync Started Dec 26 10:42:00 volumio volumio[1343]: info: go-librespot daemon successfully initialized Dec 26 10:42:01 volumio go-librespot[2724]: time="2025-12-26T10:42:01+08:00" level=info msg="running go-librespot 0.4.0" Dec 26 10:42:01 volumio go-librespot[2724]: time="2025-12-26T10:42:01+08:00" level=debug msg="app state loaded" Dec 26 10:42:01 volumio go-librespot[2724]: time="2025-12-26T10:42:01+08:00" level=debug msg="stored credentials not found" Dec 26 10:42:01 volumio go-librespot[2724]: time="2025-12-26T10:42:01+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 10:42:01 volumio upmpdcli[2663]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 10:42:01 volumio upmpdcli[2663]: :2:../src/main.cxx:728::MPD connection failed Dec 26 10:42:01 volumio volumio-remote-updater[1016]: [2025-12-26 10:42:01] [connect] Successful connection Dec 26 10:42:01 volumio volumio-remote-updater[1016]: [2025-12-26 10:42:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766716921 101 Dec 26 10:42:01 volumio volumio[1343]: 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: 2 Dec 26 10:42:03 volumio volumio[1343]: info: Initializing connection to go-librespot Websocket Dec 26 10:42:07 volumio upmpdcli[2663]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 10:42:07 volumio upmpdcli[2663]: :2:../src/main.cxx:728::MPD connection failed Dec 26 10:42:08 volumio go-librespot[2724]: time="2025-12-26T10:42:08+08:00" level=debug msg="new websocket client" Dec 26 10:42:08 volumio volumio[1343]: info: Connection to go-librespot Websocket established Dec 26 10:42:11 volumio volumio[1343]: info: Getting Spotify volume Dec 26 10:42:11 volumio volumio[1343]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 26 10:42:11 volumio volumio[1343]: info: CoreCommandRouter::volumioGetState Dec 26 10:42:11 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:42:11 volumio volumio[1343]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Dec 26 10:42:11 volumio volumio[1343]: SPOTIFY: SPOTIFY VOLUME undefined Dec 26 10:42:11 volumio volumio[1343]: SPOTIFY: VOLUMIO VOLUME 49 Dec 26 10:42:11 volumio volumio[1343]: info: Aligning Spotify Volume to Volumio Volume Dec 26 10:42:11 volumio volumio[1343]: info: CoreCommandRouter::volumioGetState Dec 26 10:42:11 volumio volumio[1343]: info: CorePlayQueue::getTrack 0 Dec 26 10:42:11 volumio volumio[1343]: info: Setting Spotify Volume from Volumio: 49 Dec 26 10:42:13 volumio volumio[1343]: SPOTIFY: SETTING SPOTIFY VOLUME 49 Dec 26 10:42:13 volumio volumio[1343]: info: Sending Spotify command with payload to local API: /player/volume Dec 26 10:42:17 volumio upmpdcli[2663]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 10:42:17 volumio upmpdcli[2663]: :2:../src/main.cxx:728::MPD connection failed Dec 26 10:42:23 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 10:42:23 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:42:23 volumio volumio[1343]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 10:42:23 volumio volumio[1343]: error: Plugin music_service mpd failed to complete 'onStart' in a timely fashion Dec 26 10:42:23 volumio volumio[1343]: info: ------------------------------------------- Dec 26 10:42:23 volumio volumio[1343]: info: ----- MyVolumio plugins startup ---- Dec 26 10:42:23 volumio volumio[1343]: info: ------------------------------------------- Dec 26 10:42:23 volumio volumio[1343]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 26 10:42:31 volumio go-librespot[2724]: time="2025-12-26T10:42:31+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Dec 26 10:42:35 volumio upmpdcli[2663]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 26 10:42:35 volumio upmpdcli[2663]: :2:../src/main.cxx:728::MPD connection failed Dec 26 10:42:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 10:42:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 10:42:35 volumio volumio[1343]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Dec 26 10:42:35 volumio volumio[1343]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 10:42:35 volumio volumio[1343]: Error: socket hang up Dec 26 10:42:35 volumio volumio[1343]: at connResetException (node:internal/errors:720:14) Dec 26 10:42:35 volumio volumio[1343]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 26 10:42:35 volumio volumio[1343]: at Socket.emit (node:events:526:35) Dec 26 10:42:35 volumio volumio[1343]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 26 10:42:35 volumio volumio[1343]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 26 10:42:35 volumio volumio[1343]: code: 'ECONNRESET', Dec 26 10:42:35 volumio volumio[1343]: response: undefined Dec 26 10:42:35 volumio volumio[1343]: } Dec 26 10:42:35 volumio volumio[1343]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 10:42:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 26 10:42:38 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 26 10:42:38 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 26 10:42:38 volumio go-librespot[2884]: go-librespot daemon starting... Dec 26 10:42:38 volumio go-librespot[2885]: time="2025-12-26T10:42:38+08:00" level=info msg="running go-librespot 0.4.0" Dec 26 10:42:38 volumio go-librespot[2885]: time="2025-12-26T10:42:38+08:00" level=debug msg="app state loaded" Dec 26 10:42:38 volumio go-librespot[2885]: time="2025-12-26T10:42:38+08:00" level=debug msg="stored credentials not found" Dec 26 10:42:38 volumio go-librespot[2885]: time="2025-12-26T10:42:38+08:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 26 10:42:40 volumio sudo[2900]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 10:41' Dec 26 10:42:40 volumio sudo[2900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"