-- Logs begin at Thu 2025-03-13 13:30:13 CET, end at Sat 2025-05-10 19:22:50 CEST. --
May 10 19:21:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri
May 10 19:21:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 10 19:21:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 10 19:21:08 rivo volumio[3276]: info: Discovery: Getting this device information
May 10 19:21:08 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:21:08 rivo volumio[3276]: info: CorePlayQueue::getTrack 1
May 10 19:21:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 10 19:21:08 rivo volumio[3276]: info: handleBrowseUri took 527 milliseconds
May 10 19:21:08 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735894
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735895
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735897
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735898
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735899
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735900
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735902
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735904
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735906
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735908
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735910
May 10 19:21:08 rivo volumio[3276]: info: Preloading song: qobuz://song/34735912
May 10 19:21:08 rivo volumio[3276]: info: Exploding uri qobuz://song/34735894 in service qobuz
May 10 19:21:08 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:08 rivo volumio[3276]: info: Exploding uri qobuz://song/34735895 in service qobuz
May 10 19:21:08 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:08 rivo volumio[3276]: info: Exploding uri qobuz://song/34735897 in service qobuz
May 10 19:21:08 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:08 rivo volumio[3276]: info: Exploding uri qobuz://song/34735898 in service qobuz
May 10 19:21:08 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:08 rivo volumio[3276]: info: Exploding uri qobuz://song/34735899 in service qobuz
May 10 19:21:08 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735900 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735902 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: Executing endpoint getSimilarAlbums
May 10 19:21:09 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums
May 10 19:21:09 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:21:09 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:21:09 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:21:09 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735904 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735906 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 420 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735908 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 446 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735910 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: Exploding uri qobuz://song/34735912 in service qobuz
May 10 19:21:09 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 489 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 442 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 468 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 484 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 456 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 466 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 481 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 621 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 474 milliseconds
May 10 19:21:09 rivo volumio[3276]: info: explodeUri took 582 milliseconds
May 10 19:21:23 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::ClearQueue
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::stPlaybackTimer
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::pushState
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::getTrack 1
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::volumioPushState
May 10 19:21:23 rivo volumio[3276]: info: MRS: Pushing multiroomSync output update for this device
May 10 19:21:23 rivo volumio[3276]: info: MRS: Pushing multiroomSync output
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::serviceStop
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::getTrack 1
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::serviceStop
May 10 19:21:23 rivo volumio[3276]: info: [1746897683315] ControllerQobuz::stop
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 19:21:23 rivo volumio[3276]: info: ControllerMpd::stop
May 10 19:21:23 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::clearPlayQueue
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::addQueueItems
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::addQueueItems
May 10 19:21:23 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:23 rivo volumio[3276]: info: Adding Item to queue: qobuz://album/0060255716840
May 10 19:21:23 rivo volumio[3276]: info: Exploding uri qobuz://album/0060255716840 in service qobuz
May 10 19:21:23 rivo volumio[3276]: https://prod.vlmapi.io/v2/qobuz/explodeUri
May 10 19:21:23 rivo volumio[3276]: info: Updating RAAT Signal Path
May 10 19:21:23 rivo volumio[3276]: info: explodeUri took 401 milliseconds
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:21:23 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::play index 0
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:23 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:21:23 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:23 rivo volumio[3276]: info: [1746897683741] ControllerQobuz::clearAddPlayTrack
May 10 19:21:23 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:21:24 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:24 rivo volumio[3276]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::ClearQueue
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::clearPlayQueue
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::addQueueItems
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::addQueueItems
May 10 19:21:24 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:24 rivo volumio[3276]: info: Adding Item to queue: qobuz://album/0060255716840
May 10 19:21:24 rivo volumio[3276]: info: Using cached record of: qobuz://album/0060255716840
May 10 19:21:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:21:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::play index 0
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:24 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:21:24 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:24 rivo volumio[3276]: info: [1746897684833] ControllerQobuz::clearAddPlayTrack
May 10 19:21:24 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:21:26 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:26 rivo volumio[3276]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::ClearQueue
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::clearPlayQueue
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:26 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::addQueueItems
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::addQueueItems
May 10 19:21:26 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:26 rivo volumio[3276]: info: Adding Item to queue: qobuz://album/0060255716840
May 10 19:21:26 rivo volumio[3276]: info: Using cached record of: qobuz://album/0060255716840
May 10 19:21:26 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:21:26 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::play index 0
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:26 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:21:26 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:26 rivo volumio[3276]: info: [1746897686993] ControllerQobuz::clearAddPlayTrack
May 10 19:21:26 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:21:30 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:30 rivo volumio[3276]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::ClearQueue
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::clearPlayQueue
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:30 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::addQueueItems
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::addQueueItems
May 10 19:21:30 rivo volumio[3276]: info: Preload queue cleared
May 10 19:21:30 rivo volumio[3276]: info: Adding Item to queue: qobuz://album/0060255716840
May 10 19:21:30 rivo volumio[3276]: info: Using cached record of: qobuz://album/0060255716840
May 10 19:21:30 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:21:30 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::play index 0
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:30 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:21:30 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:30 rivo volumio[3276]: info: [1746897690574] ControllerQobuz::clearAddPlayTrack
May 10 19:21:30 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:21:35 rivo volumio-remote-updater[2821]: No test mode
May 10 19:21:35 rivo volumio-remote-updater[2821]: No alpha test mode
May 10 19:21:35 rivo volumio[3276]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"
NEW ADDITIONS
- QOBUZ Connect Ready
","title":"Update v3.807","updateavailable":true}
May 10 19:21:35 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 10 19:21:36 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:21:36 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 10 19:21:38 rivo volumio[3276]: info: Discovery: Getting this device information
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:21:38 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 10 19:21:38 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:21:38 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:21:38 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:21:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:21:40 rivo volumio[3276]: info: CoreCommandRouter::Close All Modals sent
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:21:45 rivo volumio[3276]: No protocol specified
May 10 19:21:45 rivo volumio[3276]: xcb_connection_has_error() returned true
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 10 19:21:45 rivo volumio[3276]: No protocol specified
May 10 19:21:45 rivo volumio[3276]: xcb_connection_has_error() returned true
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 10 19:21:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 10 19:21:53 rivo systemd[1]: mpd.service: State 'stop-sigterm' timed out. Killing.
May 10 19:21:53 rivo systemd[1]: mpd.service: Killing process 3914 (mpd) with signal SIGKILL.
May 10 19:21:53 rivo volumio[3276]: error: Upnp client error: Error: read ECONNRESET
May 10 19:21:53 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL
May 10 19:21:53 rivo systemd[1]: mpd.service: Failed with result 'timeout'.
May 10 19:21:53 rivo systemd[1]: Stopped Music Player Daemon.
May 10 19:21:53 rivo volumio[3276]: error: MPD error: Error: read ECONNRESET
May 10 19:21:53 rivo systemd[1]: Starting Music Player Daemon...
May 10 19:21:53 rivo volumio[3276]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
May 10 19:21:53 rivo volumio[3276]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 10 19:21:53 rivo sudo[7044]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 10 19:21:53 rivo sudo[7044]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 10 19:21:53 rivo sudo[7044]: pam_unix(sudo:session): session closed for user root
May 10 19:21:55 rivo mpd[7056]: May 10 19:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 10 19:21:55 rivo systemd[1]: Started Music Player Daemon.
May 10 19:21:55 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:55 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:55 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:55 rivo mpd_monitor.sh[2818]: MPD Monitor Service: MPD restarted due to no mpc output.
May 10 19:21:55 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:56 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:56 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:57 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:57 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:21:58 rivo volumio[3276]: error: updateQueue error: null
May 10 19:21:58 rivo volumio-remote-updater[2821]: No test mode
May 10 19:21:58 rivo volumio-remote-updater[2821]: No alpha test mode
May 10 19:21:58 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:21:58 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:21:59 rivo volumio[3276]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"
NEW ADDITIONS
- QOBUZ Connect Ready
","title":"Update v3.807","updateavailable":true}
May 10 19:21:59 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 10 19:21:59 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:00 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:00 rivo volumio[3276]: info: CoreCommandRouter::Close All Modals sent
May 10 19:22:01 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:01 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:01 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:01 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:01 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:01 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:01 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:02 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:03 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:03 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:03 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:03 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:03 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:03 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:06 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:06 rivo volumio[3276]: info: Preload queue cleared
May 10 19:22:06 rivo volumio[3276]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::ClearQueue
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::clearPlayQueue
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:22:06 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::addQueueItems
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::addQueueItems
May 10 19:22:06 rivo volumio[3276]: info: Preload queue cleared
May 10 19:22:06 rivo volumio[3276]: info: Adding Item to queue: qobuz://album/0060255716840
May 10 19:22:06 rivo volumio[3276]: info: Using cached record of: qobuz://album/0060255716840
May 10 19:22:06 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:22:06 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::play index 0
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:06 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:22:06 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:06 rivo volumio[3276]: info: [1746897726530] ControllerQobuz::clearAddPlayTrack
May 10 19:22:06 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:22:06 rivo volumio[3276]: info: sendMpdCommand stop took 9 milliseconds
May 10 19:22:06 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand clear
May 10 19:22:06 rivo volumio[3276]: info:
May 10 19:22:06 rivo volumio[3276]: ---------------------------- MPD announces system playlist update
May 10 19:22:06 rivo volumio[3276]: info: Ignoring MPD Status Update
May 10 19:22:06 rivo volumio[3276]: info: sendMpdCommand clear took 2 milliseconds
May 10 19:22:06 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/34735894"
May 10 19:22:06 rivo volumio[3276]: error: updateQueue error: null
May 10 19:22:06 rivo volumio[3276]: info: ------------------------------ 4ms
May 10 19:22:06 rivo volumio[3276]: STREAMING PROXY: Handling url /?data=qobuz://song/34735894
May 10 19:22:06 rivo volumio[3276]: info: Executing endpoint getStreamUrlqobuz
May 10 19:22:06 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
May 10 19:22:07 rivo volumio[3276]: info: getStreamUrl took 534 milliseconds
May 10 19:22:07 rivo volumio[3276]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3077903&eid=34735894&fmt=6&profile=raw&app_id=539451548&cid=2740985&etsp=1746901326&hmac=MKsiuHf3G93cKO5VGqsQPFovRf0
May 10 19:22:07 rivo volumio[3276]: STREAMING PROXY: Response: 200, length: 43090715
May 10 19:22:07 rivo volumio[3276]: STREAMING PROXY: Client dropped request, destroying
May 10 19:22:07 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/34735894"
May 10 19:22:07 rivo volumio[3276]: info:
May 10 19:22:07 rivo volumio[3276]: ---------------------------- MPD announces system playlist update
May 10 19:22:07 rivo volumio[3276]: info: Ignoring MPD Status Update
May 10 19:22:07 rivo volumio[3276]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/34735894" took 5 milliseconds
May 10 19:22:07 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 19:22:07 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand play
May 10 19:22:07 rivo volumio[3276]: info: ------------------------------ 14ms
May 10 19:22:07 rivo volumio[3276]: info: sendMpdCommand play took 12 milliseconds
May 10 19:22:07 rivo volumio[3276]: STREAMING PROXY: Handling url /?data=qobuz://song/34735894
May 10 19:22:07 rivo volumio[3276]: info: Executing endpoint getStreamUrlqobuz
May 10 19:22:07 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI
May 10 19:22:07 rivo volumio[3276]: info: getStreamUrl took 426 milliseconds
May 10 19:22:07 rivo volumio[3276]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3077903&eid=34735894&fmt=6&profile=raw&app_id=539451548&cid=2740985&etsp=1746901327&hmac=ihUR8Wwmjk3MWTHKYhurTDGeyis
May 10 19:22:07 rivo volumio[3276]: STREAMING PROXY: Response: 200, length: 43090715
May 10 19:22:07 rivo volumio[3276]: info:
May 10 19:22:07 rivo volumio[3276]: ---------------------------- MPD announces state update: player
May 10 19:22:07 rivo volumio[3276]: info: ControllerMpd::getState
May 10 19:22:07 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand status
May 10 19:22:08 rivo volumio[3276]: info:
May 10 19:22:08 rivo volumio[3276]: ---------------------------- MPD announces state update: player
May 10 19:22:08 rivo volumio[3276]: info: sendMpdCommand status took 54 milliseconds
May 10 19:22:08 rivo volumio[3276]: info: ControllerMpd::getState
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand status
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::parseState
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 19:22:08 rivo volumio[3276]: info: sendMpdCommand status took 6 milliseconds
May 10 19:22:08 rivo volumio[3276]: info: sendMpdCommand playlistinfo took 7 milliseconds
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::parseState
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::parseTrackInfo
May 10 19:22:08 rivo volumio[3276]: info: ControllerMpd::pushState
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::servicePushState
May 10 19:22:08 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:08 rivo volumio[3276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":370,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"34735894","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/34735894","trackType":"qobuz"}
May 10 19:22:08 rivo volumio[3276]: verbose: CURRENT POSITION 0
May 10 19:22:08 rivo volumio[3276]: info: CoreStateMachine::syncState stateService play
May 10 19:22:08 rivo volumio[3276]: info: CoreStateMachine::syncState currentStatus stop
May 10 19:22:08 rivo volumio[3276]: info: ------------------------------ 80ms
May 10 19:22:08 rivo volumio[3276]: info: sendMpdCommand playlistinfo took 36 milliseconds
May 10 19:22:08 rivo volumio[3276]: verbose: ControllerMpd::parseTrackInfo
May 10 19:22:08 rivo volumio[3276]: info: ControllerMpd::pushState
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::servicePushState
May 10 19:22:08 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:08 rivo volumio[3276]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1121,"duration":370,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"489 Kbps","isStreaming":false,"title":"34735894","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/34735894","trackType":"qobuz"}
May 10 19:22:08 rivo volumio[3276]: verbose: CURRENT POSITION 0
May 10 19:22:08 rivo volumio[3276]: info: CoreStateMachine::syncState stateService play
May 10 19:22:08 rivo volumio[3276]: info: CoreStateMachine::syncState currentStatus play
May 10 19:22:08 rivo volumio[3276]: info: Received an update from plugin. extracting info from payload
May 10 19:22:08 rivo volumio[3276]: info: CoreStateMachine::pushState
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::volumioPushState
May 10 19:22:08 rivo volumio[3276]: info: MRS: Pushing multiroomSync output update for this device
May 10 19:22:08 rivo volumio[3276]: info: MRS: Pushing multiroomSync output
May 10 19:22:08 rivo volumio[3276]: info: CoreStateMachine::pushState
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::volumioPushState
May 10 19:22:08 rivo volumio[3276]: info: MRS: Pushing multiroomSync output update for this device
May 10 19:22:08 rivo volumio[3276]: info: MRS: Pushing multiroomSync output
May 10 19:22:08 rivo volumio[3276]: info: ------------------------------ 133ms
May 10 19:22:08 rivo volumio[3276]: info: Updating RAAT Signal Path
May 10 19:22:08 rivo volumio[3276]: info: Updating RAAT Signal Path
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 10 19:22:08 rivo volumio[3276]: info: Discovery: Getting this device information
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:22:08 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 10 19:22:10 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:10 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:10 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:10 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:10 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:10 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:15 rivo mpd_monitor.sh[2818]: MPD error: Connection closed by the server
May 10 19:22:15 rivo mpd_monitor.sh[2818]: MPD Monitor Service: MPD Appears to be inactive, restarting
May 10 19:22:15 rivo volumio[3276]: STREAMING PROXY: Client dropped request, destroying
May 10 19:22:15 rivo systemd[1]: Stopping Music Player Daemon...
May 10 19:22:15 rivo volumio[3276]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 10 19:22:15 rivo systemd[1]: mpd.service: Succeeded.
May 10 19:22:15 rivo systemd[1]: Stopped Music Player Daemon.
May 10 19:22:15 rivo volumio[3276]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
May 10 19:22:15 rivo systemd[1]: Starting Music Player Daemon...
May 10 19:22:15 rivo volumio[3276]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 10 19:22:15 rivo sudo[7163]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 10 19:22:15 rivo sudo[7163]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 10 19:22:15 rivo sudo[7163]: pam_unix(sudo:session): session closed for user root
May 10 19:22:16 rivo volumio[3276]: error: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:17 rivo mpd[7173]: May 10 19:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 10 19:22:17 rivo systemd[1]: Started Music Player Daemon.
May 10 19:22:17 rivo mpd_monitor.sh[2818]: MPD Monitor Service: MPD restarted due to no mpc output.
May 10 19:22:19 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:21 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:23 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: info: Preload queue cleared
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::ClearQueue
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::stPlaybackTimer
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::pushState
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPushState
May 10 19:22:24 rivo volumio[3276]: info: MRS: Pushing multiroomSync output update for this device
May 10 19:22:24 rivo volumio[3276]: info: MRS: Pushing multiroomSync output
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::serviceStop
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::serviceStop
May 10 19:22:24 rivo volumio[3276]: info: [1746897744313] ControllerQobuz::stop
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 19:22:24 rivo volumio[3276]: info: ControllerMpd::stop
May 10 19:22:24 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::clearPlayQueue
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::addQueueItems
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::addQueueItems
May 10 19:22:24 rivo volumio[3276]: info: Preload queue cleared
May 10 19:22:24 rivo volumio[3276]: info: Adding Item to queue: qobuz://album/0060255716840
May 10 19:22:24 rivo volumio[3276]: info: Using cached record of: qobuz://album/0060255716840
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPushQueue
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::saveQueue
May 10 19:22:24 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:24 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:24 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::updateTrackBlock
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::getTrackBlock
May 10 19:22:24 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::play index 0
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::stop
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:24 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:22:24 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:24 rivo volumio[3276]: info: [1746897744357] ControllerQobuz::clearAddPlayTrack
May 10 19:22:24 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:22:24 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:24 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:24 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:24 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:24 rivo volumio[3276]: info: Updating RAAT Signal Path
May 10 19:22:26 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:22:26 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:22:26 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:26 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:26 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:22:26 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:26 rivo volumio[3276]: info: [1746897746239] ControllerQobuz::clearAddPlayTrack
May 10 19:22:26 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:22:26 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:26 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:26 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:26 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:26 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:26 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:27 rivo volumio[3276]: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:35 rivo volumio[3276]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
May 10 19:22:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 10 19:22:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 10 19:22:38 rivo volumio[3276]: info: Discovery: Getting this device information
May 10 19:22:38 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:22:38 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:38 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 10 19:22:38 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:22:38 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:22:38 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:38 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:38 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:22:38 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:38 rivo volumio[3276]: info: [1746897758772] ControllerQobuz::clearAddPlayTrack
May 10 19:22:38 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:22:38 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:38 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:38 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:38 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:38 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:38 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:39 rivo volumio[3276]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
May 10 19:22:41 rivo volumio[3276]: info: CoreCommandRouter::volumioPlay
May 10 19:22:41 rivo volumio[3276]: info: CoreStateMachine::play index undefined
May 10 19:22:41 rivo volumio[3276]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 19:22:41 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:41 rivo volumio[3276]: info: CoreStateMachine::startPlaybackTimer
May 10 19:22:41 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:41 rivo volumio[3276]: info: [1746897761699] ControllerQobuz::clearAddPlayTrack
May 10 19:22:41 rivo volumio[3276]: verbose: ControllerMpd::sendMpdCommand stop
May 10 19:22:41 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:41 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:41 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:41 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:41 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:41 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:42 rivo volumio[3276]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
May 10 19:22:44 rivo volumio[3276]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
May 10 19:22:44 rivo volumio[3276]: info: CoreCommandRouter::volumioGetState
May 10 19:22:44 rivo volumio[3276]: info: CorePlayQueue::getTrack 0
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:22:45 rivo volumio[3276]: info: Retrieving Cloud Streaming UI
May 10 19:22:45 rivo volumio[3276]: info: Getting Tidal Cloud Configuration
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:22:45 rivo volumio[3276]: info: Getting Qobuz Cloud Configuration
May 10 19:22:45 rivo volumio[3276]: info: Asking plugin for UI Config
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:22:45 rivo volumio[3276]: info: Getting Spotify Cloud Configuration
May 10 19:22:45 rivo volumio[3276]: info: Asking plugin for UI Config
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:22:45 rivo volumio[3276]: info: Saving Spotify Acccount
May 10 19:22:45 rivo volumio[3276]: info: Got it
May 10 19:22:45 rivo volumio[3276]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
May 10 19:22:45 rivo volumio[3276]: info: Got Tidal Cloud Configuration
May 10 19:22:45 rivo volumio[3276]: info: Got it
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::getUIConfigOnPlugin
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::volumioGetBrowseSources
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::volumioGetBrowseSources
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::volumioGetBrowseSources
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
May 10 19:22:45 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:45 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:45 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:45 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:45 rivo volumio[3276]: error: MPD error: Error: This socket has been ended by the other party
May 10 19:22:45 rivo volumio[3276]: error: This socket has been ended by the other party {"code":"EPIPE"}
May 10 19:22:45 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
May 10 19:22:46 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:46 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:46 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:46 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:46 rivo volumio[3276]: info: Executing endpoint metavolumio
May 10 19:22:46 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio
May 10 19:22:48 rivo volumio[3276]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
May 10 19:22:48 rivo volumio[3276]: info: Disabling MyMusic plugin upnp
May 10 19:22:48 rivo sudo[7335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
May 10 19:22:48 rivo sudo[7335]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 10 19:22:48 rivo systemd[1]: Stopping UPnP Renderer front-end to MPD...
May 10 19:22:48 rivo volumio[3276]: error: Upnp client error: Error: This socket has been ended by the other party
May 10 19:22:48 rivo volumio[3276]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 10 19:22:49 rivo volumio[3276]: info: Enabling MyMusic plugin upnp
May 10 19:22:49 rivo volumio[3276]: info: Enabling plugin upnp
May 10 19:22:49 rivo volumio[3276]: info: Loading plugin "upnp"...
May 10 19:22:49 rivo volumio[3276]: info: [1746897769498] Starting Upmpd Daemon
May 10 19:22:49 rivo volumio[3276]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 10 19:22:49 rivo volumio[3276]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 10 19:22:49 rivo volumio[3276]: Error: listen EADDRINUSE: address already in use :::6599
May 10 19:22:49 rivo volumio[3276]: at Server.setupListenHandle [as _listen2] (net.js:1331:16)
May 10 19:22:49 rivo volumio[3276]: at listenInCluster (net.js:1379:12)
May 10 19:22:49 rivo volumio[3276]: at Server.listen (net.js:1465:7)
May 10 19:22:49 rivo volumio[3276]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
May 10 19:22:49 rivo volumio[3276]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
May 10 19:22:49 rivo volumio[3276]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
May 10 19:22:49 rivo volumio[3276]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
May 10 19:22:49 rivo volumio[3276]: at processTicksAndRejections (internal/process/task_queues.js:77:11) {
May 10 19:22:49 rivo volumio[3276]: code: 'EADDRINUSE',
May 10 19:22:49 rivo volumio[3276]: errno: -98,
May 10 19:22:49 rivo volumio[3276]: syscall: 'listen',
May 10 19:22:49 rivo volumio[3276]: address: '::',
May 10 19:22:49 rivo volumio[3276]: port: 6599
May 10 19:22:49 rivo volumio[3276]: }
May 10 19:22:49 rivo volumio[3276]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 10 19:22:50 rivo sudo[7348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-10 19:21
May 10 19:22:50 rivo sudo[7348]: 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="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="rivo"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:49:04 PM CET"
VOLUMIO_VERSION="3.795"
VOLUMIO_HARDWARE="mp1"
VOLUMIO_DEVICENAME="Volumio MP1"
VOLUMIO_VENDOR_MODEL="Volumio Rivo"
VOLUMIO_VENDOR="Volumio"
VOLUMIO_MODEL="Rivo"
VOLUMIO_HASH="65eb03fcbe62db7e7fbed9cd39ac44df"