-- Logs begin at Fri 2025-07-11 13:26:00 BST, end at Fri 2025-07-11 13:50:35 BST. -- Jul 11 13:49:13 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:49:13 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:49:13 rivoplus volumio[3336]: info: Discovery: Getting this device information Jul 11 13:49:13 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetState Jul 11 13:49:13 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:49:18 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 2 Jul 11 13:49:18 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 3 Jul 11 13:49:18 rivoplus volumio[3336]: info: Prefetching next song Jul 11 13:49:18 rivoplus volumio[3336]: info: [1752238158171] ControllerTidal::prefetch Jul 11 13:49:18 rivoplus volumio[3336]: info: Getting stream with soundQuality HI_RES Jul 11 13:49:18 rivoplus volumio[3336]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/444023363&soundQuality=HI_RES" Jul 11 13:49:18 rivoplus volumio[3336]: info: Jul 11 13:49:18 rivoplus volumio[3336]: ---------------------------- MPD announces system playlist update Jul 11 13:49:18 rivoplus volumio[3336]: info: Ignoring MPD Status Update Jul 11 13:49:18 rivoplus volumio[3336]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/444023363&soundQuality=HI_RES" took 9 milliseconds Jul 11 13:49:18 rivoplus volumio[3336]: STREAMING PROXY: Handling url /?data=tidal://song/444023363&soundQuality=HI_RES Jul 11 13:49:18 rivoplus volumio[3336]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 11 13:49:18 rivoplus volumio[3336]: verbose: ControllerMpd::sendMpdCommand consume 1 Jul 11 13:49:18 rivoplus volumio[3336]: info: Jul 11 13:49:18 rivoplus volumio[3336]: ---------------------------- MPD announces system playlist update Jul 11 13:49:18 rivoplus volumio[3336]: info: Ignoring MPD Status Update Jul 11 13:49:18 rivoplus volumio[3336]: info: ------------------------------ 6ms Jul 11 13:49:18 rivoplus volumio[3336]: info: sendMpdCommand consume 1 took 5 milliseconds Jul 11 13:49:18 rivoplus volumio[3336]: info: ------------------------------ 5ms Jul 11 13:49:18 rivoplus volumio[3336]: info: Executing endpoint getStreamUrltidal Jul 11 13:49:18 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jul 11 13:49:18 rivoplus volumio[3336]: info: getStreamUrl took 454 milliseconds Jul 11 13:49:18 rivoplus volumio[3336]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQxMGIyZmQ4NGRkMTdiYTA0Y2FmNjhiZWE0MDFlYTljZC5tcDQ/0.flac?token=1752241758~Njg2NDNkYTM2MGM0NTVlYTQyMzVlOTFjNzIxNDI2MjZjNDcyYWE4ZQ== Jul 11 13:49:18 rivoplus volumio[3336]: STREAMING PROXY: Response: 200, length: 31996175 Jul 11 13:49:20 rivoplus volumio[3336]: info: Jul 11 13:49:20 rivoplus volumio[3336]: ---------------------------- MPD announces system playlist update Jul 11 13:49:20 rivoplus volumio[3336]: info: Ignoring MPD Status Update Jul 11 13:49:20 rivoplus volumio[3336]: info: Jul 11 13:49:20 rivoplus volumio[3336]: ---------------------------- MPD announces state update: player Jul 11 13:49:20 rivoplus volumio[3336]: info: ControllerMpd::getState Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::sendMpdCommand status Jul 11 13:49:20 rivoplus volumio[3336]: info: Jul 11 13:49:20 rivoplus volumio[3336]: ---------------------------- MPD announces system playlist update Jul 11 13:49:20 rivoplus volumio[3336]: info: Ignoring MPD Status Update Jul 11 13:49:20 rivoplus volumio[3336]: info: Jul 11 13:49:20 rivoplus volumio[3336]: ---------------------------- MPD announces state update: player Jul 11 13:49:20 rivoplus volumio[3336]: info: ControllerMpd::getState Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::sendMpdCommand status Jul 11 13:49:20 rivoplus volumio[3336]: info: ------------------------------ 13ms Jul 11 13:49:20 rivoplus volumio[3336]: info: sendMpdCommand status took 12 milliseconds Jul 11 13:49:20 rivoplus volumio[3336]: info: ------------------------------ 11ms Jul 11 13:49:20 rivoplus volumio[3336]: info: sendMpdCommand status took 11 milliseconds Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::parseState Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::parseState Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 11 13:49:20 rivoplus volumio[3336]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 11 13:49:20 rivoplus volumio[3336]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::parseTrackInfo Jul 11 13:49:20 rivoplus volumio[3336]: verbose: ControllerMpd::parseTrackInfo Jul 11 13:49:20 rivoplus volumio[3336]: info: ControllerMpd::pushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::servicePushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 2 Jul 11 13:49:20 rivoplus volumio[3336]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"444023363&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444023363&soundQuality=HI_RES","trackType":"tidal"} Jul 11 13:49:20 rivoplus volumio[3336]: verbose: CURRENT POSITION 2 Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::syncState stateService play Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::syncState currentStatus play Jul 11 13:49:20 rivoplus volumio[3336]: info: Received an update from plugin. extracting info from payload Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::pushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::volumioPushState Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output update for this device Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::pushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::volumioPushState Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output update for this device Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output Jul 11 13:49:20 rivoplus volumio[3336]: info: ControllerMpd::pushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::servicePushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 2 Jul 11 13:49:20 rivoplus volumio[3336]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"444023363&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/444023363&soundQuality=HI_RES","trackType":"tidal"} Jul 11 13:49:20 rivoplus volumio[3336]: verbose: CURRENT POSITION 2 Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::syncState stateService play Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::syncState currentStatus play Jul 11 13:49:20 rivoplus volumio[3336]: info: Received an update from plugin. extracting info from payload Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::pushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::volumioPushState Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output update for this device Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreStateMachine::pushState Jul 11 13:49:20 rivoplus volumio[3336]: info: CoreCommandRouter::volumioPushState Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output update for this device Jul 11 13:49:20 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output Jul 11 13:49:20 rivoplus volumio[3336]: info: ------------------------------ 107ms Jul 11 13:49:20 rivoplus volumio[3336]: info: ------------------------------ 106ms Jul 11 13:49:21 rivoplus volumio[3336]: info: Signalling Playback active due to playback status change Jul 11 13:49:21 rivoplus volumio[3336]: info: Signalling Playback active due to playback status change Jul 11 13:49:21 rivoplus volumio[3336]: info: Signalling Playback active due to playback status change Jul 11 13:49:21 rivoplus volumio[3336]: info: Signalling Playback active due to playback status change Jul 11 13:49:22 rivoplus volumio[3336]: info: CoreStateMachine::startPlaybackTimer Jul 11 13:49:22 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 3 Jul 11 13:49:23 rivoplus volumio[3336]: info: CoreStateMachine::pushState Jul 11 13:49:23 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 3 Jul 11 13:49:23 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 13:49:23 rivoplus volumio[3336]: info: CoreCommandRouter::volumioPushState Jul 11 13:49:23 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output update for this device Jul 11 13:49:23 rivoplus volumio[3336]: info: MRS: Pushing multiroomSync output Jul 11 13:49:23 rivoplus volumio[3336]: info: Signalling Playback active due to playback status change Jul 11 13:49:33 rivoplus volumio[3336]: STREAMING PROXY: Client dropped request, destroying Jul 11 13:49:43 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:49:43 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:49:43 rivoplus volumio[3336]: info: Discovery: Getting this device information Jul 11 13:49:43 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetState Jul 11 13:49:43 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 3 Jul 11 13:49:43 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:50:06 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 11 13:50:06 rivoplus volumio[3336]: info: Received Get System Info Jul 11 13:50:06 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:50:06 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:50:06 rivoplus volumio[3336]: info: Discovery: Getting this device information Jul 11 13:50:06 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetState Jul 11 13:50:06 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 3 Jul 11 13:50:06 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:50:13 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 11 13:50:13 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 11 13:50:13 rivoplus volumio[3336]: info: Discovery: Getting this device information Jul 11 13:50:13 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetState Jul 11 13:50:13 rivoplus volumio[3336]: info: CorePlayQueue::getTrack 3 Jul 11 13:50:13 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:50:22 rivoplus volumio[3336]: info: Retrieving Cloud Streaming UI Jul 11 13:50:22 rivoplus volumio[3336]: info: Getting Tidal Cloud Configuration Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:50:22 rivoplus volumio[3336]: info: Getting Qobuz Cloud Configuration Jul 11 13:50:22 rivoplus volumio[3336]: info: Asking plugin for UI Config Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:50:22 rivoplus volumio[3336]: info: Getting Spotify Cloud Configuration Jul 11 13:50:22 rivoplus volumio[3336]: info: Asking plugin for UI Config Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:50:22 rivoplus volumio[3336]: info: Saving Spotify Acccount Jul 11 13:50:22 rivoplus volumio[3336]: info: Got it Jul 11 13:50:22 rivoplus volumio[3336]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 11 13:50:22 rivoplus volumio[3336]: info: Got Tidal Cloud Configuration Jul 11 13:50:22 rivoplus volumio[3336]: info: Got it Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::volumioGetBrowseSources Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:50:22 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 11 13:50:26 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:50:30 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:50:32 rivoplus volumio[3336]: info: Disabling MyMusic plugin upnp Jul 11 13:50:32 rivoplus sudo[8738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 11 13:50:32 rivoplus sudo[8738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 13:50:32 rivoplus volumio[3336]: error: Upnp client error: Error: This socket has been ended by the other party Jul 11 13:50:32 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 11 13:50:32 rivoplus volumio[3336]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 11 13:50:34 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 11 13:50:34 rivoplus volumio[3336]: info: Enabling MyMusic plugin upnp Jul 11 13:50:34 rivoplus volumio[3336]: info: Enabling plugin upnp Jul 11 13:50:34 rivoplus volumio[3336]: info: Loading plugin "upnp"... Jul 11 13:50:34 rivoplus volumio[3336]: info: [1752238234757] Starting Upmpd Daemon Jul 11 13:50:34 rivoplus volumio[3336]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 13:50:34 rivoplus volumio[3336]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 13:50:34 rivoplus volumio[3336]: Error: listen EADDRINUSE: address already in use :::6599 Jul 11 13:50:34 rivoplus volumio[3336]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jul 11 13:50:34 rivoplus volumio[3336]: at listenInCluster (net.js:1379:12) Jul 11 13:50:34 rivoplus volumio[3336]: at Server.listen (net.js:1465:7) Jul 11 13:50:34 rivoplus volumio[3336]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 11 13:50:34 rivoplus volumio[3336]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 11 13:50:34 rivoplus volumio[3336]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 11 13:50:34 rivoplus volumio[3336]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 11 13:50:34 rivoplus volumio[3336]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jul 11 13:50:34 rivoplus volumio[3336]: code: 'EADDRINUSE', Jul 11 13:50:34 rivoplus volumio[3336]: errno: -98, Jul 11 13:50:34 rivoplus volumio[3336]: syscall: 'listen', Jul 11 13:50:34 rivoplus volumio[3336]: address: '::', Jul 11 13:50:34 rivoplus volumio[3336]: port: 6599 Jul 11 13:50:34 rivoplus volumio[3336]: } Jul 11 13:50:34 rivoplus volumio[3336]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 13:50:35 rivoplus sudo[8749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-11 13:49 Jul 11 13:50:35 rivoplus sudo[8749]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:55:44 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="66719bc94798fca30ca68a8e6d955a83"