-- Logs begin at Sun 2026-03-29 15:06:26 EEST, end at Sun 2026-03-29 18:44:39 EEST. -- Mar 29 18:43:02 minidsp-shd volumio[6145]: info: searchTIDALUri took 88220 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: search took 88667 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:02 minidsp-shd volumio[6145]: info: searchTIDALUri took 88385 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: search took 88962 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:02 minidsp-shd volumio[6145]: info: searchTIDALUri took 88729 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: search took 89203 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:02 minidsp-shd volumio[6145]: info: searchTIDALUri took 88704 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: search took 89202 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: searchTIDALUri took 88664 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: search took 89173 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:02 minidsp-shd volumio[6145]: info: searchTIDALUri took 88642 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: info: search took 89163 milliseconds Mar 29 18:43:02 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88626 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89163 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88764 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89245 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88757 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89244 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88881 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89307 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88719 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89230 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88819 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89285 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88755 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89263 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 88973 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89424 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 89304 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89818 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 89226 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 89795 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:03 minidsp-shd volumio[6145]: info: searchTIDALUri took 89540 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: info: search took 90087 milliseconds Mar 29 18:43:03 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:04 minidsp-shd volumio[6145]: info: searchTIDALUri took 89998 milliseconds Mar 29 18:43:04 minidsp-shd volumio[6145]: info: search took 90456 milliseconds Mar 29 18:43:04 minidsp-shd volumio[6145]: info: searchTIDALUri took 90107 milliseconds Mar 29 18:43:04 minidsp-shd volumio[6145]: info: search took 90526 milliseconds Mar 29 18:43:04 minidsp-shd volumio[6145]: error: Failed search in plugin tidal: Error: Unable to resolve or reject the same promise twice Mar 29 18:43:09 minidsp-shd volumio[6145]: <--- Last few GCs ---> Mar 29 18:43:09 minidsp-shd volumio[6145]: [6145:0x4180008] 82621021 ms: Mark-sweep (reduce) 241.2 (251.7) -> 240.4 (253.5) MB, 382.1 / 0.8 ms (+ 38.7 ms in 6 steps since start of marking, biggest step 14.4 ms, walltime since start of marking 435 ms) (average mu = 0.807, current mu = 0.352) alloc[6145:0x4180008] 82621508 ms: Mark-sweep (reduce) 240.9 (251.5) -> 240.7 (251.7) MB, 471.5 / 0.8 ms (average mu = 0.654, current mu = 0.032) allocation failure scavenge might not succeed Mar 29 18:43:09 minidsp-shd volumio[6145]: <--- JS stacktrace ---> Mar 29 18:43:09 minidsp-shd volumio[6145]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Mar 29 18:43:09 minidsp-shd systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Mar 29 18:43:09 minidsp-shd volumio-remote-updater[500]: [2026-03-29 18:43:09] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 29 18:43:09 minidsp-shd volumio-remote-updater[500]: [2026-03-29 18:43:09] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 29 18:43:09 minidsp-shd vtcs[6603]: [2026-03-29 18:43:09.443] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Mar 29 18:43:09 minidsp-shd qobuz-connect[6461]: 20260329 18:43:09.525 [6461.6461] INFO SampleApp: Socket closed Mar 29 18:43:09 minidsp-shd go-librespot[12051]: time="2026-03-29T18:43:09+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 29 18:43:09 minidsp-shd systemd[1]: volumio.service: Failed with result 'signal'. Mar 29 18:43:09 minidsp-shd systemd[1]: Started dynamicswap service. Mar 29 18:43:09 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Mar 29 18:43:09 minidsp-shd systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 29 18:43:09 minidsp-shd systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Mar 29 18:43:09 minidsp-shd systemd[1]: Started dynamicswap service. Mar 29 18:43:09 minidsp-shd systemd[1]: Stopped Volumio Backend Module. Mar 29 18:43:09 minidsp-shd systemd[1]: Started Volumio Backend Module. Mar 29 18:43:09 minidsp-shd systemd[1]: dynamicswap.service: Succeeded. Mar 29 18:43:13 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:13 minidsp-shd volumio[12379]: info: ----- Volumio3 ---- Mar 29 18:43:13 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:13 minidsp-shd volumio[12379]: info: ----- System startup ---- Mar 29 18:43:13 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:14 minidsp-shd volumio-remote-updater[500]: [2026-03-29 18:43:14] [connect] Successful connection Mar 29 18:43:14 minidsp-shd volumio[12379]: info: MYVOLUMIO Environment detected Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Plugin folders cleanup Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning into folder /volumio/app/plugins/ Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category audio_interface Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category miscellanea Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category music_service Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category plugins.json Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category system_controller Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category user_interface Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning into folder /data/plugins/ Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Scanning category music_service Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Plugin folders cleanup completed Mar 29 18:43:14 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:14 minidsp-shd volumio[12379]: info: ----- Core plugins startup ---- Mar 29 18:43:14 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Loading plugins from folder /volumio/app/plugins/ Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin bluetooth to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin multiroom to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin upnp to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin metavolumio to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin ms_surface_dial to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin cd_controller to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin qobuzconnect to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin raat to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Loading plugins from folder /data/plugins/ Mar 29 18:43:14 minidsp-shd volumio[12379]: info: Loading plugin "system"... Mar 29 18:43:15 minidsp-shd volumio[12379]: info: Loading plugin "appearance"... Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Loading plugin "network"... Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Refreshing Cached IP Addresses Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Loading plugin "services"... Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Loading plugin "alsa_controller"... Mar 29 18:43:16 minidsp-shd sudo[12405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:43:16 minidsp-shd sudo[12407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:43:16 minidsp-shd sudo[12405]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:16 minidsp-shd sudo[12407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:16 minidsp-shd sudo[12414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 29 18:43:16 minidsp-shd sudo[12414]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:16 minidsp-shd sudo[12405]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:16 minidsp-shd sudo[12407]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:16 minidsp-shd sudo[12414]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:16 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Loading plugin "wizard"... Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Loading plugin "networkfs"... Mar 29 18:43:16 minidsp-shd volumio[12379]: info: Starting Udev Watcher for removable devices Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Ignoring mount for partition: BOOT Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Ignoring mount for partition: volumio Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Ignoring mount for partition: volumio_data Mar 29 18:43:17 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Loading plugin "volumio_command_line_client"... Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Loading plugin "upnp"... Mar 29 18:43:17 minidsp-shd volumio[12379]: info: [1774798997080] Starting Upmpd Daemon Mar 29 18:43:17 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Loading plugin "my_music"... Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Loading plugin "mpd"... Mar 29 18:43:17 minidsp-shd volumio[12379]: info: Loading plugin "upnp_browser"... Mar 29 18:43:18 minidsp-shd volumio[12379]: info: Starting UPNP Browser Mar 29 18:43:18 minidsp-shd volumio[12379]: info: Loading plugin "bluetooth"... Mar 29 18:43:18 minidsp-shd volumio[12379]: info: [1774798998506] Starting BluetoothController Mar 29 18:43:18 minidsp-shd volumio[12379]: info: Loading plugin "alarm-clock"... Mar 29 18:43:19 minidsp-shd volumio[12379]: info: Loading plugin "manifestui"... Mar 29 18:43:19 minidsp-shd volumio[12379]: info: Loading plugin "metavolumio"... Mar 29 18:43:21 minidsp-shd volumio[12379]: info: Loading plugin "airplay_emulation"... Mar 29 18:43:21 minidsp-shd volumio[12379]: info: Starting Shairport Sync Mar 29 18:43:21 minidsp-shd volumio[12379]: info: Loading plugin "cd_controller"... Mar 29 18:43:21 minidsp-shd volumio[12379]: info: Loading plugin "last_100"... Mar 29 18:43:21 minidsp-shd volumio[12379]: info: Loading plugin "qobuzconnect"... Mar 29 18:43:21 minidsp-shd volumio[12379]: info: Loading plugin "raat"... Mar 29 18:43:22 minidsp-shd volumio[12379]: info: RAAT Plugin loaded Mar 29 18:43:22 minidsp-shd volumio[12379]: info: Adding restartRAATSocket REST API Endpoint Mar 29 18:43:22 minidsp-shd volumio[12379]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Mar 29 18:43:22 minidsp-shd volumio[12379]: info: Loading plugin "streaming_services"... Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Starting Streaming Service Transparent Proxy Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Loading plugin "tidalconnect"... Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Loading plugin "webradio"... Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Loading plugin "i2s_dacs"... Mar 29 18:43:24 minidsp-shd volumio[12379]: info: I2S DAC not set, start Auto-detection Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Loading plugin "volumiodiscovery"... Mar 29 18:43:24 minidsp-shd volumio[12379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 29 18:43:24 minidsp-shd volumio[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 18:43:24 minidsp-shd volumio[12379]: *** WARNING *** For more information see Mar 29 18:43:24 minidsp-shd volumio[12379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 29 18:43:24 minidsp-shd volumio[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 18:43:24 minidsp-shd volumio[12379]: *** WARNING *** For more information see Mar 29 18:43:24 minidsp-shd node[12379]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 29 18:43:24 minidsp-shd node[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 18:43:24 minidsp-shd node[12379]: *** WARNING *** For more information see Mar 29 18:43:24 minidsp-shd node[12379]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 29 18:43:24 minidsp-shd node[12379]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 29 18:43:24 minidsp-shd node[12379]: *** WARNING *** For more information see Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Discovery: Started advertising with name: miniDSP SHD Mar 29 18:43:24 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 18:43:24 minidsp-shd volumio[12379]: info: Loading plugin "soundcloud"... Mar 29 18:43:25 minidsp-shd volumio[12379]: info: Loading plugin "spop"... Mar 29 18:43:25 minidsp-shd volumio[12379]: STREAMING PROXY: Starting server on port 3245 Mar 29 18:43:25 minidsp-shd volumio[12379]: Node JS runtime: 14 Mar 29 18:43:26 minidsp-shd volumio-remote-updater[500]: [2026-03-29 18:43:26] [connect] Successful connection Mar 29 18:43:27 minidsp-shd volumio[12379]: info: Loading plugin "ytmusic"... Mar 29 18:43:28 minidsp-shd volumio[12379]: info: Loading plugin "multiroom"... Mar 29 18:43:31 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin multiroom Mar 29 18:43:31 minidsp-shd sudo[12501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 29 18:43:31 minidsp-shd sudo[12501]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:31 minidsp-shd sudo[12501]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:31 minidsp-shd volumio[12379]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Mar 29 18:43:31 minidsp-shd volumio[12379]: info: MRS: MultiRoom plugin initialized Mar 29 18:43:31 minidsp-shd volumio[12379]: info: MRS: STOPPING SNAPCLIENT Mar 29 18:43:31 minidsp-shd volumio[12379]: info: MRS: Snap server stop Mar 29 18:43:31 minidsp-shd sudo[12517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 29 18:43:31 minidsp-shd sudo[12517]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:31 minidsp-shd volumio[12379]: info: MRS: STOPPING volumioStreaming Mar 29 18:43:31 minidsp-shd sudo[12520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 29 18:43:31 minidsp-shd sudo[12520]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:31 minidsp-shd sudo[12517]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:31 minidsp-shd sudo[12523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 29 18:43:31 minidsp-shd sudo[12523]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:31 minidsp-shd sudo[12520]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:31 minidsp-shd volumio[12379]: info: Loading plugin "outputs"... Mar 29 18:43:31 minidsp-shd volumio[12379]: info: Loading plugin "albumart"... Mar 29 18:43:31 minidsp-shd sudo[12526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 29 18:43:31 minidsp-shd sudo[12526]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:31 minidsp-shd sudo[12526]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:31 minidsp-shd sudo[12523]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:31 minidsp-shd volumio[12379]: info: Loading plugin "ms_surface_dial"... Mar 29 18:43:32 minidsp-shd volumio[12379]: Forking 3 albumart workers Mar 29 18:43:32 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin ms_surface_dial Mar 29 18:43:32 minidsp-shd volumio[12379]: info: Plugin example_plugin is not enabled Mar 29 18:43:32 minidsp-shd volumio[12379]: info: Loading plugin "hi_res_audio"... Mar 29 18:43:33 minidsp-shd volumio[12379]: Starting albumart workers Mar 29 18:43:33 minidsp-shd volumio[12379]: Starting albumart workers Mar 29 18:43:33 minidsp-shd volumio[12379]: Starting albumart workers Mar 29 18:43:34 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin hi_res_audio Mar 29 18:43:34 minidsp-shd volumio[12379]: info: Loading plugin "inputs"... Mar 29 18:43:35 minidsp-shd volumio[12379]: info: Loading plugin "qobuz"... Mar 29 18:43:38 minidsp-shd volumio[12379]: info: Loading plugin "tidal"... Mar 29 18:43:39 minidsp-shd volumio[12379]: info: Loading plugin "oem_helper"... Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin oem_helper Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Loading plugin "updater_comm"... Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Plugin mpdemulation is not enabled Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Loading plugin "rest_api"... Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Loading plugin "websocket"... Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Starting Socket.io Server version 2.3.0 Mar 29 18:43:41 minidsp-shd volumio[12379]: info: Loading plugin "80s80s"... Mar 29 18:43:41 minidsp-shd volumio-remote-updater[500]: [2026-03-29 18:43:41] [connect] Successful connection Mar 29 18:43:42 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin 80s80s Mar 29 18:43:42 minidsp-shd volumio[12379]: info: [1774799022145] [80s80s] API delay: 30 Mar 29 18:43:42 minidsp-shd volumio[12379]: info: Loading plugin "lms"... Mar 29 18:43:42 minidsp-shd volumio[12379]: info: Loading plugin "motherearthradio"... Mar 29 18:43:42 minidsp-shd volumio[12379]: info: Applying required configuration parameters for plugin motherearthradio Mar 29 18:43:42 minidsp-shd volumio[12379]: info: [1774799022772] [MotherEarth] API delay: 5 Mar 29 18:43:42 minidsp-shd volumio[12379]: info: Loading i18n strings for locale en Mar 29 18:43:42 minidsp-shd volumio[12379]: Updating browse sources language Mar 29 18:43:42 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::initPlayerControls Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:43:43 minidsp-shd volumio[12379]: Express server listening on port 3000 Mar 29 18:43:43 minidsp-shd volumio[12379]: [Metrics] WebUI: 30s 708.67ms Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreStateMachine::resetVolumioState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreStateMachine::getcurrentVolume Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:43 minidsp-shd sudo[12598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:43:43 minidsp-shd sudo[12598]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:43 minidsp-shd sudo[12598]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Mar 29 18:43:43 minidsp-shd sudo[12601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:43:43 minidsp-shd sudo[12601]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:43 minidsp-shd volumio[12379]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Completed loading Core Plugins Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Preparing to generate the ALSA configuration file Mar 29 18:43:43 minidsp-shd sudo[12601]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Volumio Network Manager: Network status updated: 1 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: MRS: Removed streaming files Mar 29 18:43:43 minidsp-shd volumio[12379]: info: MRS: volumioStreaming STOPPED Mar 29 18:43:43 minidsp-shd volumio[12379]: info: MRS: SNAPSERVER STOPPED Mar 29 18:43:43 minidsp-shd volumio[12379]: info: MRS: SNAPCLIENT STOPPED Mar 29 18:43:43 minidsp-shd volumio[12379]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 29 18:43:43 minidsp-shd volumio[12379]: wlan0 Interface doesn't support scanning. Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Cannot use regular scanning, forcing with ap-force Mar 29 18:43:43 minidsp-shd sudo[12606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 29 18:43:43 minidsp-shd sudo[12606]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:43 minidsp-shd sudo[12606]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:43 minidsp-shd volumio[12379]: command failed: No such device (-19) Mar 29 18:43:43 minidsp-shd volumio[12379]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 29 18:43:43 minidsp-shd volumio[12379]: command failed: No such device (-19) Mar 29 18:43:43 minidsp-shd volumio[12379]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Reading ALSA contributions from plugins. Mar 29 18:43:43 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.9 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Mar 29 18:43:43 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.9 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 29 18:43:43 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.9 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 29 18:43:43 minidsp-shd volumio-remote-updater[500]: [2026-03-29 18:43:43] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1774799021 101 Mar 29 18:43:43 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:43 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:43 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Reloading queue from file Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreStateMachine::setRepeat false single undefined Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreStateMachine::setRandom null Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Setting Device type: NanoPi NEO 2 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Asound.conf file unchanged, so no further update is needed Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Output device has changed, restarting MPD Mar 29 18:43:44 minidsp-shd sudo[12616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 29 18:43:44 minidsp-shd sudo[12616]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12616]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:44 minidsp-shd sudo[12619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 18:43:44 minidsp-shd sudo[12619]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12619]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Output device has changed, restarting Shairport Sync Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:44 minidsp-shd sudo[12622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 18:43:44 minidsp-shd sudo[12622]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd systemd[1]: Stopping Music Player Daemon... Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: ___________ START PLUGINS ___________ Mar 29 18:43:44 minidsp-shd volumio[12379]: info: ControllerMpd::onStart: Initializing MPD Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Creating MPD Configuration file Mar 29 18:43:44 minidsp-shd sudo[12630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 18:43:44 minidsp-shd sudo[12630]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12630]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:44 minidsp-shd volumio[12379]: info: [1774799024463] CoreMusicLibrary::Adding element Media Servers Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:44 minidsp-shd sudo[12633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 18:43:44 minidsp-shd sudo[12633]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd volumio[12379]: info: UPNP Browser: Client initialized successfully Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding METAVOLUMIO REST API Endpoints Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:44 minidsp-shd sudo[12636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Mar 29 18:43:44 minidsp-shd sudo[12636]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12636]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Preparing CD Folders Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding CD REST API Endpoints Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Starting UDEV Watcher for CD Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Detecting CD presence with UDEV Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:44 minidsp-shd volumio[12379]: info: [1774799024746] CoreMusicLibrary::Adding element Last_100 Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:44 minidsp-shd volumio[12379]: info: QobuzConnect: Starting Qobuz Connect socket and service Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Starting RAAT Plugin Mar 29 18:43:44 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Additional UI Settings Added for plugin music_service/raat Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Streaming services startup Mar 29 18:43:44 minidsp-shd volumio[12379]: info: Starting Streaming Daemon Mar 29 18:43:44 minidsp-shd sudo[12648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Mar 29 18:43:44 minidsp-shd sudo[12648]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12648]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:44 minidsp-shd sudo[12652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 18:43:44 minidsp-shd sudo[12651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Mar 29 18:43:44 minidsp-shd sudo[12652]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12651]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:44 minidsp-shd sudo[12652]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:44 minidsp-shd systemd[1]: Stopping Volumio Qobuz Connect Service... Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:45 minidsp-shd volumio[12379]: info: [1774799025074] CoreMusicLibrary::Adding element Webradio Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:43:45 minidsp-shd sudo[12661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 18:43:45 minidsp-shd sudo[12661]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Initializing BBC Radios Mar 29 18:43:45 minidsp-shd qobuz-connect[6461]: 20260329 18:43:45.103 [6461.6461] INFO SampleApp: Stopping Local configuration server Mar 29 18:43:45 minidsp-shd systemd[1]: Stopping Volumio Tidal Connect Service... Mar 29 18:43:45 minidsp-shd systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 29 18:43:45 minidsp-shd systemd[1]: vtcs.service: Succeeded. Mar 29 18:43:45 minidsp-shd systemd[1]: Stopped Volumio Tidal Connect Service. Mar 29 18:43:45 minidsp-shd sudo[12661]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:45 minidsp-shd volumio[12379]: info: [1774799025258] CoreMusicLibrary::Adding element SoundCloud Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Creating Spotify config file Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:45 minidsp-shd systemd[1]: qobuz-connect.service: Succeeded. Mar 29 18:43:45 minidsp-shd systemd[1]: Stopped Volumio Qobuz Connect Service. Mar 29 18:43:45 minidsp-shd systemd[1]: Started Volumio Qobuz Connect Service. Mar 29 18:43:45 minidsp-shd sudo[12651]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:45 minidsp-shd volumio[12379]: info: [1774799025428] CoreMusicLibrary::Adding element YouTube Music Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 29 18:43:45 minidsp-shd volumio[12379]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:45 minidsp-shd volumio[12379]: error: Hi Res Audio Failed Login: Missing Login Data Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 29 18:43:45 minidsp-shd systemd[1]: mpd.service: Succeeded. Mar 29 18:43:45 minidsp-shd systemd[1]: Stopped Music Player Daemon. Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Refreshing TIDAL token Mar 29 18:43:45 minidsp-shd systemd[1]: Starting Music Player Daemon... Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:43:45 minidsp-shd sudo[12678]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:45 minidsp-shd sudo[12678]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:45 minidsp-shd sudo[12678]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:45 minidsp-shd volumio[12379]: info: [1774799025710] CoreMusicLibrary::Adding element 80s80s Radio Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:45 minidsp-shd volumio[12379]: info: [1774799025762] CoreMusicLibrary::Adding element Mother Earth Radio Mar 29 18:43:45 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:45 minidsp-shd volumio[12379]: Cannot find translation for source Mother Earth Radio Mar 29 18:43:45 minidsp-shd sudo[12692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Mar 29 18:43:45 minidsp-shd sudo[12692]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:45 minidsp-shd volumio[12379]: info: Volumio Calling Home Mar 29 18:43:45 minidsp-shd systemd[1]: Starting Logitech Media Server Daemon... Mar 29 18:43:45 minidsp-shd systemd[1]: Started Logitech Media Server Daemon. Mar 29 18:43:45 minidsp-shd sudo[12692]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:45 minidsp-shd systemd[12699]: logitechmediaserver.service: Failed to execute command: No such file or directory Mar 29 18:43:45 minidsp-shd systemd[12699]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Mar 29 18:43:45 minidsp-shd systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Mar 29 18:43:45 minidsp-shd systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Mar 29 18:43:45 minidsp-shd volumio[12379]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Stopping AccessToken refresher cron for QOBUZ Mar 29 18:43:46 minidsp-shd volumio[12379]: info: AccessToken refresher cron started for QOBUZ Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Adding QOBUZ REST API Endpoints Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Discovery: adding 1f2bcdd1-85b6-4928-a45f-76a78d220f54 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Discovery: Found device miniDSP SHD Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output for this device Mar 29 18:43:46 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Adding audio output: Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Adding audio output: Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Serial port opened successfully Mar 29 18:43:46 minidsp-shd volumio[12379]: info: logitechmediaserver started Mar 29 18:43:46 minidsp-shd volumio[12379]: error: Cannot start Volumio Streaming Daemon Mar 29 18:43:46 minidsp-shd volumio[12379]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 18:43:46 minidsp-shd volumio[12379]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 18:43:46 minidsp-shd volumio[12379]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Mar 29 18:43:46 minidsp-shd volumio[12379]: Can't get device info: No such device Mar 29 18:43:46 minidsp-shd volumio[12379]: info: MPD Permissions set Mar 29 18:43:46 minidsp-shd volumio[12379]: info: MPD Permissions set Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Upmpdcli Daemon Started Mar 29 18:43:46 minidsp-shd volumio[12379]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"disableVolumeControl":false} Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:43:46 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Ignoring ROON Volume update because of undefined data Mar 29 18:43:46 minidsp-shd volumio[12379]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Mar 29 18:43:46 minidsp-shd volumio[12379]: info: [msSurfaceDial init()] check /org/bluez Mar 29 18:43:46 minidsp-shd volumio[12379]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Mar 29 18:43:46 minidsp-shd volumio[12379]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::Reload Ui Mar 29 18:43:46 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.9 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Executing endpoint qc_getconfig Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Volumio called home Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Spotify config file written Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.792 [12674.12674] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.820 [12674.12674] INFO VolumeManager: [0x151c4d8]: Setting new playback volume: 75 Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.820 [12674.12674] INFO VolumeManager: [0x151c4d8]: Setting new mute state: 0 Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.820 [12674.12674] INFO AudioStreamManager: [0x151c230]: Setting new audio download buffer size: 1048576 Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.820 [12674.12674] INFO QobuzConnect: [0x151cda0]: Client initialized! Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.820 [12674.12674] INFO SampleApp: Starting Avahi advertising, name: miniDSP SHD, service name: _qobuz-connect._tcp Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Mar 29 18:43:46 minidsp-shd volumio[12379]: info: updateDSP function in raat called! Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Updating RAAT Signal Path Mar 29 18:43:46 minidsp-shd volumio[12379]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Reconfiguring and Restarting RAAT Plugin Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.849 [12674.12674] INFO LocalConfigManager: [0x151bf58]: Starting Local Configuration server Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.849 [12674.12674] INFO SampleApp: Starting Local configuration server Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.850 [12674.12674] INFO SampleApp: Connected to UNIX socket client 0x1511ed8 Mar 29 18:43:46 minidsp-shd sudo[12704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 29 18:43:46 minidsp-shd sudo[12704]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:46 minidsp-shd systemd[1]: Stopping go-librespot Daemon... Mar 29 18:43:46 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 29 18:43:46 minidsp-shd systemd[1]: go-librespot-daemon.service: Succeeded. Mar 29 18:43:46 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 29 18:43:46 minidsp-shd qobuz-connect[12674]: 20260329 18:43:46.930 [12674.12674] INFO SampleApp: Playback volume changed: 75 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:46 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:46 minidsp-shd sudo[12704]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Not Reporting Auto name since its the default one Mar 29 18:43:46 minidsp-shd volumio[12379]: info: RAAT Overriding default device vendor model Mar 29 18:43:46 minidsp-shd go-librespot[12725]: go-librespot daemon starting... Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Adding MINIDSP Inputs Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:46 minidsp-shd volumio[12379]: info: [1774799026956] CoreMusicLibrary::Adding element Inputs Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source Mother Earth Radio Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:46 minidsp-shd volumio[12379]: info: [1774799026965] CoreMusicLibrary::Adding element Presets Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source Mother Earth Radio Mar 29 18:43:46 minidsp-shd volumio[12379]: Cannot find translation for source Presets Mar 29 18:43:46 minidsp-shd volumio[12379]: (node:12379) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Mar 29 18:43:46 minidsp-shd volumio[12379]: (Use `node --trace-deprecation ...` to show where the warning was created) Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:46 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:46 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:47 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 29 18:43:47 minidsp-shd volumio[12379]: info: No need to fix Spotify hosts Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=info msg="running go-librespot 0.7.1" Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=debug msg="app state loaded" Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=debug msg="stored credentials not found" Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:47 minidsp-shd volumio[12379]: info: Access Token successfully retrieved Mar 29 18:43:47 minidsp-shd volumio[12379]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Mar 29 18:43:47 minidsp-shd volumio[12379]: info: QobuzConnect: QOBUZ Connect daemon connected Mar 29 18:43:47 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.9 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:47 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 29 18:43:47 minidsp-shd sudo[12747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=info msg="zeroconf server listening on port 43991" Mar 29 18:43:47 minidsp-shd sudo[12747]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:47 minidsp-shd volumio[12379]: info: New Spotify access tokenBQDjZz43t_... Mar 29 18:43:47 minidsp-shd volumio[12379]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 29 18:43:47 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:47+03:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 29 18:43:47 minidsp-shd sudo[12747]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:47 minidsp-shd volumio[12379]: info: Starting Shairport Sync Mar 29 18:43:47 minidsp-shd volumio[12379]: info: Starting Shairport Sync Mar 29 18:43:47 minidsp-shd sudo[12750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 18:43:47 minidsp-shd sudo[12750]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:47 minidsp-shd volumio[12379]: info: Starting Shairport Sync Mar 29 18:43:47 minidsp-shd sudo[12753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 18:43:47 minidsp-shd sudo[12753]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:47 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 18:43:47 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Mar 29 18:43:47 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 18:43:47 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="obtained new client token: AAAmKSHOu2b0xPQjLOG1SiLjRjfmBSzJRtdRhTLszlMuzM+iKYagNejo5uj2fZEc0hyfQh4QUfmG3Km9OiNFqn5TptUtVVgaKq7TWY8lhPi+C6F8q5PdHu9C+vP6gRwWm8Y3Dmyti1mv7zDU+XjLJNrycAVg9+/1LecNGPQCvU+nTpci4d3We8rC+y/53N0Pfw874F4sFtJBp+FT1ArBAQNXRBgBJQYHkJUFRIw9ny/sIL1kCUFNcA==" Mar 29 18:43:48 minidsp-shd sudo[12756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 18:43:48 minidsp-shd sudo[12756]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 18:43:48 minidsp-shd sudo[12750]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd sudo[12753]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:43:48 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 18:43:48 minidsp-shd volumio[12379]: xcb_connection_has_error() returned true Mar 29 18:43:48 minidsp-shd systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 29 18:43:48 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Mar 29 18:43:48 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 18:43:48 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 18:43:48 minidsp-shd sudo[12756]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:48 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:48 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:48 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:48 minidsp-shd volumio[12379]: Cannot find translation for source Mother Earth Radio Mar 29 18:43:48 minidsp-shd volumio[12379]: Cannot find translation for source Presets Mar 29 18:43:48 minidsp-shd sudo[12763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 29 18:43:48 minidsp-shd sudo[12763]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="completed challenge" Mar 29 18:43:48 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Mar 29 18:43:48 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 29 18:43:48 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Mar 29 18:43:48 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Mar 29 18:43:48 minidsp-shd systemd[1]: Started RAAT DAEMON. Mar 29 18:43:48 minidsp-shd sudo[12763]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Volumio BT Module successfully started Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Raat Daemon started successfully Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Shairport-Sync Started Mar 29 18:43:48 minidsp-shd volumio[12379]: Error adding Membership: Error: addMembership EINVAL Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Shairport-Sync Started Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Shairport-Sync Started Mar 29 18:43:48 minidsp-shd volumio[12379]: info: TidalConnect service stoped! Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Successfully retrieved User Session From TIDAL Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 29 18:43:48 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Executing endpoint restartRAATSocket Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Enabling external Volume Control Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:48 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 29 18:43:48 minidsp-shd sudo[12787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 29 18:43:48 minidsp-shd sudo[12787]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd sudo[12787]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd sudo[12793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 29 18:43:48 minidsp-shd sudo[12793]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd sudo[12793]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd sudo[12802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 29 18:43:48 minidsp-shd sudo[12802]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd sudo[12812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 29 18:43:48 minidsp-shd sudo[12812]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:43:48 minidsp-shd sudo[12802]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd sudo[12812]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd sudo[12825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:43:48 minidsp-shd sudo[12825]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd sudo[12825]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd sudo[12829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:43:48 minidsp-shd sudo[12829]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=debug msg="completed challenge" Mar 29 18:43:48 minidsp-shd sudo[12829]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:48 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:48+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 29 18:43:48 minidsp-shd volumio[12379]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 29 18:43:49 minidsp-shd sudo[12834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 29 18:43:49 minidsp-shd sudo[12834]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:43:49 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Mar 29 18:43:49 minidsp-shd sudo[12834]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Successfully retrieved User Subscription From TIDAL Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Adding TIDAL to Browse Sources Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:49 minidsp-shd volumio[12379]: info: [1774799029218] CoreMusicLibrary::Adding element TIDAL Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source Mother Earth Radio Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source Presets Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source TIDAL Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Adding TIDAL REST API Endpoints Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:49 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8:3000 from 192.168.1.9 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Spotify Successfully logged in Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 18:43:49 minidsp-shd volumio[12379]: info: [1774799029388] CoreMusicLibrary::Adding element Spotify Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source SoundCloud Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source YouTube Music Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source 80s80s Radio Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source Mother Earth Radio Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source Presets Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source TIDAL Mar 29 18:43:49 minidsp-shd volumio[12379]: Cannot find translation for source Spotify Mar 29 18:43:49 minidsp-shd mpd[12693]: Mar 29 18:43 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:43:49 minidsp-shd systemd[1]: Started Music Player Daemon. Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 29 18:43:49 minidsp-shd sudo[12622]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd sudo[12633]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 29 18:43:49 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:49+03:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 29 18:43:49 minidsp-shd volumio[12379]: error: MPD error: The expression evaluated to a falsy value: Mar 29 18:43:49 minidsp-shd volumio[12379]: assert.ok(self.idling) Mar 29 18:43:49 minidsp-shd volumio[12379]: error: The expression evaluated to a falsy value: Mar 29 18:43:49 minidsp-shd volumio[12379]: assert.ok(self.idling) Mar 29 18:43:49 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:49+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:49 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:49+03:00" level=debug msg="completed challenge" Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Executing endpoint tc_getconfig Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 29 18:43:49 minidsp-shd volumio[12379]: info: MPD running with PID12693 Mar 29 18:43:49 minidsp-shd volumio[12379]: ,establishing connection Mar 29 18:43:49 minidsp-shd vtcs[12836]: STARTING TidalConnect services, version: 1.6.1 Mar 29 18:43:49 minidsp-shd vtcs[12836]: STARTED TidalConnect services. Mar 29 18:43:49 minidsp-shd volumio[12379]: error: updateQueue error: null Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 29 18:43:49 minidsp-shd sudo[12853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 29 18:43:49 minidsp-shd sudo[12853]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd sudo[12853]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:49+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:49 minidsp-shd sudo[12860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 29 18:43:49 minidsp-shd sudo[12860]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd sudo[12869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 29 18:43:49 minidsp-shd sudo[12869]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd sudo[12860]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd sudo[12879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 29 18:43:49 minidsp-shd sudo[12879]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd sudo[12869]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd sudo[12889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:43:49 minidsp-shd sudo[12889]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd sudo[12879]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd sudo[12889]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd sudo[12894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:43:49 minidsp-shd sudo[12894]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Executing endpoint tc_connect Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Connecting to TidalConnect Mar 29 18:43:49 minidsp-shd sudo[12894]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:49 minidsp-shd volumio[12379]: error: updateQueue error: null Mar 29 18:43:49 minidsp-shd volumio[12379]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"mute":false,"disableVolumeControl":false} Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:43:49 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::servicePushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:43:49 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received tidalconnect Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::servicePushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:49 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:50 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:43:50 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:50 minidsp-shd volumio[12379]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received tidalconnect Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:50 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 18:43:50 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:50+03:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Mar 29 18:43:50 minidsp-shd volumio[12379]: info: MRS: Getting audio outputs on start Mar 29 18:43:50 minidsp-shd volumio[12379]: info: MRS: Requesting all other devices output Mar 29 18:43:50 minidsp-shd volumio[12379]: info: go-librespot daemon successfully initialized Mar 29 18:43:50 minidsp-shd volumio[12379]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"mute":false,"disableVolumeControl":false} Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreStateMachine::pushState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:50 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output update for this device Mar 29 18:43:50 minidsp-shd volumio[12379]: info: MRS: Pushing multiroomSync output Mar 29 18:43:50 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Mar 29 18:43:50 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:50+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 29 18:43:51 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:51+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:51 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:51+03:00" level=debug msg="completed challenge" Mar 29 18:43:51 minidsp-shd sudo[12907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:43:51 minidsp-shd sudo[12907]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:51 minidsp-shd sudo[12907]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:51 minidsp-shd sudo[12910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:43:51 minidsp-shd sudo[12910]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:51 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:51+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:51 minidsp-shd sudo[12910]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:51 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:43:51 minidsp-shd sudo[12915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 18:43:51 minidsp-shd sudo[12915]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:51 minidsp-shd sudo[12915]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:51 minidsp-shd sudo[12918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 18:43:51 minidsp-shd sudo[12918]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:51 minidsp-shd sudo[12918]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:51 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to 192.168.1.8 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:43:51 minidsp-shd volumio[12379]: info: Listing playlists Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 18:43:51 minidsp-shd volumio[12379]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Mar 29 18:43:51 minidsp-shd volumio[12379]: info: Received Get System Info Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:51 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 18:43:51 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:43:52 minidsp-shd volumio[12379]: info: TidalConnect service started! Mar 29 18:43:52 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:52+03:00" level=debug msg="connected to ap-gae2.spotify.com:80" Mar 29 18:43:52 minidsp-shd volumio[12379]: info: Completed starting Core Plugins Mar 29 18:43:52 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:52 minidsp-shd volumio[12379]: info: ----- MyVolumio plugins startup ---- Mar 29 18:43:52 minidsp-shd volumio[12379]: info: ------------------------------------------- Mar 29 18:43:52 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 29 18:43:52 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:52+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:52 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:52+03:00" level=debug msg="completed challenge" Mar 29 18:43:52 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:52+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:53 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:53+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:43:53 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:53+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:53 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:53+03:00" level=debug msg="completed challenge" Mar 29 18:43:53 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:53+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:53 minidsp-shd go-librespot[12725]: time="2026-03-29T18:43:53+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 18:43:53 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 29 18:43:53 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 29 18:43:53 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:43:53 minidsp-shd volumio[12379]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:43:53 minidsp-shd volumio[12379]: info: Received Get System Info Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:53 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:53 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 29 18:43:54 minidsp-shd volumio[12379]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 18:43:54 minidsp-shd volumio[12379]: info: Not Reporting Auto name since its the default one Mar 29 18:43:54 minidsp-shd volumio[12379]: info: RAAT Overriding default device vendor model Mar 29 18:43:54 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 29 18:43:54 minidsp-shd volumio[12379]: xcb_connection_has_error() returned true Mar 29 18:43:55 minidsp-shd sudo[12930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 29 18:43:55 minidsp-shd sudo[12930]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:43:55 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Mar 29 18:43:55 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Mar 29 18:43:55 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Mar 29 18:43:55 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Mar 29 18:43:55 minidsp-shd systemd[1]: Started RAAT DAEMON. Mar 29 18:43:55 minidsp-shd volumio[12379]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 18:43:55 minidsp-shd sudo[12930]: pam_unix(sudo:session): session closed for user root Mar 29 18:43:55 minidsp-shd volumio[12379]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Raat Daemon started successfully Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Executing endpoint restartRAATSocket Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 29 18:43:55 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Received Get System Info Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 0 Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Adding Item to queue: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Exploding uri ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D in service ytmusic Mar 29 18:43:55 minidsp-shd volumio[12379]: info: [ytmusic-browse] explodeUri: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushQueue Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::saveQueue Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::updateTrackBlock Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrackBlock Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPlay Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::play index 464 Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::stop Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::play index undefined Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreStateMachine::startPlaybackTimer Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 18:43:55 minidsp-shd volumio[12379]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Mar 29 18:43:55 minidsp-shd volumio[12379]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 29 18:43:55 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Mar 29 18:43:55 minidsp-shd volumio[12379]: info: updateDSP function in raat called! Mar 29 18:43:55 minidsp-shd volumio[12379]: info: Updating RAAT Signal Path Mar 29 18:43:56 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:43:56 minidsp-shd volumio[12379]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 18:43:56 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 29 18:43:56 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 29 18:43:56 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 29 18:43:56 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 29 18:43:56 minidsp-shd go-librespot[12943]: go-librespot daemon starting... Mar 29 18:43:56 minidsp-shd volumio[12379]: PlayerError: Failed to extract signature decipher algorithm Mar 29 18:43:56 minidsp-shd volumio[12379]: at Function.extractSigSourceCode (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:21905:20) Mar 29 18:43:56 minidsp-shd volumio[12379]: at Function. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:21798:27) Mar 29 18:43:56 minidsp-shd volumio[12379]: at Generator.next () Mar 29 18:43:56 minidsp-shd volumio[12379]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Mar 29 18:43:56 minidsp-shd volumio[12379]: at runMicrotasks () Mar 29 18:43:56 minidsp-shd volumio[12379]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Mar 29 18:43:56 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:56+03:00" level=info msg="running go-librespot 0.7.1" Mar 29 18:43:56 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:56+03:00" level=debug msg="app state loaded" Mar 29 18:43:56 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:56+03:00" level=debug msg="stored credentials not found" Mar 29 18:43:56 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:56+03:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 18:43:56 minidsp-shd volumio[12379]: info: [ytmusic] InnertubeLoader: creating Auth instance... Mar 29 18:43:56 minidsp-shd volumio[12379]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=info msg="zeroconf server listening on port 44055" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="obtained new client token: AACS10NK6Z4+5f2EowfeyJpjXzzLW9MupbN7A6CWcTzUHO+yW8qBxr2f3BEHvhLKHH2335AMHwmcn6u5AXO7gNcie0VyOexPWkecztfN7dZi6700uQ3PCSFNfLfPM3GQzSafSQr0apD/paW+NtnP6aLr2ke00ueioNskp5n8wE8talY8h6uYUZChjLeX9rbkX1lCZZ8+3T4abEGSttFX3OFbNmpczKqdSjJ2qEnuBdj8j1XmYvJEag==" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=debug msg="completed challenge" Mar 29 18:43:57 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:57+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:57 minidsp-shd volumio[12379]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 18:43:57 minidsp-shd volumio[12379]: info: [ytmusic] InnertubeLoader: Auth instance created (status: pending sign-in) Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=debug msg="completed challenge" Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:58 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:58+03:00" level=debug msg="completed challenge" Mar 29 18:43:59 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:59+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:43:59 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:43:59 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:59+03:00" level=debug msg="new websocket client" Mar 29 18:43:59 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket established Mar 29 18:43:59 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:59+03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 29 18:43:59 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:59+03:00" level=debug msg="completed keyexchange" Mar 29 18:43:59 minidsp-shd go-librespot[12943]: time="2026-03-29T18:43:59+03:00" level=debug msg="completed challenge" Mar 29 18:44:00 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:00+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:00 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:00+03:00" level=debug msg="connected to ap-gue1.spotify.com:443" Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 29 18:44:00 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 29 18:44:00 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:00+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:00 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:00+03:00" level=debug msg="completed challenge" Mar 29 18:44:01 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:01+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:01 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:01+03:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Mar 29 18:44:01 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:01+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:44:01 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:01+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:01 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:01+03:00" level=debug msg="completed challenge" Mar 29 18:44:02 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:02+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:02 minidsp-shd go-librespot[12943]: time="2026-03-29T18:44:02+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 29 18:44:02 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 29 18:44:02 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 29 18:44:02 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:02 minidsp-shd volumio[12379]: info: Starting MyVolumio Remote Streaming Endpoints Mar 29 18:44:02 minidsp-shd volumio[12379]: info: MyVolumio login type: Token Mar 29 18:44:02 minidsp-shd volumio[12379]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 29 18:44:02 minidsp-shd volumio[12379]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 18:44:02 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket closed Mar 29 18:44:02 minidsp-shd volumio[12379]: info: Getting Spotify volume Mar 29 18:44:02 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 18:44:02 minidsp-shd volumio[12379]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 29 18:44:02 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Mar 29 18:44:02 minidsp-shd volumio[12379]: (node:12379) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Mar 29 18:44:02 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:02 minidsp-shd volumio[12379]: info: Aligning Spotify Volume to Volumio Volume Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:44:02 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:02 minidsp-shd volumio[12379]: info: Setting Spotify Volume from Volumio: 100 Mar 29 18:44:02 minidsp-shd volumio[12379]: error: MyVolumio Custom Token format not valid, refreshing it Mar 29 18:44:03 minidsp-shd volumio[12379]: info: MyVolumio login type: Token Mar 29 18:44:04 minidsp-shd volumio[12379]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 29 18:44:04 minidsp-shd volumio[12379]: info: Sending Spotify command with payload to local API: /player/volume Mar 29 18:44:04 minidsp-shd volumio[12379]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 18:44:04 minidsp-shd volumio[12379]: info: MyVolumio token set successfully Mar 29 18:44:04 minidsp-shd volumio[12379]: info: MYVOLUMIO: Adding device Mar 29 18:44:04 minidsp-shd volumio[12379]: info: MYVOLUMIO: Evaluating Server Mar 29 18:44:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 29 18:44:05 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Mar 29 18:44:05 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 29 18:44:05 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 29 18:44:05 minidsp-shd go-librespot[12980]: go-librespot daemon starting... Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=info msg="running go-librespot 0.7.1" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="app state loaded" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="stored credentials not found" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:44:05 minidsp-shd volumio[12379]: info: MyVolumio status changed Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=info msg="zeroconf server listening on port 42203" Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Streaming services startup Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Starting Streaming Daemon Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Removing audio output: Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Stoppping Tunnel 1 Mar 29 18:44:05 minidsp-shd sudo[12998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 18:44:05 minidsp-shd sudo[12998]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:44:05 minidsp-shd sudo[12998]: pam_unix(sudo:session): session closed for user root Mar 29 18:44:05 minidsp-shd sudo[13001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 29 18:44:05 minidsp-shd sudo[13001]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 18:44:05 minidsp-shd sudo[13001]: pam_unix(sudo:session): session closed for user root Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Setting Geolocation for MyVolumio to eu6 Mar 29 18:44:05 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:05 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:05 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="new websocket client" Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Remote SSH Stopped Mar 29 18:44:05 minidsp-shd volumio[12379]: error: Cannot start Volumio Streaming Daemon Mar 29 18:44:05 minidsp-shd volumio[12379]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 18:44:05 minidsp-shd volumio[12379]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 18:44:05 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket established Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=debug msg="obtained new client token: AABzyi6YcIB9dibnaFRplU4xOAybUZa9a5oQXlNNpmtzHBfVIxMBkQB35pCmjcGmKtGxZeb3Hr219mbr91forxBFoR8QVIcQ33R4Ydu7QrGSDqt4aEWCor7YlpHNFkZS08Uk9Agjc59Fuoo98ZzBsFEN431SenRMnnffTor8Rj9MviWIdeEJRoPMAUTAy0o4vxbw6fu4Pca8SY59hr2zbxs9Z72q7q0zsG2WUR1/ZTZZtFjQ3D6g/g==" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Mar 29 18:44:05 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:05+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:443, retrying with a different AP" error="dial tcp 104.199.65.9:443: connect: connection refused" Mar 29 18:44:06 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:06+03:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 29 18:44:06 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:06+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:06 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:06+03:00" level=debug msg="completed challenge" Mar 29 18:44:06 minidsp-shd volumio[12379]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 18:44:06 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:06+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:06 minidsp-shd volumio[12379]: info: Updating MyVolumio device info Mar 29 18:44:06 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:06 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:06+03:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=debug msg="completed challenge" Mar 29 18:44:07 minidsp-shd volumio[12379]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=debug msg="completed challenge" Mar 29 18:44:07 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:07+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:08 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:08+03:00" level=debug msg="connected to ap-gae2.spotify.com:80" Mar 29 18:44:08 minidsp-shd volumio[12379]: info: Getting Spotify volume Mar 29 18:44:08 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 29 18:44:08 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:44:08 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:08 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:08+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:08 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:08+03:00" level=debug msg="completed challenge" Mar 29 18:44:09 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:09+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:09 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:09+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:44:09 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:09+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:09 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:09+03:00" level=debug msg="completed challenge" Mar 29 18:44:10 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:10+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:10 minidsp-shd volumio[12379]: info: MYVOLUMIO: Adding device Mar 29 18:44:10 minidsp-shd volumio[12379]: info: MYVOLUMIO: Evaluating Server Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 18:44:10 minidsp-shd volumio[12379]: info: Received Get System Version Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:44:10 minidsp-shd volumio[12379]: info: Received Get System Info Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 18:44:10 minidsp-shd volumio[12379]: info: Discovery: Getting this device information Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:10 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 18:44:10 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:10+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:44:10 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:10+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:10 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:10+03:00" level=debug msg="completed challenge" Mar 29 18:44:10 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:10+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:10 minidsp-shd go-librespot[12980]: time="2026-03-29T18:44:10+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 29 18:44:10 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 29 18:44:11 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 29 18:44:11 minidsp-shd volumio[12379]: at connResetException (internal/errors.js:639:14) Mar 29 18:44:11 minidsp-shd volumio[12379]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 29 18:44:11 minidsp-shd volumio[12379]: at Socket.emit (events.js:412:35) Mar 29 18:44:11 minidsp-shd volumio[12379]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 29 18:44:11 minidsp-shd volumio[12379]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 29 18:44:11 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Mar 29 18:44:11 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket closed Mar 29 18:44:11 minidsp-shd volumio[12379]: info: Setting Geolocation for MyVolumio to eu7 Mar 29 18:44:11 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:11 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:11 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:11 minidsp-shd volumio[12379]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 29 18:44:12 minidsp-shd volumio[12379]: error: MyVolumio Plugin failed to start in a timely fashion Mar 29 18:44:12 minidsp-shd volumio[12379]: [Metrics] CommandRouter: 58s 739.56ms Mar 29 18:44:12 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 18:44:12 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:44:12 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:12 minidsp-shd volumio[12379]: info: CoreCommandRouter::Close All Modals sent Mar 29 18:44:12 minidsp-shd volumio[12379]: info: CoreCommandRouter::Close All Modals sent Mar 29 18:44:12 minidsp-shd volumio[12379]: info: Updating MyVolumio device info Mar 29 18:44:12 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:12 minidsp-shd volumio[12379]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 29 18:44:13 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 18:44:13 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 18:44:13 minidsp-shd volumio[12379]: xcb_connection_has_error() returned true Mar 29 18:44:13 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 18:44:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 29 18:44:13 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Mar 29 18:44:13 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 29 18:44:13 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 29 18:44:13 minidsp-shd go-librespot[13047]: go-librespot daemon starting... Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=info msg="running go-librespot 0.7.1" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="app state loaded" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="stored credentials not found" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 29 18:44:14 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=info msg="zeroconf server listening on port 39515" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="new websocket client" Mar 29 18:44:14 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket established Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="obtained new client token: AAD8kzocjdSwmzDuaKlocZhqW7W1KHLpfpqMaBXLHwV5YnYH41DB8RHGKS/mNJA110GRMfFzCOL01g7iLK/0T6Vjy4LfeLbw0iODdWoM+2CCRQ2Rr0j7/JbmoCBVIzU+AeHaTj4KOJVlvSQZtDIplL8ESTwagDhlxsRs6pMutzUhQZXI/a+DX2jqjGGkMDNDRxKejoeaOlhiE6gwSWLCN5kWNMEvw4v33m2RBScuXpYlQfhbRa1OMA==" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=debug msg="completed challenge" Mar 29 18:44:14 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:14+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:15 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:15+03:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.65.9:80: connect: connection refused" Mar 29 18:44:15 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:15+03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 29 18:44:15 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:44:15 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 18:44:15 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 18:44:15 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:15+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:15 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:15+03:00" level=debug msg="completed challenge" Mar 29 18:44:15 minidsp-shd volumio-remote-updater[500]: No test mode Mar 29 18:44:15 minidsp-shd volumio-remote-updater[500]: No alpha test mode Mar 29 18:44:15 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 18:44:15 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:15+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:16 minidsp-shd volumio[12379]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 18:44:16 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 18:44:16 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:16+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 29 18:44:16 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:16+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:16 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:16+03:00" level=debug msg="completed challenge" Mar 29 18:44:16 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:16+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:17 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 18:44:17 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 29 18:44:17 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:44:17 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:17 minidsp-shd volumio[12379]: info: Getting Spotify volume Mar 29 18:44:17 minidsp-shd volumio[12379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 29 18:44:17 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetState Mar 29 18:44:17 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:17 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:17+03:00" level=debug msg="connected to ap-gae2.spotify.com:80" Mar 29 18:44:17 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:17+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:17 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:17+03:00" level=debug msg="completed challenge" Mar 29 18:44:17 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:17+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:18 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:18+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:44:18 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:18+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:18 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:18+03:00" level=debug msg="completed challenge" Mar 29 18:44:18 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:18+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:19 minidsp-shd volumio[12379]: info: BOOT COMPLETED Mar 29 18:44:19 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:19+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:44:19 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:19+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:19 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:19+03:00" level=debug msg="completed challenge" Mar 29 18:44:19 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:19+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:19 minidsp-shd go-librespot[13047]: time="2026-03-29T18:44:19+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:19 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 29 18:44:19 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 29 18:44:19 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Error: socket hang up Mar 29 18:44:19 minidsp-shd volumio[12379]: at connResetException (internal/errors.js:639:14) Mar 29 18:44:19 minidsp-shd volumio[12379]: at Socket.socketOnEnd (_http_client.js:499:23) Mar 29 18:44:19 minidsp-shd volumio[12379]: at Socket.emit (events.js:412:35) Mar 29 18:44:19 minidsp-shd volumio[12379]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 29 18:44:19 minidsp-shd volumio[12379]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 29 18:44:19 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Mar 29 18:44:19 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket closed Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 18:44:22 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 18:44:22 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:44:22 minidsp-shd volumio[12379]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 18:44:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 29 18:44:22 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Mar 29 18:44:22 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 29 18:44:22 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 29 18:44:22 minidsp-shd go-librespot[13111]: go-librespot daemon starting... Mar 29 18:44:22 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:22+03:00" level=info msg="running go-librespot 0.7.1" Mar 29 18:44:22 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:22+03:00" level=debug msg="app state loaded" Mar 29 18:44:22 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:22+03:00" level=debug msg="stored credentials not found" Mar 29 18:44:22 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:22+03:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=info msg="zeroconf server listening on port 37653" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="obtained new client token: AABdyflR79DQOvR6lgPjFKFACY+FMhII4zsEi5ZMDMMaNsn8BVHUhA3xRDBieudgZypO1RE6qwfX/zy2sfhPSxQtRAZVYkPmPt2VP7c+GwQOxnDBsQc7hQv7IaTJb2lJyN7VYRqKC4e3fwXmV+majQpsuF/4qeop22fDweTm+fxAH6D3I5gVj1i6kqKPl88e6pD7/vC5b+ts27ivic1/woH1NzGTKOA4VH+dZ2DCeSsgUAD3/uKZmQ==" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=debug msg="completed challenge" Mar 29 18:44:23 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:23+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:24 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:24+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:44:24 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:24+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:24 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:24+03:00" level=debug msg="completed challenge" Mar 29 18:44:24 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:24+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:25 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:25+03:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 29 18:44:25 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:25+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:25 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:25+03:00" level=debug msg="completed challenge" Mar 29 18:44:25 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:44:25 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:25+03:00" level=debug msg="new websocket client" Mar 29 18:44:25 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket established Mar 29 18:44:25 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:25+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:26 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:26 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:26 minidsp-shd volumio[12379]: info: Adding Item to queue: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Mar 29 18:44:26 minidsp-shd volumio[12379]: info: Using cached record of: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPushQueue Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CorePlayQueue::saveQueue Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::updateTrackBlock Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrackBlock Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioPlay Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::play index 464 Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::stop Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::play index undefined Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreStateMachine::startPlaybackTimer Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CorePlayQueue::getTrack 464 Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 18:44:26 minidsp-shd volumio[12379]: info: METAVOLUMIO: Retrieving similar tracks from metavolumio Mar 29 18:44:26 minidsp-shd volumio[12379]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Avril%20Lavigne%20%26%20Cyndi%20Lauper%20-%20Girls%20Just%20Want%20To%20Have%20Fun%20LIVE%20%7C%202025%20Rock%20%26%20Roll%20Hall%20of%20Fame%22%2C%22artist%22%3A%22Sliper%20Slip%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FSOeYWLfSdcY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3nAdguQfgWNrXjSVt-tublAEQg1sg%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22SOeYWLfSdcY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Mar 29 18:44:26 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:26+03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 29 18:44:26 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:26+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:26 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:26+03:00" level=debug msg="completed challenge" Mar 29 18:44:26 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:26+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: my_music , getSuperSearchEnabledSourcesList Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 18:44:26 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: All cached search sources collected Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: soundcloud , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: ytmusic , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: 80s80s , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: inputs , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: tidal , search Mar 29 18:44:27 minidsp-shd volumio[12379]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 29 18:44:27 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:27+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 29 18:44:27 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:27+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:27 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:27+03:00" level=debug msg="completed challenge" Mar 29 18:44:27 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:27+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:28 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:28+03:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Mar 29 18:44:28 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:28+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:44:28 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:28+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:28 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:28+03:00" level=debug msg="completed challenge" Mar 29 18:44:28 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:28+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:28 minidsp-shd go-librespot[13111]: time="2026-03-29T18:44:28+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 29 18:44:28 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 29 18:44:28 minidsp-shd volumio[12379]: info: Getting Spotify volume Mar 29 18:44:28 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 29 18:44:28 minidsp-shd volumio[12379]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Mar 29 18:44:28 minidsp-shd volumio[12379]: (node:12379) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Mar 29 18:44:28 minidsp-shd volumio[12379]: info: Connection to go-librespot Websocket closed Mar 29 18:44:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 29 18:44:31 minidsp-shd systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Mar 29 18:44:31 minidsp-shd systemd[1]: Stopped go-librespot Daemon. Mar 29 18:44:31 minidsp-shd systemd[1]: Started go-librespot Daemon. Mar 29 18:44:31 minidsp-shd go-librespot[13133]: go-librespot daemon starting... Mar 29 18:44:31 minidsp-shd volumio[12379]: info: Initializing connection to go-librespot Websocket Mar 29 18:44:31 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:31+03:00" level=info msg="running go-librespot 0.7.1" Mar 29 18:44:31 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:31+03:00" level=debug msg="app state loaded" Mar 29 18:44:31 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:31+03:00" level=debug msg="stored credentials not found" Mar 29 18:44:31 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:31+03:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=info msg="zeroconf server listening on port 39887" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="obtained new client token: AAAInCTCGyTHu7bxuXNDbGpj0qaEFxcf8V+40ObuEounwvXya9K5jG7SMZq8ef2gAm5Sqen0xCL11qc3TuXLPEVvOiUFEQI8dXHdmQJ3pmMbmaAvCImDLkpz5jfEo5EKKrm35IbMASCjsolP9t8BQvam0Jh3YGWYOav4Fe4AGKqwYS96zlejJUkdGQlNlFZJ/I9XYgqN7ilpBqglYcjW+Cvh+RD/diPPLShGlMjiyKQ3ERTq+07auQ==" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=debug msg="completed challenge" Mar 29 18:44:32 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:32+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:33 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:33+03:00" level=debug msg="connected to ap-gew1.spotify.com:443" Mar 29 18:44:33 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:33+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:33 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:33+03:00" level=debug msg="completed challenge" Mar 29 18:44:33 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:33+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:34 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:34+03:00" level=debug msg="connected to ap-gew1.spotify.com:80" Mar 29 18:44:34 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:34+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:34 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:34+03:00" level=debug msg="completed challenge" Mar 29 18:44:34 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:34+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:35 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:35+03:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Mar 29 18:44:35 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:35+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:35 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:35+03:00" level=debug msg="completed challenge" Mar 29 18:44:35 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:35+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:36 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:36+03:00" level=debug msg="connected to ap-gew4.spotify.com:443" Mar 29 18:44:36 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:36+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:36 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:36+03:00" level=debug msg="completed challenge" Mar 29 18:44:36 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:36+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:513MEZlokmpMxeE7nY9JTO Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:513MEZlokmpMxeE7nY9JTO in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:4y1LsJpmMti1PfRQV9AWWe Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:4y1LsJpmMti1PfRQV9AWWe in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:3AXDLGI5TIYi72JcYFvNpv Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:3AXDLGI5TIYi72JcYFvNpv in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:0vOkmmJEtjuFZDzrQSFzEE Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:0vOkmmJEtjuFZDzrQSFzEE in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:37+03:00" level=debug msg="connected to ap-gae2.spotify.com:80" Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:1ryT9eOWD1JuxPD5ew53SJ Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:1ryT9eOWD1JuxPD5ew53SJ in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:1Jwc3ODLQxtbnS8M9TflSP Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:1Jwc3ODLQxtbnS8M9TflSP in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:39lSeqnyjZJejRuaREfyLL Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:39lSeqnyjZJejRuaREfyLL in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:7bkyXSi4GtVfD7itZRUR3e Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:7bkyXSi4GtVfD7itZRUR3e in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:5kz9GDBTX846OXwqWoyKzF Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:5kz9GDBTX846OXwqWoyKzF in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:2Cdvbe2G4hZsnhNMKyGrie Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:2Cdvbe2G4hZsnhNMKyGrie in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin soundcloud timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin inputs timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: error: Search in plugin tidal timed out Mar 29 18:44:37 minidsp-shd volumio[12379]: info: All search sources collected, pushing search results Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioGetQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::getQueue Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreCommandRouter::volumioAddQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CoreStateMachine::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: CorePlayQueue::addQueueItems Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Preload queue cleared Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Adding Item to queue: spotify:track:33ORrHQvjcWEzf8IxY77Cr Mar 29 18:44:37 minidsp-shd volumio[12379]: info: Exploding uri spotify:track:33ORrHQvjcWEzf8IxY77Cr in service spop Mar 29 18:44:37 minidsp-shd volumio[12379]: info: METAVOLUMIO: Infinity Playback added 11 Tracks to queue Mar 29 18:44:37 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:37+03:00" level=debug msg="completed keyexchange" Mar 29 18:44:37 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:37+03:00" level=debug msg="completed challenge" Mar 29 18:44:37 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:37+03:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:37 minidsp-shd go-librespot[13133]: time="2026-03-29T18:44:37+03:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: accesspoint login failed: BadCredentials " Mar 29 18:44:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 29 18:44:37 minidsp-shd systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 29 18:44:38 minidsp-shd volumio[12379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:44:38 minidsp-shd volumio[12379]: Error: connect ECONNREFUSED 127.0.0.1:443 Mar 29 18:44:38 minidsp-shd volumio[12379]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 29 18:44:38 minidsp-shd volumio[12379]: errno: -111, Mar 29 18:44:38 minidsp-shd volumio[12379]: code: 'ECONNREFUSED', Mar 29 18:44:38 minidsp-shd volumio[12379]: syscall: 'connect', Mar 29 18:44:38 minidsp-shd volumio[12379]: address: '127.0.0.1', Mar 29 18:44:38 minidsp-shd volumio[12379]: port: 443 Mar 29 18:44:38 minidsp-shd volumio[12379]: } Mar 29 18:44:38 minidsp-shd volumio[12379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 18:44:39 minidsp-shd sudo[13164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 18:43 Mar 29 18:44:39 minidsp-shd sudo[13164]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 10:55:12 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="nanopineo2" VOLUMIO_DEVICENAME="NanoPi Neo2" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="0dc70286d49f98e8b92f179804dc6fb6"