Feb 22 00:21:00 volumio ntpd[1034]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 00:21:00 volumio volumio[1205]: info: Loading plugin "soundcloud"... Feb 22 00:21:01 volumio volumio[1205]: info: Loading plugin "spop"... Feb 22 00:21:02 volumio volumio[1205]: info: Loading plugin "youtube2"... Feb 22 00:21:03 volumio volumio[1205]: info: Loading plugin "ytcr"... Feb 22 00:21:05 volumio volumio[1205]: info: Loading plugin "ytmusic"... Feb 22 00:21:06 volumio volumio[1205]: info: Loading plugin "now_playing"... Feb 22 00:21:08 volumio volumio[1205]: info: Loading plugin "outputs"... Feb 22 00:21:08 volumio volumio[1205]: info: Loading plugin "albumart"... Feb 22 00:21:08 volumio volumio[1205]: info: Plugin example_plugin is not enabled Feb 22 00:21:08 volumio volumio[1205]: info: Loading plugin "inputs"... Feb 22 00:21:08 volumio volumio[1205]: info: Loading plugin "updater_comm"... Feb 22 00:21:09 volumio volumio-remote-updater[833]: [2026-02-22 00:21:08] [connect] Successful connection Feb 22 00:21:09 volumio volumio[1205]: info: Plugin mpdemulation is not enabled Feb 22 00:21:09 volumio volumio[1205]: info: Loading plugin "rest_api"... Feb 22 00:21:09 volumio volumio[1205]: info: Loading plugin "websocket"... Feb 22 00:21:09 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Feb 22 00:21:09 volumio volumio[1205]: info: Starting Socket.io Server version 1.7.4 Feb 22 00:21:09 volumio volumio[1205]: info: Loading plugin "motherearthradio"... Feb 22 00:21:09 volumio volumio[1205]: info: Applying required configuration parameters for plugin motherearthradio Feb 22 00:21:09 volumio volumio[1205]: info: [1771699869837] [MotherEarth] API delay: 5 Feb 22 00:21:09 volumio volumio[1205]: info: Loading plugin "radio_paradise"... Feb 22 00:21:10 volumio volumio[1421]: Forking 3 albumart workers Feb 22 00:21:10 volumio volumio[1205]: info: Applying required configuration parameters for plugin radio_paradise Feb 22 00:21:10 volumio volumio[1205]: info: [1771699870941] [RadioParadise] API delay: 5 Feb 22 00:21:10 volumio volumio[1205]: info: Loading plugin "music_services_shield"... Feb 22 00:21:11 volumio ntpd[1034]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Feb 22 00:21:11 volumio ntpd[1034]: DNS: Pool skipping: 172.105.60.167 Feb 22 00:21:11 volumio ntpd[1034]: DNS: Pool skipping: 14.139.60.103 Feb 22 00:21:11 volumio ntpd[1034]: DNS: Pool skipping: 139.59.15.185 Feb 22 00:21:11 volumio ntpd[1034]: DNS: Pool skipping: 103.136.36.100 Feb 22 00:21:11 volumio ntpd[1034]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Feb 22 00:21:11 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Feb 22 00:21:12 volumio ntpd[1034]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 00:21:12 volumio volumio[1205]: info: Applying required configuration parameters for plugin music_services_shield Feb 22 00:21:12 volumio volumio[1205]: info: Loading i18n strings for locale en Feb 22 00:21:12 volumio volumio[1205]: Updating browse sources language Feb 22 00:21:12 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::initPlayerControls Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 00:21:14 volumio volumio[1205]: Express server listening on port 3000 Feb 22 00:21:14 volumio volumio[1205]: [Metrics] WebUI: 23s 187.98ms Feb 22 00:21:14 volumio volumio[1205]: info: CoreStateMachine::resetVolumioState Feb 22 00:21:14 volumio volumio[1205]: info: CoreStateMachine::getcurrentVolume Feb 22 00:21:14 volumio volumio[1205]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 00:21:14 volumio sudo[1470]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:21:14 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:14 volumio sudo[1470]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:14 volumio sudo[1472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:21:14 volumio sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:14 volumio sudo[1472]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:14 volumio volumio[1205]: info: Volumio Network Manager: Network status updated: 1 Feb 22 00:21:15 volumio volumio[1433]: Starting albumart workers Feb 22 00:21:15 volumio volumio[1205]: info: VolumeController:: Volume=100 Mute =false Feb 22 00:21:15 volumio volumio[1432]: Starting albumart workers Feb 22 00:21:15 volumio volumio[1205]: info: CoreStateMachine::pushState Feb 22 00:21:15 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Feb 22 00:21:15 volumio volumio[1205]: info: CoreStateMachine::updateTrackBlock Feb 22 00:21:15 volumio volumio[1205]: info: CorePlayQueue::getTrackBlock Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:15 volumio volumio[1205]: info: Executing start script for DAC IQaudIO DAC Plus Feb 22 00:21:15 volumio volumio[1431]: Starting albumart workers Feb 22 00:21:15 volumio volumio[1205]: info: Reloading queue from file Feb 22 00:21:15 volumio volumio[1205]: info: CoreStateMachine::setRepeat false single undefined Feb 22 00:21:15 volumio volumio[1205]: info: CoreStateMachine::pushState Feb 22 00:21:15 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Feb 22 00:21:15 volumio volumio[1205]: info: CoreStateMachine::setRandom false Feb 22 00:21:15 volumio volumio[1205]: info: CoreStateMachine::pushState Feb 22 00:21:15 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:21:15 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Feb 22 00:21:15 volumio volumio[1205]: info: Setting Device type: Raspberry PI Feb 22 00:21:15 volumio volumio[1205]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 22 00:21:16 volumio volumio[1205]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 22 00:21:16 volumio volumio[1205]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 22 00:21:16 volumio volumio[1205]: info: DAC script executed Feb 22 00:21:16 volumio volumio[1205]: info: Completed loading Core Plugins Feb 22 00:21:16 volumio volumio[1205]: info: Preparing to generate the ALSA configuration file Feb 22 00:21:16 volumio volumio[1205]: info: VolumeController:: Volume=100 Mute =false Feb 22 00:21:16 volumio volumio[1205]: info: CoreStateMachine::pushState Feb 22 00:21:16 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:16 volumio volumio[1205]: info: CoreCommandRouter::volumioPushState Feb 22 00:21:16 volumio volumio[1205]: info: Asound.conf file unchanged, so no further update is needed Feb 22 00:21:16 volumio volumio[1205]: info: Output device has changed, restarting MPD Feb 22 00:21:16 volumio sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 00:21:16 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:16 volumio volumio[1205]: info: Output device has changed, restarting Shairport Sync Feb 22 00:21:16 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:16 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:16 volumio sudo[1511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 00:21:16 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Feb 22 00:21:16 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:16 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:16 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:16 volumio volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 00:21:16 volumio volumio[1205]: info: ___________ START PLUGINS ___________ Feb 22 00:21:16 volumio sudo[1514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 00:21:16 volumio sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:16 volumio volumio[1205]: info: ControllerMpd::onStart: Initializing MPD Feb 22 00:21:16 volumio volumio[1205]: info: Creating MPD Configuration file Feb 22 00:21:16 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Feb 22 00:21:16 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 22 00:21:16 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 22 00:21:16 volumio sudo[1532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 00:21:16 volumio sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:16 volumio sudo[1532]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:16 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 00:21:16 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 00:21:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 00:21:16 volumio volumio[1205]: info: [1771699876893] CoreMusicLibrary::Adding element Media Servers Feb 22 00:21:16 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:17 volumio sudo[1537]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 00:21:17 volumio sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 00:21:17 volumio sudo[1538]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 00:21:17 volumio sudo[1537]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:17 volumio sudo[1536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 00:21:17 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:17 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 22 00:21:17 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 00:21:17 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 22 00:21:17 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 00:21:17 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 00:21:17 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 00:21:17 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 00:21:17 volumio volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:17 volumio sudo[1546]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 00:21:17 volumio sudo[1546]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 00:21:17 volumio sudo[1549]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 22 00:21:17 volumio sudo[1546]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:17 volumio volumio[1205]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:17 volumio volumio[1205]: info: [1771699877401] CoreMusicLibrary::Adding element Last_100 Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:17 volumio volumio[1205]: info: [1771699877442] CoreMusicLibrary::Adding element Webradio Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:17 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:21:17 volumio volumio[1205]: info: Initializing BBC Radios Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:18 volumio volumio[1205]: info: Adding Calm Radio to Browse Sources Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:18 volumio volumio[1205]: info: [1771699878102] CoreMusicLibrary::Adding element Calm Radio Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source Calm Radio Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:18 volumio volumio[1205]: info: [1771699878225] CoreMusicLibrary::Adding element SoundCloud Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source Calm Radio Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source SoundCloud Feb 22 00:21:18 volumio volumio[1205]: info: Creating Spotify config file Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:18 volumio volumio[1205]: info: [1771699878424] CoreMusicLibrary::Adding element YouTube2 Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source Calm Radio Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source SoundCloud Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source YouTube2 Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:18 volumio volumio[1205]: info: [1771699878882] CoreMusicLibrary::Adding element YouTube Music Feb 22 00:21:18 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source Calm Radio Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source SoundCloud Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source YouTube2 Feb 22 00:21:18 volumio volumio[1205]: Cannot find translation for source YouTube Music Feb 22 00:21:19 volumio volumio[1205]: info: [now-playing] ConfigUpdater: config is up to date. Feb 22 00:21:19 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:19 volumio volumio[1205]: info: [1771699879113] CoreMusicLibrary::Adding element Mother Earth Radio Feb 22 00:21:19 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source Calm Radio Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source SoundCloud Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source YouTube2 Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source YouTube Music Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source Mother Earth Radio Feb 22 00:21:19 volumio volumio[1205]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:21:19 volumio volumio[1205]: info: [1771699879168] CoreMusicLibrary::Adding element Radio Paradise Feb 22 00:21:19 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source Calm Radio Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source SoundCloud Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source YouTube2 Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source YouTube Music Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source Mother Earth Radio Feb 22 00:21:19 volumio volumio[1205]: Cannot find translation for source Radio Paradise Feb 22 00:21:19 volumio volumio[1205]: info: Volumio Calling Home Feb 22 00:21:20 volumio sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Feb 22 00:21:20 volumio sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:21 volumio mpd[1552]: 2026-02-22T00:21:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 00:21:21 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 00:21:21 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:21 volumio sudo[1514]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:21 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 22 00:21:22 volumio volumio[1205]: info: [now-playing] App is listening on port 4004. Feb 22 00:21:22 volumio volumio[1205]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 22 00:21:22 volumio volumio[1205]: info: Discovery: adding 79e42f3a-b6c7-45d2-beac-ef3bed22036e Feb 22 00:21:22 volumio volumio[1205]: info: Discovery: Found device Volumio Feb 22 00:21:22 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Feb 22 00:21:22 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:22 volumio volumio[1205]: info: Discovery: this is already registered, 79e42f3a-b6c7-45d2-beac-ef3bed22036e Feb 22 00:21:22 volumio volumio[1205]: info: Discovery: Found device Volumio Feb 22 00:21:22 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Feb 22 00:21:22 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:22 volumio bash[1648]: cset: --> shielding system active with Feb 22 00:21:22 volumio bash[1648]: cset: "system" cpuset of CPUSPEC(1-3) with 156 tasks running Feb 22 00:21:22 volumio bash[1648]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 22 00:21:22 volumio volumio[1205]: info: MPD Permissions set Feb 22 00:21:22 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 22 00:21:22 volumio volumio[1205]: info: MPD Permissions set Feb 22 00:21:22 volumio volumio[1205]: info: Upmpdcli Daemon Started Feb 22 00:21:22 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 22 00:21:22 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 22 00:21:22 volumio volumio[1205]: info: MPD running with PID1552 Feb 22 00:21:22 volumio volumio[1205]: ,establishing connection Feb 22 00:21:22 volumio volumio[1205]: info: Spotify config file written Feb 22 00:21:23 volumio sudo[1669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 00:21:23 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:23 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. Feb 22 00:21:23 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. Feb 22 00:21:23 volumio volumio[1205]: error: MPD error: The expression evaluated to a falsy value: Feb 22 00:21:23 volumio volumio[1205]: assert.ok(self.idling) Feb 22 00:21:23 volumio volumio[1205]: error: The expression evaluated to a falsy value: Feb 22 00:21:23 volumio volumio[1205]: assert.ok(self.idling) Feb 22 00:21:23 volumio volumio[1205]: error: MPD error: The expression evaluated to a falsy value: Feb 22 00:21:23 volumio volumio[1205]: assert.ok(self.idling) Feb 22 00:21:23 volumio volumio[1205]: error: The expression evaluated to a falsy value: Feb 22 00:21:23 volumio volumio[1205]: assert.ok(self.idling) Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:21:23 volumio go-librespot[1673]: go-librespot daemon starting... Feb 22 00:21:23 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:23 volumio ntpd[1034]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Feb 22 00:21:23 volumio ntpd[1034]: DNS: Pool taking: 172.236.180.15 Feb 22 00:21:23 volumio ntpd[1034]: DNS: Pool taking: 142.93.213.206 Feb 22 00:21:23 volumio ntpd[1034]: DNS: Pool skipping: 17.253.18.131 Feb 22 00:21:23 volumio ntpd[1034]: DNS: Pool skipping: 103.136.36.100 Feb 22 00:21:23 volumio ntpd[1034]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:21:23 volumio volumio[1205]: info: No need to fix Spotify hosts Feb 22 00:21:23 volumio volumio[1205]: error: updateQueue error: null Feb 22 00:21:23 volumio volumio[1205]: info: Starting Shairport Sync Feb 22 00:21:23 volumio go-librespot[1675]: time="2026-02-22T00:21:23+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:21:23 volumio go-librespot[1675]: time="2026-02-22T00:21:23+05:30" level=debug msg="app state loaded" Feb 22 00:21:23 volumio go-librespot[1675]: time="2026-02-22T00:21:23+05:30" level=debug msg="stored credentials not found" Feb 22 00:21:23 volumio volumio[1205]: info: Starting Shairport Sync Feb 22 00:21:23 volumio go-librespot[1675]: time="2026-02-22T00:21:23+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:21:23 volumio volumio[1205]: info: Starting Shairport Sync Feb 22 00:21:23 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 00:21:23 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:23 volumio sudo[1697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 00:21:23 volumio sudo[1698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 00:21:23 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:23 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:23 volumio volumio[1205]: info: CoreCommandRouter::volumioGetState Feb 22 00:21:23 volumio volumio[1205]: info: CorePlayQueue::getTrack 0 Feb 22 00:21:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 00:21:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 00:21:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:21:23 volumio systemd[1]: shairport-sync.service: Consumed 1.449s CPU time. Feb 22 00:21:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:21:23 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:23 volumio volumio[1205]: info: Shairport-Sync Started Feb 22 00:21:23 volumio volumio[1205]: Error adding Membership: Error: addMembership EINVAL Feb 22 00:21:23 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 00:21:23 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 00:21:23 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:21:23 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:23 volumio volumio[1205]: info: Shairport-Sync Started Feb 22 00:21:23 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:21:24 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:24 volumio volumio-remote-updater[833]: [2026-02-22 00:21:23] [connect] Successful connection Feb 22 00:21:24 volumio volumio-remote-updater[833]: [2026-02-22 00:21:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771699883 101 Feb 22 00:21:24 volumio volumio[1205]: 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 Feb 22 00:21:24 volumio volumio[1205]: info: Shairport-Sync Started Feb 22 00:21:24 volumio ntpd[1034]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Feb 22 00:21:24 volumio sudo[1617]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:24 volumio sudo[1722]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Feb 22 00:21:24 volumio sudo[1722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:25 volumio systemd[1]: Reloading. Feb 22 00:21:25 volumio volumio[1205]: (node:1205) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 22 00:21:25 volumio volumio[1205]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 22 00:21:25 volumio volumio[1205]: (node:1205) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 22 00:21:25 volumio sudo[1745]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 00:21:25 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:25 volumio sudo[1748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 00:21:25 volumio sudo[1748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:21:25 volumio systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Feb 22 00:21:26 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. Feb 22 00:21:26 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. Feb 22 00:21:26 volumio volumio[1205]: info: go-librespot daemon successfully initialized Feb 22 00:21:26 volumio sudo[1722]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:26 volumio volumio[1205]: info: succeeded Feb 22 00:21:26 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 22 00:21:26 volumio mpd_monitor.sh[1764]: MPD Monitor Service: Starting MPD Monitor Service Feb 22 00:21:26 volumio sudo[1748]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:26 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root Feb 22 00:21:26 volumio volumio[1205]: info: Successfully started MPD Monitor Feb 22 00:21:26 volumio volumio[1205]: info: Successfully started MPD Monitor Feb 22 00:21:26 volumio go-librespot[1675]: time="2026-02-22T00:21:26+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:21:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:21:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:21:29 volumio volumio[1205]: info: Initializing connection to go-librespot Websocket Feb 22 00:21:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 22 00:21:29 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:21:29 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:21:29 volumio go-librespot[1774]: go-librespot daemon starting... Feb 22 00:21:30 volumio go-librespot[1775]: time="2026-02-22T00:21:30+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:21:30 volumio go-librespot[1775]: time="2026-02-22T00:21:30+05:30" level=debug msg="app state loaded" Feb 22 00:21:30 volumio go-librespot[1775]: time="2026-02-22T00:21:30+05:30" level=debug msg="stored credentials not found" Feb 22 00:21:30 volumio go-librespot[1775]: time="2026-02-22T00:21:30+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:21:30 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (uft857gjn46lqqdh8r74ddvf1c): Feb 22 00:21:30 volumio volumio[1205]: (ConnectionError) (YouTube) Connection error in getting lounge token Feb 22 00:21:30 volumio volumio[1205]: Error info: { Feb 22 00:21:30 volumio volumio[1205]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Feb 22 00:21:30 volumio volumio[1205]: } Feb 22 00:21:30 volumio volumio[1205]: --->(TypeError) fetch failed Feb 22 00:21:30 volumio volumio[1205]: Stack trace: Feb 22 00:21:30 volumio volumio[1205]: ConnectionError: (YouTube) Connection error in getting lounge token Feb 22 00:21:30 volumio volumio[1205]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:440:15) Feb 22 00:21:30 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:30 volumio volumio[1205]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:130:31) Feb 22 00:21:30 volumio volumio[1205]: at async Promise.all (index 0) Feb 22 00:21:30 volumio volumio[1205]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:114:13) Feb 22 00:21:30 volumio volumio[1205]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:21:30 volumio volumio[1205]: Going to generate fresh screen Id and try again... Feb 22 00:21:30 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (mdjjvfp3a64ni6qgip1v2i67br): Feb 22 00:21:30 volumio volumio[1205]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Feb 22 00:21:30 volumio volumio[1205]: Error info: { Feb 22 00:21:30 volumio volumio[1205]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Feb 22 00:21:30 volumio volumio[1205]: } Feb 22 00:21:30 volumio volumio[1205]: --->(TypeError) fetch failed Feb 22 00:21:30 volumio volumio[1205]: Stack trace: Feb 22 00:21:30 volumio volumio[1205]: ConnectionError: (YouTube Music) Connection error in getting lounge token Feb 22 00:21:30 volumio volumio[1205]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:440:15) Feb 22 00:21:30 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:30 volumio volumio[1205]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:130:31) Feb 22 00:21:30 volumio volumio[1205]: at async Promise.all (index 1) Feb 22 00:21:30 volumio volumio[1205]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:114:13) Feb 22 00:21:30 volumio volumio[1205]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:21:30 volumio volumio[1205]: Going to generate fresh screen Id and try again... Feb 22 00:21:30 volumio go-librespot[1775]: time="2026-02-22T00:21:30+05:30" level=debug msg="new websocket client" Feb 22 00:21:31 volumio volumio[1205]: info: Connection to go-librespot Websocket established Feb 22 00:21:34 volumio volumio[1205]: info: Getting Spotify volume Feb 22 00:21:38 volumio volumio[1205]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.071&uuid=11731ddda67b013d655f62e8af55d797" http://updates.volumio.org/downloader-v1/track-device Feb 22 00:21:38 volumio volumio[1205]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 22 00:21:38 volumio volumio[1205]: Dload Upload Total Spent Left Speed Feb 22 00:21:38 volumio volumio[1205]: [711B blob data] Feb 22 00:21:38 volumio volumio[1205]: curl: (7) Failed to connect to updates.volumio.org port 80 after 8148 ms: Couldn't connect to server Feb 22 00:21:38 volumio volumio[1205]: retrying in 5 seconds, trial 0 Feb 22 00:21:38 volumio volumio[1205]: info: Volumio Calling Home Feb 22 00:21:40 volumio ntpd[1034]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Feb 22 00:21:40 volumio ntpd[1034]: DNS: Pool taking: 172.235.18.237 Feb 22 00:21:40 volumio ntpd[1034]: DNS: Pool taking: 139.84.142.141 Feb 22 00:21:40 volumio ntpd[1034]: DNS: Pool taking: 95.216.144.226 Feb 22 00:21:40 volumio ntpd[1034]: DNS: Pool taking: 14.139.60.107 Feb 22 00:21:40 volumio ntpd[1034]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Feb 22 00:21:42 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 22 00:21:42 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:42 volumio volumio[1205]: AID: null, Feb 22 00:21:42 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:42 volumio volumio[1205]: payload: {} Feb 22 00:21:42 volumio volumio[1205]: } Feb 22 00:21:42 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:42 volumio volumio[1205]: Stack trace: Feb 22 00:21:42 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:42 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:42 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:42 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:42 volumio volumio[1205]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:39:23) Feb 22 00:21:42 volumio volumio[1205]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:345:67 Feb 22 00:21:42 volumio volumio[1205]: at new Promise () Feb 22 00:21:42 volumio volumio[1205]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:344:16) Feb 22 00:21:42 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:241:28) Feb 22 00:21:42 volumio volumio[1205]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:209:26) Feb 22 00:21:42 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:42 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:42 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:42 volumio volumio[1205]: at async Promise.all (index 0) Feb 22 00:21:42 volumio volumio[1205]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:114:13) Feb 22 00:21:42 volumio volumio[1205]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:21:42 volumio volumio[1205]: error: [yt-cast-receiver] Failed to start receiver: Feb 22 00:21:42 volumio volumio[1205]: (AppError) Failed to start YouTubeApp Feb 22 00:21:42 volumio volumio[1205]: --->(SessionError) (YouTube) Failed to establish session Feb 22 00:21:42 volumio volumio[1205]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Feb 22 00:21:42 volumio volumio[1205]: Error info: { Feb 22 00:21:42 volumio volumio[1205]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Feb 22 00:21:42 volumio volumio[1205]: } Feb 22 00:21:42 volumio volumio[1205]: --------->(TypeError) fetch failed Feb 22 00:21:42 volumio volumio[1205]: Stack trace: Feb 22 00:21:42 volumio volumio[1205]: AppError: Failed to start YouTubeApp Feb 22 00:21:42 volumio volumio[1205]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:131:19) Feb 22 00:21:42 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:42 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:42 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:42 volumio volumio[1205]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:21:42 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 22 00:21:42 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:42 volumio volumio[1205]: AID: null, Feb 22 00:21:42 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:42 volumio volumio[1205]: payload: {} Feb 22 00:21:42 volumio volumio[1205]: } Feb 22 00:21:42 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:42 volumio volumio[1205]: Stack trace: Feb 22 00:21:42 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:42 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:42 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:42 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:42 volumio volumio[1205]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:39:23) Feb 22 00:21:42 volumio volumio[1205]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:345:67 Feb 22 00:21:42 volumio volumio[1205]: at new Promise () Feb 22 00:21:42 volumio volumio[1205]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:344:16) Feb 22 00:21:42 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:241:28) Feb 22 00:21:42 volumio volumio[1205]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:123:39 Feb 22 00:21:42 volumio volumio[1205]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:128:19 Feb 22 00:21:42 volumio volumio[1205]: at Array.forEach () Feb 22 00:21:42 volumio volumio[1205]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:118:24) Feb 22 00:21:42 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:42 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:42 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:42 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 22 00:21:42 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:42 volumio volumio[1205]: AID: null, Feb 22 00:21:42 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:42 volumio volumio[1205]: payload: {} Feb 22 00:21:42 volumio volumio[1205]: } Feb 22 00:21:42 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:42 volumio volumio[1205]: Stack trace: Feb 22 00:21:42 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:42 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:42 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:42 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:42 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Feb 22 00:21:42 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:42 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:42 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:42 volumio volumio[1205]: error: [ytcr] Failed to start plugin: Feb 22 00:21:42 volumio volumio[1205]: (AppError) Failed to start YouTubeApp Feb 22 00:21:42 volumio volumio[1205]: --->(SessionError) (YouTube) Failed to establish session Feb 22 00:21:42 volumio volumio[1205]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Feb 22 00:21:42 volumio volumio[1205]: Error info: { Feb 22 00:21:42 volumio volumio[1205]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Feb 22 00:21:42 volumio volumio[1205]: } Feb 22 00:21:42 volumio volumio[1205]: --------->(TypeError) fetch failed Feb 22 00:21:42 volumio volumio[1205]: Stack trace: Feb 22 00:21:42 volumio volumio[1205]: AppError: Failed to start YouTubeApp Feb 22 00:21:42 volumio volumio[1205]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:131:19) Feb 22 00:21:42 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:42 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:42 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:42 volumio volumio[1205]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:21:42 volumio volumio[1205]: info: Completed starting Core Plugins Feb 22 00:21:42 volumio volumio[1205]: info: ------------------------------------------- Feb 22 00:21:42 volumio volumio[1205]: info: ----- MyVolumio plugins startup ---- Feb 22 00:21:42 volumio volumio[1205]: info: ------------------------------------------- Feb 22 00:21:42 volumio volumio[1205]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 22 00:21:42 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 22 00:21:42 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:42 volumio volumio[1205]: AID: null, Feb 22 00:21:42 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:42 volumio volumio[1205]: payload: {} Feb 22 00:21:42 volumio volumio[1205]: } Feb 22 00:21:42 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:42 volumio volumio[1205]: Stack trace: Feb 22 00:21:42 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:42 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:42 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:42 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:42 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:42 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Feb 22 00:21:42 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:42 volumio volumio[1205]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:123:25 Feb 22 00:21:45 volumio volumio[1205]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 22 00:21:49 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:21:49 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:21:49 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 22 00:21:54 volumio upmpdcli[1818]: writing RSA key Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 22 00:21:55 volumio volumio[1205]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Feb 22 00:21:55 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:55 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 22 00:21:55 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:55 volumio volumio[1205]: AID: null, Feb 22 00:21:55 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:55 volumio volumio[1205]: payload: {} Feb 22 00:21:55 volumio volumio[1205]: } Feb 22 00:21:55 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:55 volumio volumio[1205]: Stack trace: Feb 22 00:21:55 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:55 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:55 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:55 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Feb 22 00:21:55 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:55 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:55 volumio volumio[1205]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:123:25 Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 22 00:21:55 volumio volumio[1205]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Feb 22 00:21:55 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:55 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 22 00:21:55 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:55 volumio volumio[1205]: AID: null, Feb 22 00:21:55 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:55 volumio volumio[1205]: payload: {} Feb 22 00:21:55 volumio volumio[1205]: } Feb 22 00:21:55 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:55 volumio volumio[1205]: Stack trace: Feb 22 00:21:55 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:55 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:55 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:55 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Feb 22 00:21:55 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:55 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 22 00:21:55 volumio volumio[1205]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Feb 22 00:21:55 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:55 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 22 00:21:55 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:55 volumio volumio[1205]: AID: null, Feb 22 00:21:55 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:55 volumio volumio[1205]: payload: {} Feb 22 00:21:55 volumio volumio[1205]: } Feb 22 00:21:55 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:55 volumio volumio[1205]: Stack trace: Feb 22 00:21:55 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:55 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:55 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:55 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Feb 22 00:21:55 volumio volumio[1205]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 22 00:21:55 volumio volumio[1205]: at process.processImmediate (node:internal/timers:449:9) Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 22 00:21:55 volumio volumio[1205]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Feb 22 00:21:55 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:55 volumio volumio[1205]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 22 00:21:55 volumio volumio[1205]: LoungeScreenDisconnected { Feb 22 00:21:55 volumio volumio[1205]: AID: null, Feb 22 00:21:55 volumio volumio[1205]: name: 'loungeScreenDisconnected', Feb 22 00:21:55 volumio volumio[1205]: payload: {} Feb 22 00:21:55 volumio volumio[1205]: } Feb 22 00:21:55 volumio volumio[1205]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 22 00:21:55 volumio volumio[1205]: Stack trace: Feb 22 00:21:55 volumio volumio[1205]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 22 00:21:55 volumio volumio[1205]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Feb 22 00:21:55 volumio volumio[1205]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Feb 22 00:21:55 volumio volumio[1205]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Feb 22 00:21:55 volumio volumio[1205]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Feb 22 00:21:55 volumio volumio[1205]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Feb 22 00:21:55 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:21:56 volumio go-librespot[1775]: time="2026-02-22T00:21:56+05:30" 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\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Feb 22 00:21:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:21:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:21:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 22 00:21:59 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:21:59 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:21:59 volumio go-librespot[1842]: go-librespot daemon starting... Feb 22 00:21:59 volumio go-librespot[1843]: time="2026-02-22T00:21:59+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:21:59 volumio go-librespot[1843]: time="2026-02-22T00:21:59+05:30" level=debug msg="app state loaded" Feb 22 00:21:59 volumio go-librespot[1843]: time="2026-02-22T00:21:59+05:30" level=debug msg="stored credentials not found" Feb 22 00:21:59 volumio go-librespot[1843]: time="2026-02-22T00:21:59+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:02 volumio go-librespot[1843]: time="2026-02-22T00:22:02+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:03 volumio volumio[1205]: error: MyVolumio Plugin failed to start in a timely fashion Feb 22 00:22:03 volumio volumio[1205]: [Metrics] CommandRouter: 71s 819.88ms Feb 22 00:22:03 volumio volumio[1205]: info: CoreCommandRouter::volumiosetStartupVolume Feb 22 00:22:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:03 volumio volumio[1205]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:03 volumio volumio[1205]: info: CoreCommandRouter::Close All Modals sent Feb 22 00:22:03 volumio volumio[1205]: info: CoreCommandRouter::Close All Modals sent Feb 22 00:22:03 volumio volumio[1205]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 00:22:03 volumio volumio[1205]: Error: socket hang up Feb 22 00:22:03 volumio volumio[1205]: at connResetException (node:internal/errors:720:14) Feb 22 00:22:03 volumio volumio[1205]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 22 00:22:03 volumio volumio[1205]: at Socket.emit (node:events:526:35) Feb 22 00:22:03 volumio volumio[1205]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 22 00:22:03 volumio volumio[1205]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 22 00:22:03 volumio volumio[1205]: code: 'ECONNRESET', Feb 22 00:22:03 volumio volumio[1205]: response: undefined Feb 22 00:22:03 volumio volumio[1205]: } Feb 22 00:22:03 volumio volumio[1205]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 00:22:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 22 00:22:05 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:05 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:05 volumio go-librespot[1871]: go-librespot daemon starting... Feb 22 00:22:05 volumio sudo[1870]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 00:21' Feb 22 00:22:05 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:05 volumio go-librespot[1872]: time="2026-02-22T00:22:05+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:05 volumio go-librespot[1872]: time="2026-02-22T00:22:05+05:30" level=debug msg="app state loaded" Feb 22 00:22:05 volumio go-librespot[1872]: time="2026-02-22T00:22:05+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:05 volumio go-librespot[1872]: time="2026-02-22T00:22:05+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:06 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:09 volumio go-librespot[1872]: time="2026-02-22T00:22:09+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:10 volumio volumio-remote-updater[833]: [2026-02-22 00:22:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 22 00:22:10 volumio volumio-remote-updater[833]: [2026-02-22 00:22:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 22 00:22:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:10 volumio ntfs-3g[1402]: Unmounting /dev/sda1 (New Volume) Feb 22 00:22:10 volumio systemd[1]: media-New_Volume.mount: Deactivated successfully. Feb 22 00:22:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 22 00:22:10 volumio systemd[1]: volumio.service: Consumed 2min 29.370s CPU time. Feb 22 00:22:10 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 22 00:22:10 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 22 00:22:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 22 00:22:10 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 22 00:22:10 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 22 00:22:10 volumio systemd[1]: volumio.service: Consumed 2min 29.370s CPU time. Feb 22 00:22:10 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Feb 22 00:22:10 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Feb 22 00:22:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 22 00:22:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:12 volumio go-librespot[1949]: go-librespot daemon starting... Feb 22 00:22:12 volumio go-librespot[1950]: time="2026-02-22T00:22:12+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:12 volumio go-librespot[1950]: time="2026-02-22T00:22:12+05:30" level=debug msg="app state loaded" Feb 22 00:22:12 volumio go-librespot[1950]: time="2026-02-22T00:22:12+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:12 volumio go-librespot[1950]: time="2026-02-22T00:22:12+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:12 volumio volumio[1928]: info: ------------------------------------------- Feb 22 00:22:12 volumio volumio[1928]: info: ----- Volumio3 ---- Feb 22 00:22:12 volumio volumio[1928]: info: ------------------------------------------- Feb 22 00:22:12 volumio volumio[1928]: info: ----- System startup ---- Feb 22 00:22:12 volumio volumio[1928]: info: ------------------------------------------- Feb 22 00:22:13 volumio volumio[1928]: info: MYVOLUMIO Environment detected Feb 22 00:22:13 volumio volumio[1928]: info: Plugin folders cleanup Feb 22 00:22:13 volumio volumio[1928]: info: Scanning into folder /volumio/app/plugins/ Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category audio_interface Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category miscellanea Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category music_service Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category plugins.json Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category system_controller Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category user_interface Feb 22 00:22:13 volumio volumio[1928]: info: Scanning into folder /data/plugins/ Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category music_service Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category system_hardware Feb 22 00:22:13 volumio volumio[1928]: info: Scanning category user_interface Feb 22 00:22:13 volumio volumio[1928]: info: Plugin folders cleanup completed Feb 22 00:22:13 volumio volumio[1928]: info: ------------------------------------------- Feb 22 00:22:13 volumio volumio[1928]: info: ----- Core plugins startup ---- Feb 22 00:22:13 volumio volumio[1928]: info: ------------------------------------------- Feb 22 00:22:13 volumio volumio[1928]: info: Loading plugins from folder /volumio/app/plugins/ Feb 22 00:22:13 volumio volumio[1928]: info: Adding plugin upnp to MyMusic Plugins Feb 22 00:22:13 volumio volumio[1928]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 22 00:22:13 volumio volumio[1928]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 22 00:22:13 volumio volumio[1928]: info: Loading plugins from folder /data/plugins/ Feb 22 00:22:13 volumio volumio[1928]: info: Loading plugin "system"... Feb 22 00:22:13 volumio volumio[1928]: info: Loading plugin "appearance"... Feb 22 00:22:14 volumio volumio[1928]: info: Loading plugin "network"... Feb 22 00:22:14 volumio volumio[1928]: info: Refreshing Cached IP Addresses Feb 22 00:22:14 volumio sudo[1971]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:22:14 volumio sudo[1971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:14 volumio sudo[1970]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:22:14 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:14 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:14 volumio volumio[1928]: info: Loading plugin "services"... Feb 22 00:22:14 volumio volumio[1928]: info: Loading plugin "alsa_controller"... Feb 22 00:22:14 volumio sudo[1971]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:14 volumio sudo[1977]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 22 00:22:14 volumio sudo[1977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:14 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:22:14 volumio volumio[1928]: info: Loading plugin "wizard"... Feb 22 00:22:14 volumio volumio[1928]: info: Loading plugin "networkfs"... Feb 22 00:22:14 volumio volumio[1928]: info: Starting Udev Watcher for removable devices Feb 22 00:22:14 volumio volumio[1928]: info: Ignoring mount for partition: boot Feb 22 00:22:14 volumio volumio[1928]: info: Ignoring mount for partition: volumio Feb 22 00:22:14 volumio volumio[1928]: info: Ignoring mount for partition: volumio_data Feb 22 00:22:14 volumio volumio[1928]: info: Mounting Device 952C-A2F0 Feb 22 00:22:14 volumio sudo[2008]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/952C-A2F0 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 00:22:14 volumio sudo[2008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:14 volumio sudo[2008]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:14 volumio volumio[1928]: mount: /media/952C-A2F0: /dev/sdb1 already mounted on /media/952C-A2F0. Feb 22 00:22:14 volumio volumio[1928]: dmesg(1) may have more information after failed mount system call. Feb 22 00:22:14 volumio volumio[1928]: error: Failed to mount 952C-A2F0: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/952C-A2F0" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 00:22:14 volumio volumio[1928]: mount: /media/952C-A2F0: /dev/sdb1 already mounted on /media/952C-A2F0. Feb 22 00:22:14 volumio volumio[1928]: dmesg(1) may have more information after failed mount system call. Feb 22 00:22:14 volumio volumio[1928]: info: Mounting Device New_Volume Feb 22 00:22:14 volumio sudo[2016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/New_Volume -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 00:22:14 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:15 volumio volumio-remote-updater[833]: [2026-02-22 00:22:15] [connect] Successful connection Feb 22 00:22:15 volumio ntfs-3g[2021]: Version 2022.10.3 integrated FUSE 28 Feb 22 00:22:15 volumio ntfs-3g[2021]: Mounted /dev/sda1 (Read-Write, label "New Volume", NTFS 3.1) Feb 22 00:22:15 volumio ntfs-3g[2021]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 22 00:22:15 volumio ntfs-3g[2021]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Feb 22 00:22:15 volumio ntfs-3g[2021]: Global ownership and permissions enforced, configuration type 7 Feb 22 00:22:15 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:15 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 00:22:15 volumio volumio[1928]: info: Loading plugin "volumio_command_line_client"... Feb 22 00:22:15 volumio volumio[1928]: info: Loading plugin "upnp"... Feb 22 00:22:15 volumio volumio[1928]: info: [1771699935461] Starting Upmpd Daemon Feb 22 00:22:15 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 00:22:15 volumio volumio[1928]: info: Loading plugin "my_music"... Feb 22 00:22:15 volumio volumio[1928]: info: Loading plugin "mpd"... Feb 22 00:22:15 volumio go-librespot[1950]: time="2026-02-22T00:22:15+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:15 volumio volumio[1928]: info: Loading plugin "upnp_browser"... Feb 22 00:22:16 volumio volumio[1928]: info: Loading plugin "alarm-clock"... Feb 22 00:22:16 volumio volumio[1928]: info: Loading plugin "airplay_emulation"... Feb 22 00:22:16 volumio volumio[1928]: info: Starting Shairport Sync Feb 22 00:22:16 volumio volumio[1928]: info: Loading plugin "last_100"... Feb 22 00:22:16 volumio volumio[1928]: info: Loading plugin "webradio"... Feb 22 00:22:16 volumio volumio[1928]: info: Loading plugin "i2s_dacs"... Feb 22 00:22:17 volumio volumio[1928]: info: Loading plugin "volumiodiscovery"... Feb 22 00:22:17 volumio sudo[1977]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:17 volumio volumio[1928]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 00:22:17 volumio volumio[1928]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 00:22:17 volumio volumio[1928]: *** WARNING *** For more information see Feb 22 00:22:17 volumio volumio[1928]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 00:22:17 volumio volumio[1928]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 00:22:17 volumio node[1928]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 22 00:22:17 volumio volumio[1928]: *** WARNING *** For more information see Feb 22 00:22:17 volumio node[1928]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 00:22:17 volumio node[1928]: *** WARNING *** For more information see Feb 22 00:22:17 volumio node[1928]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 22 00:22:17 volumio node[1928]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 22 00:22:17 volumio node[1928]: *** WARNING *** For more information see Feb 22 00:22:17 volumio volumio[1928]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 22 00:22:17 volumio volumio[1928]: info: Discovery: Started advertising with name: Volumio Feb 22 00:22:17 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 22 00:22:17 volumio volumio[1928]: info: Loading plugin "calmradio"... Feb 22 00:22:18 volumio volumio[1928]: info: Loading plugin "soundcloud"... Feb 22 00:22:18 volumio volumio[1928]: info: Loading plugin "spop"... Feb 22 00:22:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Feb 22 00:22:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:19 volumio go-librespot[2029]: go-librespot daemon starting... Feb 22 00:22:19 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:19 volumio go-librespot[2030]: time="2026-02-22T00:22:19+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:19 volumio go-librespot[2030]: time="2026-02-22T00:22:19+05:30" level=debug msg="app state loaded" Feb 22 00:22:19 volumio go-librespot[2030]: time="2026-02-22T00:22:19+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:19 volumio go-librespot[2030]: time="2026-02-22T00:22:19+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:19 volumio volumio[1928]: info: Loading plugin "youtube2"... Feb 22 00:22:20 volumio volumio[1928]: info: Loading plugin "ytcr"... Feb 22 00:22:21 volumio volumio[1928]: info: Loading plugin "ytmusic"... Feb 22 00:22:21 volumio volumio[1928]: info: Loading plugin "now_playing"... Feb 22 00:22:22 volumio go-librespot[2030]: time="2026-02-22T00:22:22+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "outputs"... Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "albumart"... Feb 22 00:22:23 volumio volumio[1928]: info: Plugin example_plugin is not enabled Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "inputs"... Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "updater_comm"... Feb 22 00:22:23 volumio volumio[1928]: info: Plugin mpdemulation is not enabled Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "rest_api"... Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "websocket"... Feb 22 00:22:23 volumio volumio[1928]: info: Starting Socket.io Server version 1.7.4 Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "motherearthradio"... Feb 22 00:22:23 volumio volumio[1928]: info: Applying required configuration parameters for plugin motherearthradio Feb 22 00:22:23 volumio volumio[1928]: info: [1771699943737] [MotherEarth] API delay: 5 Feb 22 00:22:23 volumio volumio[1928]: info: Loading plugin "radio_paradise"... Feb 22 00:22:24 volumio volumio[2036]: Forking 3 albumart workers Feb 22 00:22:24 volumio volumio[1928]: info: Applying required configuration parameters for plugin radio_paradise Feb 22 00:22:24 volumio volumio[1928]: info: [1771699944541] [RadioParadise] API delay: 5 Feb 22 00:22:24 volumio volumio[1928]: info: Loading plugin "music_services_shield"... Feb 22 00:22:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Feb 22 00:22:25 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:25 volumio go-librespot[2095]: go-librespot daemon starting... Feb 22 00:22:25 volumio go-librespot[2096]: time="2026-02-22T00:22:25+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:25 volumio go-librespot[2096]: time="2026-02-22T00:22:25+05:30" level=debug msg="app state loaded" Feb 22 00:22:25 volumio go-librespot[2096]: time="2026-02-22T00:22:25+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:25 volumio go-librespot[2096]: time="2026-02-22T00:22:25+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:25 volumio volumio[1928]: info: Applying required configuration parameters for plugin music_services_shield Feb 22 00:22:25 volumio volumio[1928]: info: Loading i18n strings for locale en Feb 22 00:22:25 volumio volumio[1928]: Updating browse sources language Feb 22 00:22:25 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::initPlayerControls Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 00:22:26 volumio volumio[1928]: Express server listening on port 3000 Feb 22 00:22:26 volumio volumio[1928]: [Metrics] WebUI: 14s 170.67ms Feb 22 00:22:26 volumio volumio[1928]: info: CoreStateMachine::resetVolumioState Feb 22 00:22:26 volumio volumio[1928]: info: CoreStateMachine::getcurrentVolume Feb 22 00:22:26 volumio volumio[1928]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 00:22:26 volumio sudo[2112]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:22:26 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:27 volumio sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:22:27 volumio sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:27 volumio sudo[2111]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:27 volumio volumio[1928]: info: Volumio Network Manager: Network status updated: 1 Feb 22 00:22:27 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:27 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Feb 22 00:22:27 volumio volumio-remote-updater[833]: [2026-02-22 00:22:27] [connect] Successful connection Feb 22 00:22:27 volumio volumio[1928]: info: VolumeController:: Volume=100 Mute =false Feb 22 00:22:27 volumio volumio[1928]: info: CoreStateMachine::pushState Feb 22 00:22:27 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:27 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:22:27 volumio volumio[1928]: info: CoreCommandRouter::volumioPushState Feb 22 00:22:27 volumio volumio[1928]: info: CoreStateMachine::updateTrackBlock Feb 22 00:22:27 volumio volumio[1928]: info: CorePlayQueue::getTrackBlock Feb 22 00:22:27 volumio volumio[1928]: info: CoreCommandRouter::volumioRetrievevolume Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:28 volumio volumio[1928]: info: Executing start script for DAC IQaudIO DAC Plus Feb 22 00:22:28 volumio volumio[1928]: info: Reloading queue from file Feb 22 00:22:28 volumio volumio[1928]: info: CoreStateMachine::setRepeat false single undefined Feb 22 00:22:28 volumio volumio[1928]: info: CoreStateMachine::pushState Feb 22 00:22:28 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::volumioPushState Feb 22 00:22:28 volumio volumio[1928]: info: CoreStateMachine::setRandom false Feb 22 00:22:28 volumio volumio[1928]: info: CoreStateMachine::pushState Feb 22 00:22:28 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::volumioPushState Feb 22 00:22:28 volumio volumio[1928]: info: Setting Device type: Raspberry PI Feb 22 00:22:28 volumio volumio[1928]: info: DAC script executed Feb 22 00:22:28 volumio volumio[1928]: info: Completed loading Core Plugins Feb 22 00:22:28 volumio volumio[1928]: info: Preparing to generate the ALSA configuration file Feb 22 00:22:28 volumio go-librespot[2096]: time="2026-02-22T00:22:28+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:28 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:28 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:28 volumio volumio[1928]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 22 00:22:28 volumio volumio[1928]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Feb 22 00:22:28 volumio volumio[1928]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:28 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:28 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:28 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:29 volumio volumio-remote-updater[833]: [2026-02-22 00:22:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771699947 101 Feb 22 00:22:29 volumio volumio[1928]: 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 Feb 22 00:22:29 volumio volumio[1928]: info: VolumeController:: Volume=100 Mute =false Feb 22 00:22:29 volumio volumio[1928]: info: CoreStateMachine::pushState Feb 22 00:22:29 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:29 volumio volumio[1928]: info: CoreCommandRouter::volumioPushState Feb 22 00:22:29 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Feb 22 00:22:29 volumio volumio[1928]: info: Asound.conf file unchanged, so no further update is needed Feb 22 00:22:29 volumio volumio[1928]: info: Output device has changed, restarting MPD Feb 22 00:22:29 volumio sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 22 00:22:29 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:29 volumio volumio[1928]: info: Output device has changed, restarting Shairport Sync Feb 22 00:22:29 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:29 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:29 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:29 volumio sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 00:22:29 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:29 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:29 volumio volumio[2047]: Starting albumart workers Feb 22 00:22:29 volumio sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 00:22:29 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:29 volumio volumio[1928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 00:22:29 volumio volumio[1928]: info: ___________ START PLUGINS ___________ Feb 22 00:22:29 volumio volumio[1928]: info: ControllerMpd::onStart: Initializing MPD Feb 22 00:22:29 volumio volumio[1928]: info: Creating MPD Configuration file Feb 22 00:22:29 volumio volumio[2053]: Starting albumart workers Feb 22 00:22:29 volumio volumio[2046]: Starting albumart workers Feb 22 00:22:29 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Feb 22 00:22:29 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 22 00:22:29 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 22 00:22:29 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 00:22:29 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 22 00:22:29 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:29 volumio volumio[1928]: info: [1771699949822] CoreMusicLibrary::Adding element Media Servers Feb 22 00:22:29 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:29 volumio sudo[2144]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 00:22:29 volumio sudo[2144]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:29 volumio sudo[2142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 00:22:29 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:29 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:30 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 22 00:22:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 00:22:30 volumio systemd[1]: mpd.service: Consumed 3.739s CPU time. Feb 22 00:22:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 22 00:22:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 00:22:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 00:22:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 00:22:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 00:22:30 volumio sudo[2150]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 00:22:30 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 00:22:30 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:30 volumio volumio[1928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:30 volumio volumio[1928]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:30 volumio volumio[1928]: info: [1771699950463] CoreMusicLibrary::Adding element Last_100 Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:30 volumio volumio[1928]: info: [1771699950465] CoreMusicLibrary::Adding element Webradio Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:30 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:22:30 volumio volumio[1928]: info: Initializing BBC Radios Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:31 volumio volumio[1928]: info: Adding Calm Radio to Browse Sources Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:31 volumio volumio[1928]: info: [1771699951294] CoreMusicLibrary::Adding element Calm Radio Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:31 volumio volumio[1928]: Cannot find translation for source Calm Radio Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:31 volumio volumio[1928]: info: [1771699951434] CoreMusicLibrary::Adding element SoundCloud Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:31 volumio volumio[1928]: Cannot find translation for source Calm Radio Feb 22 00:22:31 volumio volumio[1928]: Cannot find translation for source SoundCloud Feb 22 00:22:31 volumio volumio[1928]: info: Creating Spotify config file Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:31 volumio volumio[1928]: info: [1771699951625] CoreMusicLibrary::Adding element YouTube2 Feb 22 00:22:31 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:31 volumio volumio[1928]: Cannot find translation for source Calm Radio Feb 22 00:22:31 volumio volumio[1928]: Cannot find translation for source SoundCloud Feb 22 00:22:31 volumio volumio[1928]: Cannot find translation for source YouTube2 Feb 22 00:22:31 volumio mpd[2154]: 2026-02-22T00:22:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 00:22:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Feb 22 00:22:31 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:31 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:31 volumio go-librespot[2172]: go-librespot daemon starting... Feb 22 00:22:31 volumio go-librespot[2173]: time="2026-02-22T00:22:31+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:32 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 00:22:32 volumio sudo[2144]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:32 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Feb 22 00:22:32 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:32 volumio volumio[1928]: info: [1771699952057] CoreMusicLibrary::Adding element YouTube Music Feb 22 00:22:32 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source Calm Radio Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source SoundCloud Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source YouTube2 Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source YouTube Music Feb 22 00:22:32 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:32 volumio volumio[1928]: info: [now-playing] ConfigUpdater: config is up to date. Feb 22 00:22:32 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:32 volumio volumio[1928]: info: [1771699952093] CoreMusicLibrary::Adding element Mother Earth Radio Feb 22 00:22:32 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source Calm Radio Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source SoundCloud Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source YouTube2 Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source YouTube Music Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source Mother Earth Radio Feb 22 00:22:32 volumio volumio[1928]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 22 00:22:32 volumio volumio[1928]: info: [1771699952222] CoreMusicLibrary::Adding element Radio Paradise Feb 22 00:22:32 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source Calm Radio Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source SoundCloud Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source YouTube2 Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source YouTube Music Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source Mother Earth Radio Feb 22 00:22:32 volumio volumio[1928]: Cannot find translation for source Radio Paradise Feb 22 00:22:32 volumio bash[1655]: setdatetime-helper: all HTTPS Date fallbacks failed Feb 22 00:22:32 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 22 00:22:32 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 22 00:22:32 volumio bash[2219]: cset: --> shielding system active with Feb 22 00:22:32 volumio bash[2219]: cset: "system" cpuset of CPUSPEC(1-3) with 153 tasks running Feb 22 00:22:32 volumio bash[2219]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Feb 22 00:22:33 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Feb 22 00:22:33 volumio volumio[1928]: info: Volumio Calling Home Feb 22 00:22:33 volumio sudo[2229]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Feb 22 00:22:33 volumio sudo[2229]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:34 volumio volumio[1928]: info: [now-playing] App is listening on port 4004. Feb 22 00:22:34 volumio volumio[1928]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 22 00:22:34 volumio volumio[1928]: info: Discovery: adding 79e42f3a-b6c7-45d2-beac-ef3bed22036e Feb 22 00:22:34 volumio volumio[1928]: info: Discovery: Found device Volumio Feb 22 00:22:34 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:34 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:34 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:34 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:34 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:34 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:34 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:34 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:34 volumio volumio[1928]: error: Upnp client error: Error: This socket has been ended by the other party Feb 22 00:22:34 volumio volumio[1928]: info: MPD Permissions set Feb 22 00:22:34 volumio volumio[1928]: info: MPD Permissions set Feb 22 00:22:34 volumio volumio[1928]: info: Upmpdcli Daemon Started Feb 22 00:22:34 volumio volumio[1928]: info: Discovery: this is already registered, 79e42f3a-b6c7-45d2-beac-ef3bed22036e Feb 22 00:22:34 volumio volumio[1928]: info: Discovery: Found device Volumio Feb 22 00:22:34 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:34 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:35 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Feb 22 00:22:35 volumio volumio[1928]: info: Spotify config file written Feb 22 00:22:35 volumio go-librespot[2173]: time="2026-02-22T00:22:35+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:35 volumio sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 22 00:22:35 volumio sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:35 volumio volumio[1928]: error: MPD error: The expression evaluated to a falsy value: Feb 22 00:22:35 volumio volumio[1928]: assert.ok(self.idling) Feb 22 00:22:35 volumio volumio[1928]: error: The expression evaluated to a falsy value: Feb 22 00:22:35 volumio volumio[1928]: assert.ok(self.idling) Feb 22 00:22:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:35 volumio go-librespot[2280]: go-librespot daemon starting... Feb 22 00:22:35 volumio sudo[2278]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:35 volumio go-librespot[2281]: time="2026-02-22T00:22:35+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:35 volumio go-librespot[2281]: time="2026-02-22T00:22:35+05:30" level=debug msg="app state loaded" Feb 22 00:22:35 volumio go-librespot[2281]: time="2026-02-22T00:22:35+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:35 volumio go-librespot[2281]: time="2026-02-22T00:22:35+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:35 volumio volumio[1928]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 00:22:35 volumio volumio[1928]: info: No need to fix Spotify hosts Feb 22 00:22:35 volumio volumio[1928]: error: updateQueue error: null Feb 22 00:22:35 volumio volumio[1928]: info: MPD running with PID2154 Feb 22 00:22:35 volumio volumio[1928]: ,establishing connection Feb 22 00:22:35 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Feb 22 00:22:35 volumio volumio[1928]: info: Starting Shairport Sync Feb 22 00:22:35 volumio volumio[1928]: info: Starting Shairport Sync Feb 22 00:22:35 volumio volumio[1928]: info: Starting Shairport Sync Feb 22 00:22:35 volumio sudo[2300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 00:22:35 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:35 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:35 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:35 volumio sudo[2302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 00:22:35 volumio sudo[2302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:35 volumio volumio[1928]: error: updateQueue error: null Feb 22 00:22:35 volumio sudo[2305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 00:22:35 volumio sudo[2305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 00:22:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 00:22:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:22:35 volumio systemd[1]: shairport-sync.service: Consumed 1.599s CPU time. Feb 22 00:22:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:35 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:35 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 00:22:35 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 00:22:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:22:35 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 00:22:35 volumio sudo[2305]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:35 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 22 00:22:35 volumio sudo[2302]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 00:22:35 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 00:22:36 volumio volumio[1928]: info: Shairport-Sync Started Feb 22 00:22:36 volumio volumio[1928]: Error adding Membership: Error: addMembership EINVAL Feb 22 00:22:36 volumio volumio[1928]: info: Shairport-Sync Started Feb 22 00:22:36 volumio volumio[1928]: info: Shairport-Sync Started Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:36 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:36 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:36 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 00:22:36 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:36 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:36 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:36 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:36 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 22 00:22:36 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 22 00:22:37 volumio sudo[2333]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 00:22:37 volumio sudo[2333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:37 volumio sudo[2327]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 22 00:22:37 volumio sudo[2338]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 00:22:37 volumio sudo[2338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:37 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:37 volumio sudo[2333]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:37 volumio sudo[2341]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 22 00:22:37 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:37 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:37 volumio sudo[2338]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:37 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:37 volumio sudo[2341]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:37 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:37 volumio sudo[2350]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:22:37 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:37 volumio sudo[2348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:22:37 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:37 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:37 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:37 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:37 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:37 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:37 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:37 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 22 00:22:37 volumio volumio[1928]: info: Discovery: Getting this device information Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:37 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::volumioGetState Feb 22 00:22:37 volumio volumio[1928]: info: CorePlayQueue::getTrack 0 Feb 22 00:22:37 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248:3000 from 192.168.29.183 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: (node:1928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 22 00:22:37 volumio volumio[1928]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 22 00:22:37 volumio volumio[1928]: (node:1928) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 22 00:22:37 volumio volumio[1928]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 22 00:22:38 volumio sudo[2362]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 00:22:38 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:38 volumio sudo[2361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 22 00:22:38 volumio sudo[2361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:38 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Feb 22 00:22:38 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Feb 22 00:22:38 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Feb 22 00:22:38 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Feb 22 00:22:38 volumio mpd_monitor.sh[2365]: MPD Monitor Service: Starting MPD Monitor Service Feb 22 00:22:38 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:38 volumio sudo[2361]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:38 volumio volumio[1928]: info: Successfully started MPD Monitor Feb 22 00:22:38 volumio volumio[1928]: info: Successfully started MPD Monitor Feb 22 00:22:38 volumio volumio[1928]: info: go-librespot daemon successfully initialized Feb 22 00:22:38 volumio sudo[2229]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:38 volumio go-librespot[2281]: time="2026-02-22T00:22:38+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:38 volumio sudo[2371]: volumio : PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Feb 22 00:22:38 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:38 volumio systemd[1]: Reloading. Feb 22 00:22:39 volumio systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Feb 22 00:22:39 volumio sudo[2394]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:22:39 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:39 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:39 volumio sudo[2396]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:22:39 volumio sudo[2396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:39 volumio sudo[2396]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:39 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248 from 192.168.29.183 UA: Mozilla/5.0 (Linux; Android 15; V2222 Build/AP3A.240905.015.A2_CS; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Feb 22 00:22:39 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. Feb 22 00:22:39 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. Feb 22 00:22:39 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:39 volumio volumio[1928]: info: succeeded Feb 22 00:22:40 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Feb 22 00:22:40 volumio sudo[2418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 22 00:22:40 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:40 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:40 volumio sudo[2421]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 22 00:22:40 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 00:22:40 volumio sudo[2421]: pam_unix(sudo:session): session closed for user root Feb 22 00:22:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Feb 22 00:22:41 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:41 volumio volumio[1928]: info: Initializing connection to go-librespot Websocket Feb 22 00:22:41 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:41 volumio go-librespot[2424]: go-librespot daemon starting... Feb 22 00:22:41 volumio go-librespot[2425]: time="2026-02-22T00:22:41+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:41 volumio go-librespot[2425]: time="2026-02-22T00:22:41+05:30" level=debug msg="app state loaded" Feb 22 00:22:41 volumio go-librespot[2425]: time="2026-02-22T00:22:41+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:41 volumio go-librespot[2425]: time="2026-02-22T00:22:41+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:41 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248 from 192.168.29.183 UA: Mozilla/5.0 (Linux; Android 15; V2222 Build/AP3A.240905.015.A2_CS; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 22 00:22:42 volumio volumio[1928]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (mdjjvfp3a64ni6qgip1v2i67br): Feb 22 00:22:42 volumio volumio[1928]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Feb 22 00:22:42 volumio volumio[1928]: Error info: { Feb 22 00:22:42 volumio volumio[1928]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Feb 22 00:22:42 volumio volumio[1928]: } Feb 22 00:22:42 volumio volumio[1928]: --->(TypeError) fetch failed Feb 22 00:22:42 volumio volumio[1928]: Stack trace: Feb 22 00:22:42 volumio volumio[1928]: ConnectionError: (YouTube Music) Connection error in getting lounge token Feb 22 00:22:42 volumio volumio[1928]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:440:15) Feb 22 00:22:42 volumio volumio[1928]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:22:42 volumio volumio[1928]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:130:31) Feb 22 00:22:42 volumio volumio[1928]: at async Promise.all (index 1) Feb 22 00:22:42 volumio volumio[1928]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:114:13) Feb 22 00:22:42 volumio volumio[1928]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:22:42 volumio volumio[1928]: Going to generate fresh screen Id and try again... Feb 22 00:22:42 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Feb 22 00:22:42 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Feb 22 00:22:42 volumio systemd[1]: apt-daily-upgrade.service: Consumed 1.245s CPU time. Feb 22 00:22:42 volumio go-librespot[2425]: time="2026-02-22T00:22:42+05:30" level=debug msg="new websocket client" Feb 22 00:22:42 volumio volumio[1928]: info: Connection to go-librespot Websocket established Feb 22 00:22:42 volumio volumio[1928]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (uft857gjn46lqqdh8r74ddvf1c): Feb 22 00:22:42 volumio volumio[1928]: (ConnectionError) (YouTube) Connection error in getting lounge token Feb 22 00:22:42 volumio volumio[1928]: Error info: { Feb 22 00:22:42 volumio volumio[1928]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Feb 22 00:22:42 volumio volumio[1928]: } Feb 22 00:22:42 volumio volumio[1928]: --->(TypeError) fetch failed Feb 22 00:22:42 volumio volumio[1928]: Stack trace: Feb 22 00:22:42 volumio volumio[1928]: ConnectionError: (YouTube) Connection error in getting lounge token Feb 22 00:22:42 volumio volumio[1928]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:440:15) Feb 22 00:22:42 volumio volumio[1928]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 22 00:22:42 volumio volumio[1928]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:130:31) Feb 22 00:22:42 volumio volumio[1928]: at async Promise.all (index 0) Feb 22 00:22:42 volumio volumio[1928]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:114:13) Feb 22 00:22:42 volumio volumio[1928]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Feb 22 00:22:42 volumio volumio[1928]: Going to generate fresh screen Id and try again... Feb 22 00:22:43 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248 from 192.168.29.183 UA: Mozilla/5.0 (Linux; Android 15; V2222 Build/AP3A.240905.015.A2_CS; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 22 00:22:44 volumio go-librespot[2425]: time="2026-02-22T00:22:44+05:30" 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\": dial tcp 35.186.224.24:443: connect: no route to host" Feb 22 00:22:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 22 00:22:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 22 00:22:45 volumio volumio[1928]: info: Connection to go-librespot Websocket closed Feb 22 00:22:45 volumio volumio[1928]: info: Getting Spotify volume Feb 22 00:22:46 volumio volumio[1928]: verbose: New Socket.io Connection to 192.168.29.248 from 192.168.29.183 UA: Mozilla/5.0 (Linux; Android 15; V2222 Build/AP3A.240905.015.A2_CS; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 22 00:22:46 volumio volumio[1928]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 00:22:46 volumio volumio[1928]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 22 00:22:46 volumio volumio[1928]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 22 00:22:46 volumio volumio[1928]: errno: -111, Feb 22 00:22:46 volumio volumio[1928]: code: 'ECONNREFUSED', Feb 22 00:22:46 volumio volumio[1928]: syscall: 'connect', Feb 22 00:22:46 volumio volumio[1928]: address: '127.0.0.1', Feb 22 00:22:46 volumio volumio[1928]: port: 9879, Feb 22 00:22:46 volumio volumio[1928]: response: undefined Feb 22 00:22:46 volumio volumio[1928]: } Feb 22 00:22:46 volumio volumio[1928]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 00:22:47 volumio dhcpcd[707]: eth0: offered 192.168.29.248 from 192.168.29.1 Feb 22 00:22:47 volumio dhcpcd[707]: eth0: leased 192.168.29.248 for 86400 seconds Feb 22 00:22:47 volumio dhcpcd[707]: eth0: adding route to 192.168.29.0/24 Feb 22 00:22:47 volumio dhcpcd[707]: eth0: changing default route via 192.168.29.1 Feb 22 00:22:47 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 22 00:22:47 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 22 00:22:47 volumio systemd[1]: welcome.service: Deactivated successfully. Feb 22 00:22:47 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 22 00:22:47 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 22 00:22:47 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 22 00:22:47 volumio welcome[2508]: Resolved ip:[2] 192.168.29.248 169.254.199.103 Feb 22 00:22:47 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 22 00:22:47 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 22 00:22:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Feb 22 00:22:48 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:48 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 22 00:22:48 volumio go-librespot[2513]: go-librespot daemon starting... Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=info msg="running go-librespot 0.4.0" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=debug msg="app state loaded" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=debug msg="stored credentials not found" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=info msg="api server listening on 127.0.0.1:9879" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Feb 22 00:22:48 volumio go-librespot[2514]: time="2026-02-22T00:22:48+05:30" level=info msg="zeroconf server listening on port 38717" Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 14.139.60.107 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 95.216.144.226 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 139.84.142.141 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 172.235.18.237 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 142.93.213.206 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 172.236.180.15 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 95.216.192.15 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 17.253.18.131 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 192.46.215.60 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio ntpd[1034]: PROTO: 192.46.210.39 unlink local addr 169.254.199.103 -> 192.168.29.248 Feb 22 00:22:49 volumio sudo[2523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 00:21' Feb 22 00:22:49 volumio sudo[2523]: 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="ec1ce6b3390e13e82b9cc0453f957c4f5298419e" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Nov 17 16:31:40 UTC 2025" VOLUMIO_VERSION="4.071" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5fbe1bcf6936184712979c0bfaf061f2"