Dec 17 18:26:07 volumio go-librespot[1744]: time="2025-12-17T18:26:07+01:00" level=error msg="did not receive last pong from dealer, 60s passed" Dec 17 18:26:12 volumio go-librespot[1744]: time="2025-12-17T18:26:12+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Dec 17 18:26:37 volumio go-librespot[1744]: time="2025-12-17T18:26:37+01:00" level=error msg="did not receive last pong from dealer, 90s passed" Dec 17 18:26:41 volumio go-librespot[1744]: time="2025-12-17T18:26:41+01:00" level=debug msg="dealer connection opened" Dec 17 18:26:41 volumio go-librespot[1744]: time="2025-12-17T18:26:41+01:00" level=debug msg="re-established dealer connection" Dec 17 18:26:41 volumio go-librespot[1744]: time="2025-12-17T18:26:41+01:00" level=debug msg="received connection id: Mzg1MjgwMWUtNDA0...RDU3MjUwRjg2RQ==" Dec 17 18:26:41 volumio go-librespot[1744]: time="2025-12-17T18:26:41+01:00" level=debug msg="put connect state because NEW_DEVICE" Dec 17 18:26:50 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:50 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 17 18:26:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 17 18:26:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 17 18:26:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 17 18:26:51 volumio volumio[1213]: info: Received Get System Version Dec 17 18:26:51 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 18:26:57 volumio volumio[1213]: info: CoreCommandRouter::volumioGetState Dec 17 18:26:57 volumio volumio[1213]: info: CorePlayQueue::getTrack 0 Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:59 volumio volumio[1213]: info: Retrieving Cloud Streaming UI Dec 17 18:26:59 volumio volumio[1213]: info: Getting Tidal Cloud Configuration Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:59 volumio volumio[1213]: info: Getting Qobuz Cloud Configuration Dec 17 18:26:59 volumio volumio[1213]: info: Asking plugin for UI Config Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:59 volumio volumio[1213]: info: Getting Spotify Cloud Configuration Dec 17 18:26:59 volumio volumio[1213]: info: Asking plugin for UI Config Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:59 volumio volumio[1213]: info: Saving Spotify Acccount Dec 17 18:26:59 volumio volumio[1213]: info: Got Tidal Cloud Configuration Dec 17 18:26:59 volumio volumio[1213]: info: Got it Dec 17 18:26:59 volumio volumio[1213]: info: Got it Dec 17 18:26:59 volumio volumio[1213]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 18:26:59 volumio volumio[1213]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 18:26:59 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 17 18:27:03 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 18:27:07 volumio volumio[1213]: info: Enabling MyMusic plugin qobuzconnect Dec 17 18:27:07 volumio volumio[1213]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service qobuzconnect Dec 17 18:27:07 volumio volumio[1213]: info: [MyVolumio PluginManager] Plugin music_service qobuzconnect not in user plan, not enabling Dec 17 18:27:07 volumio volumio[1213]: info: Error: Plugin music_service qobuzconnect not in user plan, not enabling Dec 17 18:27:07 volumio volumio[1213]: error: Could not Enable MyMusic Plugin: Error Dec 17 18:27:07 volumio go-librespot[1744]: time="2025-12-17T18:27:07+01:00" level=trace msg="received accesspoint ping" Dec 17 18:27:07 volumio go-librespot[1744]: time="2025-12-17T18:27:07+01:00" level=trace msg="received accesspoint pong ack" Dec 17 18:27:07 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 18:27:07 volumio go-librespot[1744]: time="2025-12-17T18:27:07+01:00" level=trace msg="sent dealer ping" Dec 17 18:27:07 volumio go-librespot[1744]: time="2025-12-17T18:27:07+01:00" level=trace msg="received dealer pong" Dec 17 18:27:11 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 18:27:15 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 17 18:27:18 volumio volumio[1213]: info: Disabling MyMusic plugin upnp Dec 17 18:27:18 volumio sudo[2592]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 17 18:27:18 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 17 18:27:18 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Dec 17 18:27:19 volumio volumio[1213]: info: Enabling MyMusic plugin upnp Dec 17 18:27:19 volumio volumio[1213]: info: Enabling plugin upnp Dec 17 18:27:19 volumio volumio[1213]: info: Loading plugin "upnp"... Dec 17 18:27:19 volumio volumio[1213]: info: [1765992439466] Starting Upmpd Daemon Dec 17 18:27:19 volumio volumio[1213]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 17 18:27:19 volumio volumio[1213]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 18:27:19 volumio volumio[1213]: Error: listen EADDRINUSE: address already in use :::6599 Dec 17 18:27:19 volumio volumio[1213]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Dec 17 18:27:19 volumio volumio[1213]: at listenInCluster (node:net:1920:12) Dec 17 18:27:19 volumio volumio[1213]: at Server.listen (node:net:2008:7) Dec 17 18:27:19 volumio volumio[1213]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 17 18:27:19 volumio volumio[1213]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 17 18:27:19 volumio volumio[1213]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 17 18:27:19 volumio volumio[1213]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 17 18:27:19 volumio volumio[1213]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 17 18:27:19 volumio volumio[1213]: code: 'EADDRINUSE', Dec 17 18:27:19 volumio volumio[1213]: errno: -98, Dec 17 18:27:19 volumio volumio[1213]: syscall: 'listen', Dec 17 18:27:19 volumio volumio[1213]: address: '::', Dec 17 18:27:19 volumio volumio[1213]: port: 6599 Dec 17 18:27:19 volumio volumio[1213]: } Dec 17 18:27:19 volumio volumio[1213]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 18:27:20 volumio sudo[2623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-17 18:26' Dec 17 18:27:20 volumio sudo[2623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"