-- Logs begin at Tue 2025-12-09 14:22:08 GMT, end at Tue 2025-12-09 14:40:52 GMT. -- Dec 09 14:39:09 rivoplus volumio[3121]: error: Upnp client error: Error: This socket has been ended by the other party Dec 09 14:39:12 rivoplus volumio[3121]: info: FusionDsp - ---- read samplerate, raw: Dec 09 14:39:12 rivoplus volumio[3121]: error: FusionDsp - invalid sample rate Dec 09 14:39:12 rivoplus volumio[3121]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 09 14:39:12 rivoplus volumio[3121]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 09 14:39:12 rivoplus volumio[3121]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 09 14:39:12 rivoplus volumio[3121]: info: Dec 09 14:39:12 rivoplus volumio[3121]: ---------------------------- MPD announces state update: player Dec 09 14:39:12 rivoplus volumio[3121]: info: ControllerMpd::getState Dec 09 14:39:12 rivoplus volumio[3121]: verbose: ControllerMpd::sendMpdCommand status Dec 09 14:39:12 rivoplus volumio[3121]: info: sendMpdCommand status took 8 milliseconds Dec 09 14:39:12 rivoplus volumio[3121]: verbose: ControllerMpd::parseState Dec 09 14:39:12 rivoplus volumio[3121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 14:39:12 rivoplus volumio[3121]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 09 14:39:12 rivoplus volumio[3121]: verbose: ControllerMpd::parseTrackInfo Dec 09 14:39:12 rivoplus volumio[3121]: info: ControllerMpd::pushState Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreCommandRouter::servicePushState Dec 09 14:39:12 rivoplus volumio[3121]: info: CorePlayQueue::getTrack 0 Dec 09 14:39:12 rivoplus volumio[3121]: verbose: STATE SERVICE {"status":"play","position":0,"seek":17068,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1194 Kbps","isStreaming":false,"title":"Fall Apart","artist":"Juliana Hatfield","album":"Lightning Might Strike","uri":"http://192.168.50.200:26125/content/c2/b16/f44100/d-6331931176304266197-co3D1AB523512F64DB.flac","trackType":"flac"} Dec 09 14:39:12 rivoplus volumio[3121]: verbose: CURRENT POSITION 0 Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreStateMachine::syncState stateService play Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreStateMachine::syncState currentStatus pause Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreStateMachine::pushState Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreCommandRouter::volumioPushState Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetState Dec 09 14:39:12 rivoplus volumio[3121]: info: ------------------------------ 78ms Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetQueue Dec 09 14:39:12 rivoplus volumio[3121]: info: CoreStateMachine::getQueue Dec 09 14:39:12 rivoplus volumio[3121]: info: CorePlayQueue::getQueue Dec 09 14:39:12 rivoplus volumio[3121]: info: Signalling Playback active due to playback status change Dec 09 14:39:12 rivoplus volumio[3121]: info: Signalling Playback active due to playback status change Dec 09 14:39:12 rivoplus volumio[3121]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 09 14:39:12 rivoplus volumio[3121]: info: MCU Signalled Playback Active Dec 09 14:39:32 rivoplus kernel: usb 1-1.4.2: USB disconnect, device number 7 Dec 09 14:39:32 rivoplus volumio[3121]: info: Dec 09 14:39:32 rivoplus volumio[3121]: ---------------------------- USB Audio Device Detached Dec 09 14:39:32 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 09 14:39:32 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 14:39:32 rivoplus volumio[3121]: No protocol specified Dec 09 14:39:32 rivoplus volumio[3121]: xcb_connection_has_error() returned true Dec 09 14:40:14 rivoplus volumio[3121]: error: Upnp client error: Error: This socket has been ended by the other party Dec 09 14:40:16 rivoplus volumio[3121]: info: Dec 09 14:40:16 rivoplus volumio[3121]: ---------------------------- MPD announces state update: player Dec 09 14:40:16 rivoplus volumio[3121]: info: ControllerMpd::getState Dec 09 14:40:16 rivoplus volumio[3121]: verbose: ControllerMpd::sendMpdCommand status Dec 09 14:40:16 rivoplus volumio[3121]: info: sendMpdCommand status took 11 milliseconds Dec 09 14:40:16 rivoplus volumio[3121]: verbose: ControllerMpd::parseState Dec 09 14:40:16 rivoplus volumio[3121]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 09 14:40:16 rivoplus volumio[3121]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 09 14:40:16 rivoplus volumio[3121]: verbose: ControllerMpd::parseTrackInfo Dec 09 14:40:16 rivoplus volumio[3121]: info: ControllerMpd::pushState Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreCommandRouter::servicePushState Dec 09 14:40:16 rivoplus volumio[3121]: info: CorePlayQueue::getTrack 0 Dec 09 14:40:16 rivoplus volumio[3121]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":81566,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1010 Kbps","isStreaming":false,"title":"Fall Apart","artist":"Juliana Hatfield","album":"Lightning Might Strike","uri":"http://192.168.50.200:26125/content/c2/b16/f44100/d-6331931176304266197-co3D1AB523512F64DB.flac","trackType":"flac"} Dec 09 14:40:16 rivoplus volumio[3121]: verbose: CURRENT POSITION 0 Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreStateMachine::syncState stateService pause Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreStateMachine::syncState currentStatus play Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreStateMachine::pushState Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreCommandRouter::volumioPushState Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetState Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreStateMachine::stPlaybackTimer Dec 09 14:40:16 rivoplus volumio[3121]: info: ------------------------------ 67ms Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetQueue Dec 09 14:40:16 rivoplus volumio[3121]: info: CoreStateMachine::getQueue Dec 09 14:40:16 rivoplus volumio[3121]: info: CorePlayQueue::getQueue Dec 09 14:40:16 rivoplus volumio[3121]: info: MCU Signalled Playback Inactive Dec 09 14:40:17 rivoplus volumio[3121]: info: camilladsp spawned new process with pid 9122, instance 1, run: true Dec 09 14:40:28 rivoplus volumio[3121]: verbose: New Socket.io Connection to 192.168.50.52 from 192.168.50.70 UA: Mozilla/5.0 (Linux; Android 16; SM-A546E Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.174 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetVisibleSources Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetState Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 09 14:40:28 rivoplus volumio[3121]: info: Received Get System Info Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 09 14:40:28 rivoplus volumio[3121]: info: Discovery: Getting this device information Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetState Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetState Dec 09 14:40:28 rivoplus volumio[3121]: info: Listing playlists Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetQueue Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreStateMachine::getQueue Dec 09 14:40:28 rivoplus volumio[3121]: info: CorePlayQueue::getQueue Dec 09 14:40:28 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 09 14:40:29 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:29 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:31 rivoplus volumio[3121]: No protocol specified Dec 09 14:40:31 rivoplus volumio[3121]: xcb_connection_has_error() returned true Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 09 14:40:31 rivoplus volumio[3121]: No protocol specified Dec 09 14:40:31 rivoplus volumio[3121]: xcb_connection_has_error() returned true Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 14:40:31 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:40 rivoplus volumio[3121]: info: Retrieving Cloud Streaming UI Dec 09 14:40:40 rivoplus volumio[3121]: info: Getting Tidal Cloud Configuration Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:40 rivoplus volumio[3121]: info: Getting Qobuz Cloud Configuration Dec 09 14:40:40 rivoplus volumio[3121]: info: Asking plugin for UI Config Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:40 rivoplus volumio[3121]: info: Getting Spotify Cloud Configuration Dec 09 14:40:40 rivoplus volumio[3121]: info: Asking plugin for UI Config Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:40 rivoplus volumio[3121]: info: Saving Spotify Acccount Dec 09 14:40:40 rivoplus volumio[3121]: info: Got it Dec 09 14:40:40 rivoplus volumio[3121]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 09 14:40:40 rivoplus volumio[3121]: info: Got Tidal Cloud Configuration Dec 09 14:40:40 rivoplus volumio[3121]: info: Got it Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::volumioGetBrowseSources Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 14:40:40 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 09 14:40:43 rivoplus volumio[3121]: info: Disabling MyMusic plugin upnp Dec 09 14:40:43 rivoplus sudo[9194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 09 14:40:43 rivoplus sudo[9194]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 09 14:40:43 rivoplus systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 09 14:40:43 rivoplus volumio[3121]: error: Upnp client error: Error: This socket has been ended by the other party Dec 09 14:40:43 rivoplus volumio[3121]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 09 14:40:44 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 14:40:48 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 09 14:40:52 rivoplus volumio[3121]: info: Enabling MyMusic plugin upnp Dec 09 14:40:52 rivoplus volumio[3121]: info: Enabling plugin upnp Dec 09 14:40:52 rivoplus volumio[3121]: info: Loading plugin "upnp"... Dec 09 14:40:52 rivoplus volumio[3121]: info: [1765291252034] Starting Upmpd Daemon Dec 09 14:40:52 rivoplus volumio[3121]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 09 14:40:52 rivoplus volumio[3121]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 14:40:52 rivoplus volumio[3121]: Error: listen EADDRINUSE: address already in use :::6599 Dec 09 14:40:52 rivoplus volumio[3121]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Dec 09 14:40:52 rivoplus volumio[3121]: at listenInCluster (net.js:1379:12) Dec 09 14:40:52 rivoplus volumio[3121]: at Server.listen (net.js:1465:7) Dec 09 14:40:52 rivoplus volumio[3121]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 09 14:40:52 rivoplus volumio[3121]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 09 14:40:52 rivoplus volumio[3121]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 09 14:40:52 rivoplus volumio[3121]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 09 14:40:52 rivoplus volumio[3121]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Dec 09 14:40:52 rivoplus volumio[3121]: code: 'EADDRINUSE', Dec 09 14:40:52 rivoplus volumio[3121]: errno: -98, Dec 09 14:40:52 rivoplus volumio[3121]: syscall: 'listen', Dec 09 14:40:52 rivoplus volumio[3121]: address: '::', Dec 09 14:40:52 rivoplus volumio[3121]: port: 6599 Dec 09 14:40:52 rivoplus volumio[3121]: } Dec 09 14:40:52 rivoplus volumio[3121]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 09 14:40:52 rivoplus sudo[9210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-09 14:39 Dec 09 14:40:52 rivoplus sudo[9210]: 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="889fa4d0fff9f001a4a3cc827407782acb637da4" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 05 Dec 2025 12:18:58 PM CET" VOLUMIO_VERSION="3.882" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="dc53f874dca30d64e2c1c7c9e4411549"