Feb 25 03:06:00 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Feb 25 03:06:00 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:00 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:00 salon go-librespot[9995]: go-librespot daemon starting... Feb 25 03:06:01 salon go-librespot[9996]: time="2026-02-25T03:06:01+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:01 salon go-librespot[9996]: time="2026-02-25T03:06:01+01:00" level=debug msg="app state loaded" Feb 25 03:06:01 salon go-librespot[9996]: time="2026-02-25T03:06:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:03 salon volumio-remote-updater[585]: [2026-02-25 03:06:03] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Feb 25 03:06:03 salon volumio-remote-updater[585]: [2026-02-25 03:06:03] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Feb 25 03:06:03 salon systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:03 salon systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 25 03:06:03 salon systemd[1]: volumio.service: Consumed 1min 7.546s CPU time. Feb 25 03:06:03 salon systemd[1]: Started dynamicswap.service - dynamicswap service. Feb 25 03:06:03 salon systemd[1]: volumio.service: Scheduled restart job, restart counter is at 75. Feb 25 03:06:03 salon systemd[1]: Stopped volumio.service - Volumio Backend Module. Feb 25 03:06:03 salon systemd[1]: volumio.service: Consumed 1min 7.546s CPU time. Feb 25 03:06:03 salon systemd[1]: Started volumio.service - Volumio Backend Module. Feb 25 03:06:03 salon systemd[1]: dynamicswap.service: Deactivated successfully. Feb 25 03:06:05 salon kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Feb 25 03:06:05 salon dhcpcd[667]: eth0: carrier lost Feb 25 03:06:05 salon dhcpcd[613]: eth0: carrier lost Feb 25 03:06:06 salon dhcpcd[613]: eth0: deleting route to 192.168.1.0/24 Feb 25 03:06:06 salon dhcpcd[613]: eth0: deleting default route via 192.168.1.254 Feb 25 03:06:06 salon dhcpcd[667]: eth0: deleting route to 192.168.1.0/24 Feb 25 03:06:06 salon dhcpcd[667]: eth0: deleting default route via 192.168.1.254 Feb 25 03:06:06 salon avahi-daemon[568]: Withdrawing address record for 192.168.1.2 on eth0. Feb 25 03:06:06 salon avahi-daemon[568]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.2. Feb 25 03:06:06 salon avahi-daemon[568]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 25 03:06:06 salon systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 03:06:06 salon systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 25 03:06:06 salon systemd[1]: welcome.service: Deactivated successfully. Feb 25 03:06:06 salon systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 25 03:06:06 salon systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 25 03:06:06 salon systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 25 03:06:06 salon welcome[10081]: Resolved ip:[0] Feb 25 03:06:06 salon systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 25 03:06:06 salon systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 03:06:06 salon ifplugd(eth0)[938]: Link beat lost. Feb 25 03:06:07 salon go-librespot[9996]: time="2026-02-25T03:06:07+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:07 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:07 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:07 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:07 salon volumio[10013]: info: ----- Volumio3 ---- Feb 25 03:06:07 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:07 salon volumio[10013]: info: ----- System startup ---- Feb 25 03:06:07 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:08 salon ntpd[915]: IO: Deleting interface #3 eth0, 192.168.1.2#123, interface stats: received=20844, sent=20969, dropped=0, active_time=3014642 secs Feb 25 03:06:08 salon ntpd[915]: PROTO: 45.13.105.44 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon ntpd[915]: PROTO: 37.59.63.125 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon ntpd[915]: PROTO: 38.143.19.16 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon ntpd[915]: PROTO: 176.137.36.37 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon ntpd[915]: PROTO: 162.159.200.1 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon ntpd[915]: PROTO: 51.91.83.168 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon ntpd[915]: PROTO: 109.190.177.203 unlink local addr 192.168.1.2 -> Feb 25 03:06:08 salon volumio-remote-updater[585]: [2026-02-25 03:06:08] [connect] Successful connection Feb 25 03:06:08 salon volumio[10013]: info: MYVOLUMIO Environment detected Feb 25 03:06:08 salon volumio[10013]: info: Plugin folders cleanup Feb 25 03:06:08 salon volumio[10013]: info: Scanning into folder /volumio/app/plugins/ Feb 25 03:06:08 salon volumio[10013]: info: Scanning category audio_interface Feb 25 03:06:09 salon volumio[10013]: info: Scanning category miscellanea Feb 25 03:06:09 salon volumio[10013]: info: Scanning category music_service Feb 25 03:06:09 salon volumio[10013]: info: Scanning category plugins.json Feb 25 03:06:09 salon volumio[10013]: info: Scanning category system_controller Feb 25 03:06:09 salon volumio[10013]: info: Scanning category user_interface Feb 25 03:06:09 salon volumio[10013]: info: Scanning into folder /data/plugins/ Feb 25 03:06:09 salon volumio[10013]: info: Scanning category audio_interface Feb 25 03:06:09 salon volumio[10013]: info: Scanning category music_service Feb 25 03:06:09 salon volumio[10013]: info: Scanning category system_controller Feb 25 03:06:09 salon volumio[10013]: info: Scanning category system_hardware Feb 25 03:06:09 salon volumio[10013]: info: Scanning category user_interface Feb 25 03:06:09 salon volumio[10013]: info: Plugin folders cleanup completed Feb 25 03:06:09 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:09 salon volumio[10013]: info: ----- Core plugins startup ---- Feb 25 03:06:09 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:09 salon volumio[10013]: info: Loading plugins from folder /volumio/app/plugins/ Feb 25 03:06:09 salon volumio[10013]: info: Adding plugin upnp to MyMusic Plugins Feb 25 03:06:09 salon volumio[10013]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 25 03:06:09 salon volumio[10013]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 25 03:06:09 salon volumio[10013]: info: Loading plugins from folder /data/plugins/ Feb 25 03:06:09 salon volumio[10013]: info: Loading plugin "system"... Feb 25 03:06:09 salon volumio[10013]: info: Loading plugin "appearance"... Feb 25 03:06:10 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Feb 25 03:06:10 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:10 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:10 salon go-librespot[10098]: go-librespot daemon starting... Feb 25 03:06:10 salon go-librespot[10099]: time="2026-02-25T03:06:10+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:10 salon go-librespot[10099]: time="2026-02-25T03:06:10+01:00" level=debug msg="app state loaded" Feb 25 03:06:10 salon go-librespot[10099]: time="2026-02-25T03:06:10+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:10 salon go-librespot[10099]: time="2026-02-25T03:06:10+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:10 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:10 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "network"... Feb 25 03:06:11 salon volumio[10013]: info: Refreshing Cached IP Addresses Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "services"... Feb 25 03:06:11 salon sudo[10109]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:06:11 salon sudo[10109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "alsa_controller"... Feb 25 03:06:11 salon sudo[10109]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:11 salon sudo[10111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:06:11 salon sudo[10111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:11 salon sudo[10111]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:11 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "wizard"... Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "networkfs"... Feb 25 03:06:11 salon volumio[10013]: info: Starting Udev Watcher for removable devices Feb 25 03:06:11 salon volumio[10013]: info: Ignoring mount for partition: boot Feb 25 03:06:11 salon volumio[10013]: info: Ignoring mount for partition: volumio Feb 25 03:06:11 salon volumio[10013]: info: Ignoring mount for partition: volumio_data Feb 25 03:06:11 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "volumio_command_line_client"... Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "upnp"... Feb 25 03:06:11 salon volumio[10013]: info: [1771985171709] Starting Upmpd Daemon Feb 25 03:06:11 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "my_music"... Feb 25 03:06:11 salon volumio[10013]: info: Loading plugin "mpd"... Feb 25 03:06:12 salon volumio[10013]: info: Loading plugin "upnp_browser"... Feb 25 03:06:13 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 22. Feb 25 03:06:13 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:13 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:13 salon go-librespot[10136]: go-librespot daemon starting... Feb 25 03:06:13 salon go-librespot[10137]: time="2026-02-25T03:06:13+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:13 salon go-librespot[10137]: time="2026-02-25T03:06:13+01:00" level=debug msg="app state loaded" Feb 25 03:06:13 salon go-librespot[10137]: time="2026-02-25T03:06:13+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:13 salon go-librespot[10137]: time="2026-02-25T03:06:13+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:13 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:13 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:15 salon volumio[10013]: info: Starting UPNP Browser Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "alarm-clock"... Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "airplay_emulation"... Feb 25 03:06:15 salon volumio[10013]: info: Starting Shairport Sync Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "last_100"... Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "webradio"... Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "i2s_dacs"... Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "volumiodiscovery"... Feb 25 03:06:15 salon volumio[10013]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:06:15 salon volumio[10013]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:06:15 salon volumio[10013]: *** WARNING *** For more information see Feb 25 03:06:15 salon volumio[10013]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 03:06:15 salon volumio[10013]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:06:15 salon volumio[10013]: *** WARNING *** For more information see Feb 25 03:06:15 salon node[10013]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 25 03:06:15 salon node[10013]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:06:15 salon node[10013]: *** WARNING *** For more information see Feb 25 03:06:15 salon node[10013]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 25 03:06:15 salon node[10013]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 25 03:06:15 salon node[10013]: *** WARNING *** For more information see Feb 25 03:06:15 salon volumio[10013]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 25 03:06:15 salon volumio[10013]: info: Discovery: Started advertising with name: Salon Feb 25 03:06:15 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 25 03:06:15 salon volumio[10013]: info: Loading plugin "spop"... Feb 25 03:06:16 salon ifplugd(eth0)[938]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Feb 25 03:06:16 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Feb 25 03:06:16 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:16 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:16 salon go-librespot[10155]: go-librespot daemon starting... Feb 25 03:06:16 salon go-librespot[10156]: time="2026-02-25T03:06:16+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:16 salon go-librespot[10156]: time="2026-02-25T03:06:16+01:00" level=debug msg="app state loaded" Feb 25 03:06:16 salon go-librespot[10156]: time="2026-02-25T03:06:16+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:16 salon go-librespot[10156]: time="2026-02-25T03:06:16+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:16 salon dhcpcd[10165]: sending signal ALRM to pid 611 Feb 25 03:06:16 salon dhcpcd[10165]: waiting for pid 611 to exit Feb 25 03:06:16 salon ifplugd(eth0)[938]: client: sending signal ALRM to pid 611 Feb 25 03:06:16 salon ifplugd(eth0)[938]: client: waiting for pid 611 to exit Feb 25 03:06:16 salon dhcpcd[613]: received SIGALRM, releasing Feb 25 03:06:16 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:16 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:16 salon dhcpcd[613]: eth0: removing interface Feb 25 03:06:16 salon dhcpcd[613]: dhcpcd exited Feb 25 03:06:17 salon kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Feb 25 03:06:17 salon ifplugd(eth0)[938]: Program executed successfully. Feb 25 03:06:17 salon kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Feb 25 03:06:17 salon kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Feb 25 03:06:18 salon volumio[10013]: info: Loading plugin "ytcr"... Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: Previous ethernet state: connected Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: New ethernet state: disconnected Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: First start: no Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: Action: Reconnect WiFi Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: === END TRANSITION === Feb 25 03:06:18 salon sudo[10190]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 25 03:06:18 salon sudo[10190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:18 salon sudo[10190]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: SNM: Ethernet disconnected, reconnecting WiFi Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: reconnectWiFi: Reconnect command failed: Error: Command failed: /sbin/wpa_cli -i wlan0 reconnect Feb 25 03:06:18 salon wireless.js[587]: Failed to connect to non-global ctrl_ifname: wlan0 error: No such file or directory Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: reconnectWiFi: Falling back to full wireless flow restart Feb 25 03:06:18 salon wireless.js[587]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 25 03:06:19 salon sudo[10204]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 25 03:06:19 salon sudo[10204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:19 salon sudo[10204]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:19 salon sudo[10206]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 25 03:06:19 salon sudo[10206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:19 salon sudo[10206]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: Cleaning previous... Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: netconfigured file not found, starting hotspot Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: === EMERGENCY OVERRIDE === Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: WiFi DISABLED in config, but no ethernet available Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: Forcing hotspot for system accessibility Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: User can disable hotspot after connecting via emergency AP Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: ========================== Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: Fallback: WiFi disabled. No hotspot started. Feb 25 03:06:19 salon wireless.js[587]: WIRELESS.JS - INFO: Notified systemd about wireless ready Feb 25 03:06:19 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Feb 25 03:06:19 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:19 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:19 salon go-librespot[10209]: go-librespot daemon starting... Feb 25 03:06:20 salon go-librespot[10210]: time="2026-02-25T03:06:20+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:20 salon go-librespot[10210]: time="2026-02-25T03:06:20+01:00" level=debug msg="app state loaded" Feb 25 03:06:20 salon go-librespot[10210]: time="2026-02-25T03:06:20+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:20 salon go-librespot[10210]: time="2026-02-25T03:06:20+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:20 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:20 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:20 salon nmbd[1041]: [2026/02/25 03:06:20.174751, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Feb 25 03:06:20 salon nmbd[1041]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Feb 25 03:06:20 salon dhcpcd[667]: eth0: carrier acquired Feb 25 03:06:20 salon kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Feb 25 03:06:20 salon dhcpcd[667]: eth0: IAID eb:ee:ba:39 Feb 25 03:06:20 salon volumio-remote-updater[585]: [2026-02-25 03:06:20] [connect] Successful connection Feb 25 03:06:21 salon ifplugd(eth0)[938]: Link beat detected. Feb 25 03:06:21 salon dhcpcd[667]: eth0: soliciting an IPv6 router Feb 25 03:06:21 salon ifplugd(eth0)[938]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 25 03:06:21 salon dhcpcd[667]: eth0: rebinding lease of 192.168.1.2 Feb 25 03:06:21 salon dhcpcd[667]: control command: dhcpcd eth0 Feb 25 03:06:21 salon ifplugd(eth0)[938]: client: sending commands to dhcpcd process Feb 25 03:06:21 salon dhcpcd[667]: control_free: No such file or directory Feb 25 03:06:22 salon ifplugd(eth0)[938]: Program executed successfully. Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: === SNM TRANSITION === Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: Previous ethernet state: disconnected Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: New ethernet state: connected Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: Single Network Mode: enabled Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: First start: no Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: Action: Switch to ethernet (WiFi scan mode) Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: === END TRANSITION === Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: SNM: Ethernet connected, switching to ethernet (WiFi scan mode) Feb 25 03:06:22 salon sudo[10287]: root : PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd -k wlan0 Feb 25 03:06:22 salon sudo[10287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:22 salon dhcpcd[10288]: dhcpcd not running Feb 25 03:06:22 salon sudo[10287]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:22 salon wireless.js[587]: dhcpcd not running Feb 25 03:06:22 salon wireless.js[587]: WIRELESS.JS - INFO: Wireless.js initializing wireless flow Feb 25 03:06:23 salon sudo[10296]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 25 03:06:23 salon sudo[10296]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:23 salon sudo[10296]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:23 salon sudo[10298]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 25 03:06:23 salon sudo[10298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:23 salon sudo[10298]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:23 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Feb 25 03:06:23 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:23 salon wireless.js[587]: WIRELESS.JS - INFO: Cleaning previous... Feb 25 03:06:23 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:23 salon go-librespot[10300]: go-librespot daemon starting... Feb 25 03:06:23 salon go-librespot[10301]: time="2026-02-25T03:06:23+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:23 salon go-librespot[10301]: time="2026-02-25T03:06:23+01:00" level=debug msg="app state loaded" Feb 25 03:06:23 salon go-librespot[10301]: time="2026-02-25T03:06:23+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:23 salon go-librespot[10301]: time="2026-02-25T03:06:23+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:23 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:23 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:24 salon dhcpcd[667]: eth0: carrier lost Feb 25 03:06:24 salon kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Feb 25 03:06:25 salon ifplugd(eth0)[938]: Link beat lost. Feb 25 03:06:25 salon dhcpcd[667]: eth0: carrier acquired Feb 25 03:06:25 salon kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Feb 25 03:06:25 salon dhcpcd[667]: eth0: IAID eb:ee:ba:39 Feb 25 03:06:26 salon dhcpcd[667]: eth0: rebinding lease of 192.168.1.2 Feb 25 03:06:26 salon ifplugd(eth0)[938]: Link beat detected. Feb 25 03:06:26 salon dhcpcd[667]: eth0: soliciting an IPv6 router Feb 25 03:06:26 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Feb 25 03:06:26 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:26 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:26 salon go-librespot[10326]: go-librespot daemon starting... Feb 25 03:06:26 salon go-librespot[10327]: time="2026-02-25T03:06:26+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:26 salon go-librespot[10327]: time="2026-02-25T03:06:26+01:00" level=debug msg="app state loaded" Feb 25 03:06:26 salon go-librespot[10327]: time="2026-02-25T03:06:26+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:26 salon go-librespot[10327]: time="2026-02-25T03:06:26+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:26 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:26 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:26 salon volumio[10013]: info: Loading plugin "outputs"... Feb 25 03:06:26 salon volumio[10013]: info: Loading plugin "albumart"... Feb 25 03:06:26 salon volumio[10013]: info: Plugin example_plugin is not enabled Feb 25 03:06:26 salon volumio[10013]: info: Loading plugin "inputs"... Feb 25 03:06:26 salon volumio[10013]: info: Loading plugin "updater_comm"... Feb 25 03:06:27 salon volumio[10013]: info: Plugin mpdemulation is not enabled Feb 25 03:06:27 salon volumio[10013]: info: Loading plugin "rest_api"... Feb 25 03:06:27 salon volumio[10013]: info: Loading plugin "websocket"... Feb 25 03:06:27 salon volumio[10013]: info: Starting Socket.io Server version 1.7.4 Feb 25 03:06:27 salon volumio[10013]: info: Loading plugin "fusiondsp"... Feb 25 03:06:27 salon volumio[10013]: info: Applying required configuration parameters for plugin fusiondsp Feb 25 03:06:27 salon volumio[10336]: Forking 3 albumart workers Feb 25 03:06:28 salon volumio[10013]: info: Loading plugin "podcast"... Feb 25 03:06:28 salon volumio[10013]: info: ControllerPodcast::constructor Feb 25 03:06:28 salon volumio[10013]: info: Loading plugin "backup_restore"... Feb 25 03:06:29 salon volumio[10013]: info: Applying required configuration parameters for plugin backup_restore Feb 25 03:06:29 salon volumio[10013]: info: Loading plugin "Bluetoothremote"... Feb 25 03:06:29 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Feb 25 03:06:29 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:29 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:29 salon go-librespot[10376]: go-librespot daemon starting... Feb 25 03:06:29 salon go-librespot[10377]: time="2026-02-25T03:06:29+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:29 salon go-librespot[10377]: time="2026-02-25T03:06:29+01:00" level=debug msg="app state loaded" Feb 25 03:06:29 salon go-librespot[10377]: time="2026-02-25T03:06:29+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:29 salon go-librespot[10377]: time="2026-02-25T03:06:29+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:29 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:29 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:30 salon volumio[10013]: info: Applying required configuration parameters for plugin Bluetoothremote Feb 25 03:06:30 salon volumio[10013]: info: Loading plugin "lastfm"... Feb 25 03:06:31 salon dhcpcd[667]: eth0: probing address 192.168.1.2/24 Feb 25 03:06:31 salon volumio[10346]: Starting albumart workers Feb 25 03:06:31 salon volumio[10348]: Starting albumart workers Feb 25 03:06:31 salon volumio[10347]: Starting albumart workers Feb 25 03:06:32 salon volumio[10013]: info: Loading i18n strings for locale fr Feb 25 03:06:32 salon volumio[10013]: Updating browse sources language Feb 25 03:06:32 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:06:32 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:06:32 salon volumio[10013]: info: CoreCommandRouter::initPlayerControls Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:06:33 salon volumio[10013]: Express server listening on port 3000 Feb 25 03:06:33 salon volumio[10013]: [Metrics] WebUI: 26s 801.24ms Feb 25 03:06:33 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Feb 25 03:06:33 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::resetVolumioState Feb 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::getcurrentVolume Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 03:06:33 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::pushState Feb 25 03:06:33 salon go-librespot[10414]: go-librespot daemon starting... Feb 25 03:06:33 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::volumioPushState Feb 25 03:06:33 salon go-librespot[10415]: time="2026-02-25T03:06:33+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:33 salon go-librespot[10415]: time="2026-02-25T03:06:33+01:00" level=debug msg="app state loaded" Feb 25 03:06:33 salon go-librespot[10415]: time="2026-02-25T03:06:33+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:33 salon go-librespot[10415]: time="2026-02-25T03:06:33+01:00" level=fatal msg="failed running with username and spotify token" 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 25 03:06:33 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:06:33 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:06:33 salon sudo[10423]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 03:06:33 salon sudo[10423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:33 salon sudo[10423]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:33 salon sudo[10427]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 03:06:33 salon sudo[10427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:33 salon sudo[10427]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:33 salon volumio[10013]: info: Volumio Network Manager: Network status updated: 0 Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:33 salon volumio[10013]: info: Reloading queue from file Feb 25 03:06:33 salon volumio[10013]: 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 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::setRepeat null single undefined Feb 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::pushState Feb 25 03:06:33 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::volumioPushState Feb 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::setRandom null Feb 25 03:06:33 salon volumio[10013]: info: CoreStateMachine::pushState Feb 25 03:06:33 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:33 salon volumio[10013]: info: CoreCommandRouter::volumioPushState Feb 25 03:06:33 salon volumio[10013]: info: Setting Device type: Raspberry PI Feb 25 03:06:34 salon volumio[10013]: info: Completed loading Core Plugins Feb 25 03:06:34 salon volumio[10013]: info: Preparing to generate the ALSA configuration file Feb 25 03:06:34 salon volumio[10013]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 25 03:06:34 salon volumio[10013]: info: Reading ALSA contributions from plugins. Feb 25 03:06:34 salon sudo[10442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 25 03:06:34 salon sudo[10442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:34 salon volumio[10013]: info: Discovery: adding 16866435-cf6e-476d-8b63-5b7f66d27a9e Feb 25 03:06:34 salon volumio[10013]: info: Discovery: Found device Salon Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::volumioGetState Feb 25 03:06:34 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:34 salon sudo[10442]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:34 salon volumio[10013]: info: Upmpdcli Daemon Started Feb 25 03:06:34 salon volumio[10013]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:06:34 salon volumio[10013]: info: Output device has changed, restarting MPD Feb 25 03:06:34 salon volumio[10013]: info: Output device has changed, restarting Shairport Sync Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:34 salon sudo[10447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:06:34 salon sudo[10447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:34 salon sudo[10449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:06:34 salon sudo[10447]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:34 salon sudo[10449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:34 salon volumio[10013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:06:34 salon volumio[10013]: info: ___________ START PLUGINS ___________ Feb 25 03:06:34 salon volumio[10013]: info: ControllerMpd::onStart: Initializing MPD Feb 25 03:06:34 salon volumio[10013]: info: Creating MPD Configuration file Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:06:34 salon volumio[10013]: info: [1771985194591] CoreMusicLibrary::Adding element Serveurs Média Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:06:34 salon systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 25 03:06:34 salon volumio[10013]: info: UPNP Browser: Client initialized successfully Feb 25 03:06:34 salon sudo[10457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:06:34 salon sudo[10457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:34 salon sudo[10459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:06:34 salon sudo[10459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:34 salon sudo[10457]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:34 salon systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:06:34 salon systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:06:34 salon systemd[1]: mpd.service: Consumed 5.371s CPU time. Feb 25 03:06:34 salon volumio[10013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:34 salon systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:06:34 salon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:06:34 salon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:06:34 salon volumio[10013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:06:34 salon volumio[10013]: info: [1771985194795] CoreMusicLibrary::Adding element Last_100 Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:06:34 salon volumio[10013]: info: [1771985194805] CoreMusicLibrary::Adding element Webradio Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:06:34 salon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:06:34 salon systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:06:34 salon volumio[10013]: info: Initializing BBC Radios Feb 25 03:06:34 salon systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:06:34 salon systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:06:34 salon systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:06:34 salon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:06:34 salon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:06:34 salon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:06:34 salon systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:06:34 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:35 salon volumio[10013]: info: Creating Spotify config file Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:35 salon sudo[10481]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:06:35 salon sudo[10481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:35 salon sudo[10481]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:35 salon volumio-remote-updater[585]: [2026-02-25 03:06:35] [connect] Successful connection Feb 25 03:06:35 salon volumio[10013]: info: FusionDsp - CamillaDSP binary found. Feb 25 03:06:35 salon volumio[10013]: info: Loading i18n strings for locale fr Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:35 salon volumio[10013]: info: FusionDsp - mixtype--------------------- None Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:06:35 salon volumio[10013]: info: [1771985195758] CoreMusicLibrary::Adding element Podcast Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:06:35 salon volumio[10013]: Cannot find translation for source Podcast Feb 25 03:06:35 salon volumio[10013]: info: Loading i18n strings for locale fr Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 25 03:06:35 salon volumio[10013]: info: [1771985195801] CoreMusicLibrary::Adding element LastFM Feb 25 03:06:35 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 03:06:35 salon volumio[10013]: Cannot find translation for source Podcast Feb 25 03:06:35 salon volumio[10013]: Cannot find translation for source LastFM Feb 25 03:06:35 salon volumio[10013]: info: [LastFM] scrobbler initiated! Feb 25 03:06:35 salon volumio[10013]: info: [LastFM] extended logging: false Feb 25 03:06:35 salon volumio[10013]: info: [LastFM] try scrobble stream/radio plays: true Feb 25 03:06:35 salon volumio[10013]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Feb 25 03:06:35 salon volumio[10013]: info: [LastFM] Left init routine Feb 25 03:06:35 salon volumio[10013]: info: [LastFM] Socket already connected: true Feb 25 03:06:35 salon volumio[10013]: info: Volumio Calling Home Feb 25 03:06:35 salon dhcpcd[667]: eth0: leased 192.168.1.2 for 43200 seconds Feb 25 03:06:35 salon avahi-daemon[568]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.2. Feb 25 03:06:35 salon avahi-daemon[568]: New relevant interface eth0.IPv4 for mDNS. Feb 25 03:06:35 salon avahi-daemon[568]: Registering new address record for 192.168.1.2 on eth0.IPv4. Feb 25 03:06:35 salon dhcpcd[667]: eth0: adding route to 192.168.1.0/24 Feb 25 03:06:35 salon dhcpcd[667]: eth0: adding default route via 192.168.1.254 Feb 25 03:06:36 salon systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 03:06:36 salon systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Feb 25 03:06:36 salon systemd[1]: welcome.service: Deactivated successfully. Feb 25 03:06:36 salon systemd[1]: Stopped welcome.service - Show a welcome message on console. Feb 25 03:06:36 salon systemd[1]: Stopping welcome.service - Show a welcome message on console... Feb 25 03:06:36 salon volumio[10013]: info: Preparing to generate the ALSA configuration file Feb 25 03:06:36 salon systemd[1]: Starting welcome.service - Show a welcome message on console... Feb 25 03:06:36 salon welcome[10511]: Resolved ip:[1] 192.168.1.2 Feb 25 03:06:36 salon volumio[10013]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Feb 25 03:06:36 salon systemd[1]: Finished welcome.service - Show a welcome message on console. Feb 25 03:06:36 salon systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Feb 25 03:06:36 salon systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Feb 25 03:06:36 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:36 salon volumio[10013]: Unhandled rejection Error: No sockets available, cannot start. Feb 25 03:06:36 salon volumio[10013]: at SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Feb 25 03:06:36 salon volumio[10013]: at SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Feb 25 03:06:36 salon volumio[10013]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Feb 25 03:06:36 salon volumio[10013]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Feb 25 03:06:36 salon volumio[10013]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Feb 25 03:06:36 salon volumio[10013]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Feb 25 03:06:36 salon volumio[10013]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Feb 25 03:06:36 salon volumio[10013]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Feb 25 03:06:36 salon volumio[10013]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:156:14) Feb 25 03:06:36 salon volumio[10013]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Feb 25 03:06:36 salon volumio[10013]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Feb 25 03:06:36 salon volumio[10013]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 25 03:06:36 salon volumio[10013]: at proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 25 03:06:36 salon volumio[10013]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Feb 25 03:06:36 salon volumio[10013]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Feb 25 03:06:36 salon volumio[10013]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 25 03:06:36 salon volumio[10013]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo EBUSY oauth-performer.prod.vlmapi.io Feb 25 03:06:36 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:36 salon volumio[10013]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 25 03:06:36 salon volumio[10013]: info: Reading ALSA contributions from plugins. Feb 25 03:06:36 salon go-librespot[10516]: go-librespot daemon starting... Feb 25 03:06:36 salon volumio[10013]: info: MPD Permissions set Feb 25 03:06:36 salon volumio[10013]: info: MPD Permissions set Feb 25 03:06:36 salon go-librespot[10518]: time="2026-02-25T03:06:36+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:36 salon go-librespot[10518]: time="2026-02-25T03:06:36+01:00" level=debug msg="app state loaded" Feb 25 03:06:36 salon go-librespot[10518]: time="2026-02-25T03:06:36+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:37 salon ntpd[915]: IO: Listen normally on 4 eth0 192.168.1.2:123 Feb 25 03:06:37 salon ntpd[915]: IO: new interface(s) found: waking up resolver Feb 25 03:06:37 salon volumio[10013]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 25 03:06:37 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:37 salon volumio[10013]: AID: null, Feb 25 03:06:37 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:37 salon volumio[10013]: payload: {} Feb 25 03:06:37 salon volumio[10013]: } Feb 25 03:06:37 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:37 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:37 salon volumio[10013]: Stack trace: Feb 25 03:06:37 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:37 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:37 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:37 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:37 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:37 salon volumio[10013]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:23) Feb 25 03:06:37 salon volumio[10013]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:345:67 Feb 25 03:06:37 salon volumio[10013]: at new Promise () Feb 25 03:06:37 salon volumio[10013]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:344:16) Feb 25 03:06:37 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:241:28) Feb 25 03:06:37 salon volumio[10013]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:209:26) Feb 25 03:06:37 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:37 salon volumio[10013]: at async Promise.all (index 0) Feb 25 03:06:37 salon volumio[10013]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:114:13) Feb 25 03:06:37 salon volumio[10013]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Feb 25 03:06:37 salon volumio[10013]: error: [yt-cast-receiver] Failed to start receiver: Feb 25 03:06:37 salon volumio[10013]: (AppError) Failed to start YouTubeApp Feb 25 03:06:37 salon volumio[10013]: --->(SessionError) (YouTube) Failed to establish session Feb 25 03:06:37 salon volumio[10013]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Feb 25 03:06:37 salon volumio[10013]: Error info: { Feb 25 03:06:37 salon volumio[10013]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Feb 25 03:06:37 salon volumio[10013]: } Feb 25 03:06:37 salon volumio[10013]: --------->(TypeError) fetch failed Feb 25 03:06:37 salon volumio[10013]: Stack trace: Feb 25 03:06:37 salon volumio[10013]: AppError: Failed to start YouTubeApp Feb 25 03:06:37 salon volumio[10013]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:131:19) Feb 25 03:06:37 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:37 salon volumio[10013]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Feb 25 03:06:37 salon volumio[10013]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 25 03:06:37 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:37 salon volumio[10013]: AID: null, Feb 25 03:06:37 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:37 salon volumio[10013]: payload: {} Feb 25 03:06:37 salon volumio[10013]: } Feb 25 03:06:37 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:37 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:37 salon volumio[10013]: Stack trace: Feb 25 03:06:37 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:37 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:37 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:37 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:37 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:37 salon volumio[10013]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:39:23) Feb 25 03:06:37 salon volumio[10013]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:345:67 Feb 25 03:06:37 salon volumio[10013]: at new Promise () Feb 25 03:06:37 salon volumio[10013]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:344:16) Feb 25 03:06:37 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:241:28) Feb 25 03:06:37 salon volumio[10013]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:123:39 Feb 25 03:06:37 salon volumio[10013]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:128:19 Feb 25 03:06:37 salon volumio[10013]: at Array.forEach () Feb 25 03:06:37 salon volumio[10013]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:118:24) Feb 25 03:06:37 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:37 salon volumio[10013]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Feb 25 03:06:37 salon volumio[10013]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 25 03:06:37 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:37 salon volumio[10013]: AID: null, Feb 25 03:06:37 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:37 salon volumio[10013]: payload: {} Feb 25 03:06:37 salon volumio[10013]: } Feb 25 03:06:37 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:37 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:37 salon volumio[10013]: Stack trace: Feb 25 03:06:37 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:37 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:37 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:37 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:37 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:37 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Feb 25 03:06:37 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:37 salon volumio[10013]: error: [ytcr] Failed to start plugin: Feb 25 03:06:37 salon volumio[10013]: (AppError) Failed to start YouTubeApp Feb 25 03:06:37 salon volumio[10013]: --->(SessionError) (YouTube) Failed to establish session Feb 25 03:06:37 salon volumio[10013]: ------>(ConnectionError) (YouTube) Connection error in generating screen Id Feb 25 03:06:37 salon volumio[10013]: Error info: { Feb 25 03:06:37 salon volumio[10013]: url: 'https://www.youtube.com/api/lounge/pairing/generate_screen_id' Feb 25 03:06:37 salon volumio[10013]: } Feb 25 03:06:37 salon volumio[10013]: --------->(TypeError) fetch failed Feb 25 03:06:37 salon volumio[10013]: Stack trace: Feb 25 03:06:37 salon volumio[10013]: AppError: Failed to start YouTubeApp Feb 25 03:06:37 salon volumio[10013]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:131:19) Feb 25 03:06:37 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:37 salon volumio[10013]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:93:13) Feb 25 03:06:37 salon volumio-remote-updater[585]: [2026-02-25 03:06:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1771985195 101 Feb 25 03:06:37 salon volumio[10013]: 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 25 03:06:37 salon volumio[10013]: info: Volumio called home Feb 25 03:06:37 salon volumio[10013]: info: Spotify config file written Feb 25 03:06:37 salon volumio[10013]: 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 25 03:06:37 salon sudo[10528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon sudo[10528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 25 03:06:37 salon systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 25 03:06:37 salon systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:37 salon volumio[10013]: info: No need to fix Spotify hosts Feb 25 03:06:37 salon volumio[10013]: 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 25 03:06:37 salon systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 03:06:37 salon go-librespot[10540]: go-librespot daemon starting... Feb 25 03:06:37 salon sudo[10528]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:37 salon go-librespot[10541]: time="2026-02-25T03:06:37+01:00" level=info msg="running go-librespot 0.6.2" Feb 25 03:06:37 salon go-librespot[10541]: time="2026-02-25T03:06:37+01:00" level=debug msg="app state loaded" Feb 25 03:06:37 salon go-librespot[10541]: time="2026-02-25T03:06:37+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 03:06:37 salon volumio[10013]: info: Starting Shairport Sync Feb 25 03:06:37 salon volumio[10013]: info: Starting Shairport Sync Feb 25 03:06:37 salon volumio[10013]: info: Starting Shairport Sync Feb 25 03:06:37 salon volumio[10013]: info: Discovery: this is already registered, 16866435-cf6e-476d-8b63-5b7f66d27a9e Feb 25 03:06:37 salon volumio[10013]: info: Discovery: Found device Salon Feb 25 03:06:37 salon volumio[10013]: info: CoreCommandRouter::volumioGetState Feb 25 03:06:37 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:37 salon sudo[10550]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:06:37 salon sudo[10550]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:37 salon sudo[10552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:06:37 salon sudo[10552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:37 salon sudo[10554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:06:37 salon sudo[10554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:37 salon volumio[10013]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Feb 25 03:06:37 salon volumio[10013]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Feb 25 03:06:37 salon volumio[10013]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Feb 25 03:06:37 salon systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:06:37 salon systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:06:37 salon systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:06:37 salon systemd[1]: shairport-sync.service: Consumed 3.237s CPU time. Feb 25 03:06:37 salon volumio[10013]: info: camilladsp service started and running in background, instance 1 Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Feb 25 03:06:38 salon systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:06:38 salon sudo[10552]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:38 salon volumio[10013]: info: FusionDsp loaded Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 03:06:38 salon volumio[10013]: error: FusionDsp - Socket connection not established Feb 25 03:06:38 salon volumio[10013]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 25 03:06:38 salon volumio[10013]: info: Adding Signal Path Element [object Object] Feb 25 03:06:38 salon volumio[10013]: info: Adding fusiondspeq DSP Signal Path Element Feb 25 03:06:38 salon sudo[10550]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:38 salon sudo[10554]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:38 salon volumio[10013]: info: FusionDsp - ---- installed callbackRead Feb 25 03:06:38 salon volumio[10013]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 25 03:06:38 salon sudo[10573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 25 03:06:38 salon sudo[10573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:38 salon sudo[10573]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:38 salon volumio[10013]: info: Shairport-Sync Started Feb 25 03:06:38 salon volumio[10013]: Error adding Membership: Error: addMembership EINVAL Feb 25 03:06:38 salon volumio[10013]: info: Shairport-Sync Started Feb 25 03:06:38 salon volumio[10013]: info: Shairport-Sync Started Feb 25 03:06:38 salon volumio[10013]: info: Asound.conf file unchanged, so no further update is needed Feb 25 03:06:38 salon volumio[10013]: info: Output device has changed, restarting MPD Feb 25 03:06:38 salon volumio[10013]: info: Output device has changed, restarting Shairport Sync Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:38 salon sudo[10590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 03:06:38 salon sudo[10590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:38 salon sudo[10590]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:38 salon sudo[10592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 03:06:38 salon sudo[10592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:38 salon volumio[10013]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 03:06:38 salon volumio[10013]: error: FusionDsp - Reload WebSocket error: [object Object] Feb 25 03:06:38 salon systemd[1]: mpd.service: Deactivated successfully. Feb 25 03:06:38 salon systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 25 03:06:38 salon systemd[1]: mpd.service: Consumed 3.535s CPU time. Feb 25 03:06:38 salon systemd[1]: mpd.socket: Deactivated successfully. Feb 25 03:06:38 salon systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 25 03:06:38 salon systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 25 03:06:38 salon volumio[10013]: info: MPD Permissions set Feb 25 03:06:38 salon volumio[10013]: error: Upnp client error: Error: read ECONNRESET Feb 25 03:06:38 salon systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 25 03:06:38 salon systemd[1]: Starting mpd.service - Music Player Daemon... Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 03:06:38 salon volumio[10013]: info: CoreCommandRouter::volumioGetState Feb 25 03:06:38 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:38 salon volumio[10013]: info: Starting Shairport Sync Feb 25 03:06:39 salon sudo[10607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 03:06:39 salon sudo[10607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:39 salon systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 25 03:06:39 salon systemd[1]: shairport-sync.service: Deactivated successfully. Feb 25 03:06:39 salon systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:06:39 salon systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 25 03:06:39 salon sudo[10607]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:39 salon volumio[10013]: info: Shairport-Sync Started Feb 25 03:06:39 salon sudo[10600]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 03:06:39 salon sudo[10600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 25 03:06:39 salon sudo[10600]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:40 salon volumio[10013]: info: go-librespot daemon successfully initialized Feb 25 03:06:41 salon volumio[10013]: info: Volumio Network Manager: Network status updated: 1 Feb 25 03:06:43 salon volumio[10013]: info: Initializing connection to go-librespot Websocket Feb 25 03:06:43 salon go-librespot[10541]: time="2026-02-25T03:06:43+01:00" level=debug msg="new websocket client" Feb 25 03:06:43 salon volumio[10013]: info: Connection to go-librespot Websocket established Feb 25 03:06:44 salon mpd[10611]: 2026-02-25T03:06:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 03:06:44 salon systemd[1]: Started mpd.service - Music Player Daemon. Feb 25 03:06:44 salon sudo[10592]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:44 salon sudo[10459]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:44 salon sudo[10449]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:44 salon volumio[10013]: info: Completed starting Core Plugins Feb 25 03:06:44 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:44 salon volumio[10013]: info: ----- MyVolumio plugins startup ---- Feb 25 03:06:44 salon volumio[10013]: info: ------------------------------------------- Feb 25 03:06:44 salon volumio[10013]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 03:06:44 salon volumio[10013]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:06:44 salon volumio[10013]: assert.ok(self.idling) Feb 25 03:06:44 salon volumio[10013]: error: The expression evaluated to a falsy value: Feb 25 03:06:44 salon volumio[10013]: assert.ok(self.idling) Feb 25 03:06:44 salon volumio[10013]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:06:44 salon volumio[10013]: assert.ok(self.idling) Feb 25 03:06:44 salon volumio[10013]: error: The expression evaluated to a falsy value: Feb 25 03:06:44 salon volumio[10013]: assert.ok(self.idling) Feb 25 03:06:44 salon volumio[10013]: info: MPD running with PID10611 Feb 25 03:06:44 salon volumio[10013]: ,establishing connection Feb 25 03:06:44 salon volumio[10013]: error: MPD error: The expression evaluated to a falsy value: Feb 25 03:06:44 salon volumio[10013]: assert.ok(self.idling) Feb 25 03:06:44 salon volumio[10013]: error: The expression evaluated to a falsy value: Feb 25 03:06:44 salon volumio[10013]: assert.ok(self.idling) Feb 25 03:06:44 salon volumio[10013]: error: updateQueue error: null Feb 25 03:06:46 salon volumio[10013]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 25 03:06:46 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:46 salon volumio[10013]: AID: null, Feb 25 03:06:46 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:46 salon volumio[10013]: payload: {} Feb 25 03:06:46 salon volumio[10013]: } Feb 25 03:06:46 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:46 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:46 salon volumio[10013]: Stack trace: Feb 25 03:06:46 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:46 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:46 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:46 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:46 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:46 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Feb 25 03:06:46 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:46 salon volumio[10013]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:123:25 Feb 25 03:06:46 salon volumio[10013]: info: Getting Spotify volume Feb 25 03:06:47 salon volumio[10013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 03:06:47 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:47 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:47 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:47 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:47 salon volumio[10013]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:47 salon volumio[10013]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 25 03:06:47 salon volumio[10013]: at process.processImmediate (node:internal/timers:449:9) Feb 25 03:06:47 salon volumio[10013]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 25 03:06:47 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:47 salon volumio[10013]: AID: null, Feb 25 03:06:47 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:47 salon volumio[10013]: payload: {} Feb 25 03:06:47 salon volumio[10013]: } Feb 25 03:06:47 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:47 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:47 salon volumio[10013]: Stack trace: Feb 25 03:06:47 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:47 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:47 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:47 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:47 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:47 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Feb 25 03:06:47 salon volumio[10013]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:47 salon volumio[10013]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 25 03:06:47 salon volumio[10013]: at process.processImmediate (node:internal/timers:449:9) Feb 25 03:06:47 salon volumio[10013]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:123:25 Feb 25 03:06:47 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:47 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:47 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:47 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:47 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:47 salon volumio[10013]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Feb 25 03:06:47 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:47 salon volumio[10013]: AID: null, Feb 25 03:06:47 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:47 salon volumio[10013]: payload: {} Feb 25 03:06:47 salon volumio[10013]: } Feb 25 03:06:47 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:47 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:47 salon volumio[10013]: Stack trace: Feb 25 03:06:47 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:47 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:47 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:47 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:47 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:47 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Feb 25 03:06:47 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:47 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:47 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:47 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:47 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:47 salon volumio[10013]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:47 salon volumio[10013]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 25 03:06:47 salon volumio[10013]: at process.processImmediate (node:internal/timers:449:9) Feb 25 03:06:47 salon volumio[10013]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Feb 25 03:06:47 salon volumio[10013]: LoungeScreenDisconnected { Feb 25 03:06:47 salon volumio[10013]: AID: null, Feb 25 03:06:47 salon volumio[10013]: name: 'loungeScreenDisconnected', Feb 25 03:06:47 salon volumio[10013]: payload: {} Feb 25 03:06:47 salon volumio[10013]: } Feb 25 03:06:47 salon volumio[10013]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Feb 25 03:06:47 salon volumio[10013]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Feb 25 03:06:47 salon volumio[10013]: Stack trace: Feb 25 03:06:47 salon volumio[10013]: IncompleteAPIDataError: Missing data required to construct query string from bind params Feb 25 03:06:47 salon volumio[10013]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Feb 25 03:06:47 salon volumio[10013]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:497:103) Feb 25 03:06:47 salon volumio[10013]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:558:71) Feb 25 03:06:47 salon volumio[10013]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:52:32) Feb 25 03:06:47 salon volumio[10013]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:242:71) Feb 25 03:06:47 salon volumio[10013]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:47 salon volumio[10013]: at runNextTicks (node:internal/process/task_queues:64:3) Feb 25 03:06:47 salon volumio[10013]: at process.processImmediate (node:internal/timers:449:9) Feb 25 03:06:47 salon volumio[10013]: info: CoreCommandRouter::volumioGetState Feb 25 03:06:47 salon volumio[10013]: info: CorePlayQueue::getTrack 0 Feb 25 03:06:48 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:48 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:48 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:48 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:48 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:48 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:48 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:48 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:48 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:48 salon volumio[10013]: error: [yt-cast-receiver] Caught error refreshing lounge token: Feb 25 03:06:48 salon volumio[10013]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Feb 25 03:06:48 salon volumio[10013]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:377:16) Feb 25 03:06:48 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 25 03:06:49 salon volumio[10013]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 03:06:52 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 03:06:55 salon volumio[10013]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 03:06:55 salon volumio[10013]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 03:06:55 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:55 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:06:55 salon volumio[10013]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 03:06:55 salon volumio[10013]: info: MyVolumio login type: Token Feb 25 03:06:55 salon volumio[10013]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 03:06:55 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 03:06:57 salon volumio[10013]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 03:06:57 salon volumio[10013]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 03:06:57 salon volumio[10013]: info: Streaming services startup Feb 25 03:06:57 salon volumio[10013]: info: Starting Streaming Daemon Feb 25 03:06:57 salon volumio[10013]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 03:06:57 salon sudo[10665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:06:57 salon sudo[10665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:06:58 salon sudo[10665]: pam_unix(sudo:session): session closed for user root Feb 25 03:06:58 salon volumio[10013]: error: Cannot start Volumio Streaming Daemon Feb 25 03:06:58 salon volumio[10013]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:06:58 salon volumio[10013]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:06:58 salon volumio[10013]: error: MyVolumio Custom Token format not valid, refreshing it Feb 25 03:06:58 salon volumio[10013]: info: MyVolumio login type: Token Feb 25 03:06:59 salon volumio[10013]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 03:06:59 salon volumio[10013]: info: MyVolumio token set successfully Feb 25 03:06:59 salon volumio[10013]: info: MYVOLUMIO: Adding device Feb 25 03:06:59 salon volumio[10013]: info: MYVOLUMIO: Evaluating Server Feb 25 03:07:00 salon volumio[10013]: info: MyVolumio Plan changed: premium Feb 25 03:07:00 salon volumio[10013]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Feb 25 03:07:00 salon volumio[10013]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 03:07:00 salon volumio[10013]: info: Removing audio output: Feb 25 03:07:00 salon volumio[10013]: info: MYVOLUMIO: Adding device Feb 25 03:07:00 salon volumio[10013]: info: MYVOLUMIO: Evaluating Server Feb 25 03:07:00 salon volumio[10013]: info: Remote config written successfully Feb 25 03:07:00 salon volumio[10013]: info: Starting Tunnel 1 Feb 25 03:07:00 salon volumio[10013]: info: Starting Tunnel Connection Checker Feb 25 03:07:00 salon volumio[10013]: info: Completed starting MyVolumio Plugin Feb 25 03:07:00 salon volumio[10013]: info: MYVolumio Device enabled Feb 25 03:07:00 salon volumio[10013]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Feb 25 03:07:00 salon volumio[10013]: info: MyVolumio status changed Feb 25 03:07:00 salon volumio[10013]: info: Streaming services startup Feb 25 03:07:00 salon volumio[10013]: info: Starting Streaming Daemon Feb 25 03:07:00 salon sudo[10711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 03:07:00 salon volumio[10013]: info: Setting Geolocation for MyVolumio to eu6 Feb 25 03:07:00 salon sudo[10711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:07:00 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:00 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:00 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:00 salon volumio[10013]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Feb 25 03:07:00 salon volumio[10013]: info: Bluetoothremote--- Device list cleared and placeholder written. Feb 25 03:07:00 salon sudo[10711]: pam_unix(sudo:session): session closed for user root Feb 25 03:07:01 salon volumio[10013]: error: Cannot start Volumio Streaming Daemon Feb 25 03:07:01 salon volumio[10013]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 03:07:01 salon volumio[10013]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 03:07:01 salon volumio[10013]: info: Setting Geolocation for MyVolumio to eu6 Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 25 03:07:01 salon volumio[10013]: error: [MyVolumio PluginManager] Cache data is invalid! Feb 25 03:07:01 salon bluetoothd[745]: Path / reserved for Adv Monitor app :1.1027 Feb 25 03:07:01 salon bluetoothd[745]: Adv Monitor app :1.1027 disconnected from D-Bus Feb 25 03:07:01 salon bluetoothd[745]: Path / reserved for Adv Monitor app :1.1028 Feb 25 03:07:01 salon bluetoothd[745]: Path / reserved for Adv Monitor app :1.1029 Feb 25 03:07:01 salon bluetoothd[745]: Path / reserved for Adv Monitor app :1.1030 Feb 25 03:07:01 salon bluetoothd[745]: Adv Monitor app :1.1028 disconnected from D-Bus Feb 25 03:07:01 salon bluetoothd[745]: Adv Monitor app :1.1029 disconnected from D-Bus Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 03:07:01 salon bluetoothd[745]: Adv Monitor app :1.1030 disconnected from D-Bus Feb 25 03:07:01 salon volumio[10013]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Feb 25 03:07:01 salon bluetoothd[745]: Path / reserved for Adv Monitor app :1.1031 Feb 25 03:07:01 salon volumio[10013]: info: Successfully Added MyVolumio device Feb 25 03:07:01 salon bluetoothd[745]: Adv Monitor app :1.1031 disconnected from D-Bus Feb 25 03:07:01 salon volumio[10013]: info: Successfully Added MyVolumio device Feb 25 03:07:01 salon volumio[10013]: info: Updating MyVolumio device info Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:01 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:02 salon volumio[10013]: info: Updating MyVolumio device info Feb 25 03:07:02 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:02 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:02 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:02 salon volumio[10013]: info: Successfully Updated MyVolumio device Feb 25 03:07:02 salon volumio[10013]: info: Successfully Updated MyVolumio device Feb 25 03:07:02 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Feb 25 03:07:02 salon volumio[10013]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Feb 25 03:07:02 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Feb 25 03:07:06 salon volumio[10013]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Feb 25 03:07:06 salon sudo[10744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 25 03:07:06 salon sudo[10744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:07:06 salon sudo[10744]: pam_unix(sudo:session): session closed for user root Feb 25 03:07:06 salon volumio[10013]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Feb 25 03:07:06 salon volumio[10013]: info: MRS: MultiRoom plugin initialized Feb 25 03:07:06 salon volumio[10013]: info: MRS: STOPPING SNAPCLIENT Feb 25 03:07:06 salon volumio[10013]: info: MRS: Snap server stop Feb 25 03:07:06 salon volumio[10013]: info: MRS: STOPPING volumioStreaming Feb 25 03:07:06 salon sudo[10763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 25 03:07:06 salon sudo[10763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:07:06 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Feb 25 03:07:06 salon sudo[10761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 25 03:07:06 salon sudo[10761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:07:06 salon sudo[10765]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 25 03:07:06 salon sudo[10765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:07:06 salon sudo[10768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 25 03:07:06 salon sudo[10768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 03:07:06 salon sudo[10768]: pam_unix(sudo:session): session closed for user root Feb 25 03:07:06 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Feb 25 03:07:06 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Feb 25 03:07:06 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Feb 25 03:07:07 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Feb 25 03:07:07 salon volumio[10013]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Feb 25 03:07:07 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Feb 25 03:07:07 salon sudo[10761]: pam_unix(sudo:session): session closed for user root Feb 25 03:07:07 salon sudo[10765]: pam_unix(sudo:session): session closed for user root Feb 25 03:07:07 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Feb 25 03:07:07 salon sudo[10763]: pam_unix(sudo:session): session closed for user root Feb 25 03:07:07 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Feb 25 03:07:07 salon volumio[10013]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Feb 25 03:07:07 salon volumio[10013]: info: Preparing to generate the ALSA configuration file Feb 25 03:07:07 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 03:07:07 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 03:07:07 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 03:07:07 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Feb 25 03:07:07 salon volumio[10013]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 25 03:07:07 salon volumio[10013]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 25 03:07:07 salon volumio[10013]: info: Reading ALSA contributions from plugins. Feb 25 03:07:07 salon volumio[10013]: info: MRS: Removed streaming files Feb 25 03:07:07 salon volumio[10013]: info: MRS: volumioStreaming STOPPED Feb 25 03:07:07 salon volumio[10013]: info: MRS: SNAPSERVER STOPPED Feb 25 03:07:07 salon volumio[10013]: info: MRS: SNAPCLIENT STOPPED Feb 25 03:07:07 salon volumio-remote-updater[585]: Test mode disabled Feb 25 03:07:07 salon volumio-remote-updater[585]: Alpha mode disabled Feb 25 03:07:07 salon volumio-remote-updater[585]: Alpha legacy test mode disabled Feb 25 03:07:07 salon volumio[10013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Feb 25 03:07:07 salon go-librespot[10541]: time="2026-02-25T03:07:07+01:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Feb 25 03:07:07 salon systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 03:07:07 salon systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 03:07:07 salon volumio[10013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:07:07 salon volumio[10013]: Error: socket hang up Feb 25 03:07:07 salon volumio[10013]: at connResetException (node:internal/errors:720:14) Feb 25 03:07:07 salon volumio[10013]: at Socket.socketOnEnd (node:_http_client:519:23) Feb 25 03:07:07 salon volumio[10013]: at Socket.emit (node:events:526:35) Feb 25 03:07:07 salon volumio[10013]: at endReadableNT (node:internal/streams/readable:1376:12) Feb 25 03:07:07 salon volumio[10013]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Feb 25 03:07:07 salon volumio[10013]: code: 'ECONNRESET', Feb 25 03:07:07 salon volumio[10013]: response: undefined Feb 25 03:07:07 salon volumio[10013]: } Feb 25 03:07:07 salon volumio[10013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 03:07:09 salon sudo[10798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 03:06' Feb 25 03:07:09 salon sudo[10798]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"