Jan 26 18:49:06 volumio volumio[1235]: info: CoreCommandRouter::volumioGetState Jan 26 18:49:06 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:49:12 volumio volumio[1235]: info: Retrieving Cloud Streaming UI Jan 26 18:49:12 volumio volumio[1235]: info: Getting Tidal Cloud Configuration Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:49:12 volumio volumio[1235]: info: Getting Qobuz Cloud Configuration Jan 26 18:49:12 volumio volumio[1235]: info: Asking plugin for UI Config Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:49:12 volumio volumio[1235]: info: Getting Spotify Cloud Configuration Jan 26 18:49:12 volumio volumio[1235]: info: Asking plugin for UI Config Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:49:12 volumio volumio[1235]: info: Saving Spotify Acccount Jan 26 18:49:12 volumio volumio[1235]: info: Got Tidal Cloud Configuration Jan 26 18:49:12 volumio volumio[1235]: info: Got it Jan 26 18:49:12 volumio volumio[1235]: info: Got it Jan 26 18:49:12 volumio volumio[1235]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 18:49:12 volumio volumio[1235]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::volumioGetBrowseSources Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:12 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 26 18:49:15 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand rescan Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:49:15 volumio volumio[1235]: info: Jan 26 18:49:15 volumio volumio[1235]: ---------------------------- MPD announces state update: update Jan 26 18:49:15 volumio volumio[1235]: info: sendMpdCommand rescan took 3 milliseconds Jan 26 18:49:15 volumio volumio[1235]: info: ControllerMpd::getState Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:49:15 volumio volumio[1235]: info: sendMpdCommand status took 2 milliseconds Jan 26 18:49:15 volumio volumio[1235]: info: sendMpdCommand status took 1 milliseconds Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::parseState Jan 26 18:49:15 volumio volumio[1235]: info: Command Router : Notfying DB Updatetrue Jan 26 18:49:15 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::parseState Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 18:49:15 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 26 18:49:15 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo Jan 26 18:49:15 volumio volumio[1235]: info: ControllerMpd::pushState Jan 26 18:49:15 volumio volumio[1235]: info: CoreCommandRouter::servicePushState Jan 26 18:49:15 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:15 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":220990,"duration":288,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"2879 Kbps","isStreaming":false,"title":"The Doomed","artist":"A Perfect Circle","album":"Eat The Elephant","uri":"NAS/xvoDS920/2018 - A Perfect Circle - Eat The Elephant (2LP, BMG, 538374370, US & EU, 24-96)/2018 - A Perfect Circle - Eat The Elephant.cue/track0004","trackType":""} Jan 26 18:49:15 volumio volumio[1235]: verbose: CURRENT POSITION 3 Jan 26 18:49:15 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play Jan 26 18:49:15 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play Jan 26 18:49:15 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload Jan 26 18:49:15 volumio volumio[1235]: info: CoreStateMachine::pushState Jan 26 18:49:15 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:15 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:49:15 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState Jan 26 18:49:15 volumio volumio[1235]: info: CoreStateMachine::pushState Jan 26 18:49:15 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:15 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState Jan 26 18:49:15 volumio volumio[1235]: info: ------------------------------ 51ms Jan 26 18:49:16 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:17 volumio volumio[1235]: info: MPD Database updated - AlbumList cache refreshed Jan 26 18:49:17 volumio volumio[1235]: info: Jan 26 18:49:17 volumio volumio[1235]: ---------------------------- MPD announces state update: database Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:49:17 volumio volumio[1235]: info: Jan 26 18:49:17 volumio volumio[1235]: ---------------------------- MPD announces state update: update Jan 26 18:49:17 volumio volumio[1235]: info: ControllerMpd::getState Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:49:17 volumio volumio[1235]: info: ControllerMpd::getState Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand status Jan 26 18:49:17 volumio volumio[1235]: info: sendMpdCommand status took 6 milliseconds Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::parseState Jan 26 18:49:17 volumio volumio[1235]: info: Command Router : Notfying DB Updatefalse Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::Close All Modals sent Jan 26 18:49:17 volumio volumio[1235]: info: sendMpdCommand status took 35 milliseconds Jan 26 18:49:17 volumio volumio[1235]: info: sendMpdCommand status took 34 milliseconds Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::parseState Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::parseState Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 26 18:49:17 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 26 18:49:17 volumio volumio[1235]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo Jan 26 18:49:17 volumio volumio[1235]: verbose: ControllerMpd::parseTrackInfo Jan 26 18:49:17 volumio volumio[1235]: info: ControllerMpd::pushState Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::servicePushState Jan 26 18:49:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:17 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":222615,"duration":288,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2847 Kbps","isStreaming":false,"title":"The Doomed","artist":"A Perfect Circle","album":"Eat The Elephant","uri":"NAS/xvoDS920/2018 - A Perfect Circle - Eat The Elephant (2LP, BMG, 538374370, US & EU, 24-96)/2018 - A Perfect Circle - Eat The Elephant.cue/track0004","trackType":""} Jan 26 18:49:17 volumio volumio[1235]: verbose: CURRENT POSITION 3 Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play Jan 26 18:49:17 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::pushState Jan 26 18:49:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::pushState Jan 26 18:49:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState Jan 26 18:49:17 volumio volumio[1235]: info: ControllerMpd::pushState Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::servicePushState Jan 26 18:49:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:17 volumio volumio[1235]: verbose: STATE SERVICE {"status":"play","position":0,"seek":222615,"duration":288,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2847 Kbps","isStreaming":false,"title":"The Doomed","artist":"A Perfect Circle","album":"Eat The Elephant","uri":"NAS/xvoDS920/2018 - A Perfect Circle - Eat The Elephant (2LP, BMG, 538374370, US & EU, 24-96)/2018 - A Perfect Circle - Eat The Elephant.cue/track0004","trackType":""} Jan 26 18:49:17 volumio volumio[1235]: verbose: CURRENT POSITION 3 Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::syncState stateService play Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::syncState currentStatus play Jan 26 18:49:17 volumio volumio[1235]: info: Received an update from plugin. extracting info from payload Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::pushState Jan 26 18:49:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState Jan 26 18:49:17 volumio volumio[1235]: info: CoreStateMachine::pushState Jan 26 18:49:17 volumio volumio[1235]: info: CorePlayQueue::getTrack 3 Jan 26 18:49:17 volumio volumio[1235]: info: CoreCommandRouter::volumioPushState Jan 26 18:49:17 volumio volumio[1235]: info: ------------------------------ 60ms Jan 26 18:49:17 volumio volumio[1235]: info: ------------------------------ 60ms Jan 26 18:49:20 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:24 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:28 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:32 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:34 volumio volumio[1235]: info: Enabling MyMusic plugin metavolumio Jan 26 18:49:34 volumio volumio[1235]: info: [MyVolumio PluginManager] Enabling and starting plugin miscellanea metavolumio Jan 26 18:49:34 volumio volumio[1235]: info: [MyVolumio PluginManager] Plugin miscellanea metavolumio not in user plan, not enabling Jan 26 18:49:34 volumio volumio[1235]: info: Error: Plugin miscellanea metavolumio not in user plan, not enabling Jan 26 18:49:34 volumio volumio[1235]: error: Could not Enable MyMusic Plugin: Error Jan 26 18:49:36 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:40 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:44 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:48 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:52 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:49:56 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:50:00 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:50:03 volumio volumio[1235]: info: Disabling MyMusic plugin upnp Jan 26 18:50:03 volumio sudo[13317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 26 18:50:03 volumio sudo[13317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 26 18:50:03 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Jan 26 18:50:04 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 26 18:50:05 volumio volumio[1235]: info: Enabling MyMusic plugin upnp Jan 26 18:50:05 volumio volumio[1235]: info: Enabling plugin upnp Jan 26 18:50:05 volumio volumio[1235]: info: Loading plugin "upnp"... Jan 26 18:50:05 volumio volumio[1235]: info: [1769442605099] Starting Upmpd Daemon Jan 26 18:50:05 volumio volumio[1235]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 18:50:05 volumio volumio[1235]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:50:05 volumio volumio[1235]: Error: listen EADDRINUSE: address already in use :::6599 Jan 26 18:50:05 volumio volumio[1235]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Jan 26 18:50:05 volumio volumio[1235]: at listenInCluster (node:net:1920:12) Jan 26 18:50:05 volumio volumio[1235]: at Server.listen (node:net:2008:7) Jan 26 18:50:05 volumio volumio[1235]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 26 18:50:05 volumio volumio[1235]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 26 18:50:05 volumio volumio[1235]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 26 18:50:05 volumio volumio[1235]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 26 18:50:05 volumio volumio[1235]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jan 26 18:50:05 volumio volumio[1235]: code: 'EADDRINUSE', Jan 26 18:50:05 volumio volumio[1235]: errno: -98, Jan 26 18:50:05 volumio volumio[1235]: syscall: 'listen', Jan 26 18:50:05 volumio volumio[1235]: address: '::', Jan 26 18:50:05 volumio volumio[1235]: port: 6599 Jan 26 18:50:05 volumio volumio[1235]: } Jan 26 18:50:05 volumio volumio[1235]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 26 18:50:05 volumio sudo[13354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-26 18:49' Jan 26 18:50:05 volumio sudo[13354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"