Jan 27 11:39:00 volumio volumio[1252]: info: BOOT COMPLETED Jan 27 11:39:38 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.0.41:3000 from 192.168.0.230 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 27 11:39:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 11:39:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 11:39:38 volumio volumio[1252]: info: Discovery: Getting this device information Jan 27 11:39:38 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:39:38 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 11:39:39 volumio volumio[1252]: verbose: New Socket.io Connection to 192.168.0.41 from 192.168.0.230 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Jan 27 11:39:39 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:39:40 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 27 11:39:40 volumio volumio[1252]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 27 11:39:40 volumio volumio[1252]: info: Listing playlists Jan 27 11:39:40 volumio volumio[1252]: info: CoreCommandRouter::volumioGetVisibleSources Jan 27 11:39:40 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 11:39:40 volumio volumio[1252]: info: CoreCommandRouter::volumioGetQueue Jan 27 11:39:40 volumio volumio[1252]: info: CoreStateMachine::getQueue Jan 27 11:39:40 volumio volumio[1252]: info: CorePlayQueue::getQueue Jan 27 11:39:47 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 27 11:39:47 volumio volumio[1252]: info: handleBrowseUri took 152 milliseconds Jan 27 11:39:47 volumio volumio[1252]: info: Preload queue cleared Jan 27 11:39:47 volumio volumio[1252]: info: Preload queue cleared Jan 27 11:39:53 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 27 11:39:54 volumio volumio[1252]: info: handleBrowseUri took 1032 milliseconds Jan 27 11:39:54 volumio volumio[1252]: info: Preload queue cleared Jan 27 11:39:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 27 11:39:56 volumio volumio[1252]: info: handleBrowseUri took 145 milliseconds Jan 27 11:39:56 volumio volumio[1252]: info: Preload queue cleared Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179738 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179739 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179740 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179741 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179742 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179743 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179744 Jan 27 11:39:56 volumio volumio[1252]: info: Preloading song: qobuz://song/313179745 Jan 27 11:39:56 volumio volumio[1252]: info: Executing endpoint metavolumio Jan 27 11:39:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 27 11:39:56 volumio volumio[1252]: info: Executing endpoint metavolumio Jan 27 11:39:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 27 11:39:56 volumio volumio[1252]: info: Exploding uri qobuz://song/313179738 in service qobuz Jan 27 11:39:56 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:56 volumio volumio[1252]: info: Executing endpoint metavolumio Jan 27 11:39:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 27 11:39:56 volumio volumio[1252]: info: Exploding uri qobuz://song/313179739 in service qobuz Jan 27 11:39:56 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:56 volumio volumio[1252]: info: Exploding uri qobuz://song/313179740 in service qobuz Jan 27 11:39:56 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:56 volumio volumio[1252]: info: Executing endpoint metavolumio Jan 27 11:39:56 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 27 11:39:56 volumio volumio[1252]: info: Exploding uri qobuz://song/313179741 in service qobuz Jan 27 11:39:56 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:56 volumio volumio[1252]: info: Exploding uri qobuz://song/313179742 in service qobuz Jan 27 11:39:56 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:56 volumio volumio[1252]: info: Exploding uri qobuz://song/313179743 in service qobuz Jan 27 11:39:56 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:57 volumio volumio[1252]: info: explodeUri took 453 milliseconds Jan 27 11:39:57 volumio volumio[1252]: info: Exploding uri qobuz://song/313179744 in service qobuz Jan 27 11:39:57 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:57 volumio volumio[1252]: info: Exploding uri qobuz://song/313179745 in service qobuz Jan 27 11:39:57 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:57 volumio volumio[1252]: info: explodeUri took 660 milliseconds Jan 27 11:39:57 volumio volumio[1252]: info: explodeUri took 849 milliseconds Jan 27 11:39:57 volumio volumio[1252]: info: explodeUri took 1032 milliseconds Jan 27 11:39:57 volumio volumio[1252]: info: Preload queue cleared Jan 27 11:39:57 volumio volumio[1252]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 11:39:57 volumio volumio[1252]: info: CoreStateMachine::ClearQueue Jan 27 11:39:57 volumio volumio[1252]: info: CoreStateMachine::stop Jan 27 11:39:57 volumio volumio[1252]: info: CoreStateMachine::serviceStop Jan 27 11:39:57 volumio volumio[1252]: info: Received STOP, but no service to execute it Jan 27 11:39:57 volumio volumio[1252]: info: CorePlayQueue::clearPlayQueue Jan 27 11:39:57 volumio volumio[1252]: info: CorePlayQueue::saveQueue Jan 27 11:39:57 volumio volumio[1252]: info: CoreCommandRouter::volumioPushQueue Jan 27 11:39:57 volumio volumio[1252]: info: CoreStateMachine::addQueueItems Jan 27 11:39:57 volumio volumio[1252]: info: CorePlayQueue::addQueueItems Jan 27 11:39:57 volumio volumio[1252]: info: Preload queue cleared Jan 27 11:39:57 volumio volumio[1252]: info: Adding Item to queue: qobuz://album/sx8pzjwm1ge4b Jan 27 11:39:57 volumio volumio[1252]: info: Exploding uri qobuz://album/sx8pzjwm1ge4b in service qobuz Jan 27 11:39:57 volumio volumio[1252]: https://prod.vlmapi.io/v2/qobuz/explodeUri Jan 27 11:39:58 volumio volumio[1252]: info: explodeUri took 1140 milliseconds Jan 27 11:39:58 volumio volumio[1252]: info: explodeUri took 914 milliseconds Jan 27 11:39:58 volumio volumio[1252]: info: explodeUri took 1192 milliseconds Jan 27 11:39:58 volumio volumio[1252]: info: explodeUri took 282 milliseconds Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::volumioPushQueue Jan 27 11:39:58 volumio volumio[1252]: info: CorePlayQueue::saveQueue Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::updateTrackBlock Jan 27 11:39:58 volumio volumio[1252]: info: CorePlayQueue::getTrackBlock Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::volumioPlay Jan 27 11:39:58 volumio volumio[1252]: verbose: UNSET VOLATILE: Service: undefined Jan 27 11:39:58 volumio volumio[1252]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Jan 27 11:39:58 volumio volumio[1252]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Jan 27 11:39:58 volumio volumio[1252]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::play index 0 Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 11:39:58 volumio volumio[1252]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::stop Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::resetVolumioState Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::getcurrentVolume Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::volumioRetrievevolume Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::play index undefined Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::pushState Jan 27 11:39:58 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Jan 27 11:39:58 volumio volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Jan 27 11:39:58 volumio volumio[1252]: info: MRS: Pushing multiroomSync output Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:39:58 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::volumioStop Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::stop Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 11:39:58 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:39:58 volumio volumio[1252]: info: CoreStateMachine::startPlaybackTimer Jan 27 11:39:58 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:39:58 volumio volumio[1252]: info: [1769510398408] ControllerQobuz::clearAddPlayTrack Jan 27 11:39:58 volumio volumio[1252]: info: explodeUri took 1466 milliseconds Jan 27 11:39:58 volumio volumio[1252]: info: getStreamUrl took 480 milliseconds Jan 27 11:39:58 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 11:39:58 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:39:58 volumio volumio[1252]: info: [now-playing] Fetch metadata (DefaultMetadataProvider): {"type":"song","name":"CREEK","artist":"Hiroshi Yoshimura","album":"Green (SFX Version)","duration":"362","uri":"qobuz://song/313179738","service":""} Jan 27 11:39:59 volumio volumio[1252]: info: sendMpdCommand stop took 168 milliseconds Jan 27 11:39:59 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 11:39:59 volumio volumio[1252]: info: Jan 27 11:39:59 volumio volumio[1252]: ---------------------------- MPD announces system playlist update Jan 27 11:39:59 volumio volumio[1252]: info: Ignoring MPD Status Update Jan 27 11:39:59 volumio volumio[1252]: info: sendMpdCommand clear took 32 milliseconds Jan 27 11:39:59 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc" Jan 27 11:39:59 volumio volumio[1252]: error: updateQueue error: null Jan 27 11:39:59 volumio volumio[1252]: info: ------------------------------ 822ms Jan 27 11:39:59 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc" Jan 27 11:39:59 volumio volumio[1252]: info: Jan 27 11:39:59 volumio volumio[1252]: ---------------------------- MPD announces system playlist update Jan 27 11:39:59 volumio volumio[1252]: info: Ignoring MPD Status Update Jan 27 11:39:59 volumio volumio[1252]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc" took 2 milliseconds Jan 27 11:39:59 volumio volumio[1252]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 11:39:59 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand play Jan 27 11:39:59 volumio volumio[1252]: info: ------------------------------ 15ms Jan 27 11:39:59 volumio volumio[1252]: info: sendMpdCommand play took 14 milliseconds Jan 27 11:40:00 volumio volumio[1252]: info: Jan 27 11:40:00 volumio volumio[1252]: ---------------------------- MPD announces state update: player Jan 27 11:40:00 volumio volumio[1252]: info: ControllerMpd::getState Jan 27 11:40:00 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Jan 27 11:40:00 volumio volumio[1252]: info: Jan 27 11:40:00 volumio volumio[1252]: ---------------------------- MPD announces state update: player Jan 27 11:40:00 volumio volumio[1252]: info: ControllerMpd::getState Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Jan 27 11:40:00 volumio volumio[1252]: info: sendMpdCommand status took 37 milliseconds Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::parseState Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 11:40:00 volumio volumio[1252]: info: sendMpdCommand status took 5 milliseconds Jan 27 11:40:00 volumio volumio[1252]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::parseState Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::parseTrackInfo Jan 27 11:40:00 volumio volumio[1252]: info: ControllerMpd::pushState Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::servicePushState Jan 27 11:40:00 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:40:00 volumio volumio[1252]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":362,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc","trackType":"qobuz"} Jan 27 11:40:00 volumio volumio[1252]: verbose: CURRENT POSITION 0 Jan 27 11:40:00 volumio volumio[1252]: info: CoreStateMachine::syncState stateService play Jan 27 11:40:00 volumio volumio[1252]: info: CoreStateMachine::syncState currentStatus stop Jan 27 11:40:00 volumio volumio[1252]: info: ------------------------------ 251ms Jan 27 11:40:00 volumio volumio[1252]: info: sendMpdCommand playlistinfo took 187 milliseconds Jan 27 11:40:00 volumio volumio[1252]: verbose: ControllerMpd::parseTrackInfo Jan 27 11:40:00 volumio volumio[1252]: info: ControllerMpd::pushState Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::servicePushState Jan 27 11:40:00 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:40:00 volumio volumio[1252]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1110,"duration":362,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1339 Kbps","isStreaming":false,"title":"file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc","trackType":"qobuz"} Jan 27 11:40:00 volumio volumio[1252]: verbose: CURRENT POSITION 0 Jan 27 11:40:00 volumio volumio[1252]: info: CoreStateMachine::syncState stateService play Jan 27 11:40:00 volumio volumio[1252]: info: CoreStateMachine::syncState currentStatus play Jan 27 11:40:00 volumio volumio[1252]: info: Received an update from plugin. extracting info from payload Jan 27 11:40:00 volumio volumio[1252]: info: CoreStateMachine::pushState Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Jan 27 11:40:00 volumio volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Jan 27 11:40:00 volumio volumio[1252]: info: MRS: Pushing multiroomSync output Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:40:00 volumio volumio[1252]: info: CoreStateMachine::pushState Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Jan 27 11:40:00 volumio volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Jan 27 11:40:00 volumio volumio[1252]: info: MRS: Pushing multiroomSync output Jan 27 11:40:00 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:40:00 volumio volumio[1252]: info: ------------------------------ 330ms Jan 27 11:40:11 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 11:40:11 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 11:40:11 volumio volumio[1252]: info: Retrieving Cloud Streaming UI Jan 27 11:40:11 volumio volumio[1252]: info: Getting Tidal Cloud Configuration Jan 27 11:40:11 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 11:40:11 volumio volumio[1252]: info: Getting Qobuz Cloud Configuration Jan 27 11:40:11 volumio volumio[1252]: info: Asking plugin for UI Config Jan 27 11:40:11 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 11:40:12 volumio volumio[1252]: info: Getting Spotify Cloud Configuration Jan 27 11:40:12 volumio volumio[1252]: info: Asking plugin for UI Config Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 11:40:12 volumio volumio[1252]: info: Saving Spotify Acccount Jan 27 11:40:12 volumio volumio[1252]: info: Got it Jan 27 11:40:12 volumio volumio[1252]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 27 11:40:12 volumio volumio[1252]: info: Got Tidal Cloud Configuration Jan 27 11:40:12 volumio volumio[1252]: info: Got it Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 11:40:12 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand rescan Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Jan 27 11:40:14 volumio volumio[1252]: info: Jan 27 11:40:14 volumio volumio[1252]: ---------------------------- MPD announces state update: update Jan 27 11:40:14 volumio volumio[1252]: info: sendMpdCommand rescan took 46 milliseconds Jan 27 11:40:14 volumio volumio[1252]: info: ControllerMpd::getState Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand status Jan 27 11:40:14 volumio volumio[1252]: info: sendMpdCommand status took 14 milliseconds Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::parseState Jan 27 11:40:14 volumio volumio[1252]: info: Command Router : Notfying DB Updatetrue Jan 27 11:40:14 volumio volumio[1252]: info: sendMpdCommand status took 291 milliseconds Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::parseState Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 11:40:14 volumio volumio[1252]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 27 11:40:14 volumio volumio[1252]: verbose: ControllerMpd::parseTrackInfo Jan 27 11:40:14 volumio volumio[1252]: info: ControllerMpd::pushState Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::servicePushState Jan 27 11:40:14 volumio volumio[1252]: info: CorePlayQueue::getTrack 0 Jan 27 11:40:14 volumio volumio[1252]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15356,"duration":362,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1540 Kbps","isStreaming":false,"title":"file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8142365&eid=313179738&fmt=7&profile=raw&app_id=539451548&cid=3675389&etsp=1769513998&hmac=JnaG3a-k4S943tymqZx410-saSc","trackType":"qobuz"} Jan 27 11:40:14 volumio volumio[1252]: verbose: CURRENT POSITION 0 Jan 27 11:40:14 volumio volumio[1252]: info: CoreStateMachine::syncState stateService play Jan 27 11:40:14 volumio volumio[1252]: info: CoreStateMachine::syncState currentStatus play Jan 27 11:40:14 volumio volumio[1252]: info: Received an update from plugin. extracting info from payload Jan 27 11:40:14 volumio volumio[1252]: info: CoreStateMachine::pushState Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Jan 27 11:40:14 volumio volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Jan 27 11:40:14 volumio volumio[1252]: info: MRS: Pushing multiroomSync output Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:40:14 volumio volumio[1252]: info: CoreStateMachine::pushState Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::volumioPushState Jan 27 11:40:14 volumio volumio[1252]: info: MRS: Pushing multiroomSync output update for this device Jan 27 11:40:14 volumio volumio[1252]: info: MRS: Pushing multiroomSync output Jan 27 11:40:14 volumio volumio[1252]: info: CoreCommandRouter::volumioGetState Jan 27 11:40:14 volumio volumio[1252]: info: ------------------------------ 379ms Jan 27 11:40:16 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 11:40:17 volumio volumio[1252]: info: CoreCommandRouter::Close All Modals sent Jan 27 11:40:19 volumio volumio[1252]: info: Disabling MyMusic plugin upnp Jan 27 11:40:20 volumio sudo[2950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 27 11:40:20 volumio sudo[2950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 27 11:40:20 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Jan 27 11:40:20 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 11:40:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 11:40:24 volumio volumio[1252]: info: Enabling MyMusic plugin upnp Jan 27 11:40:24 volumio volumio[1252]: info: Enabling plugin upnp Jan 27 11:40:24 volumio volumio[1252]: info: Loading plugin "upnp"... Jan 27 11:40:24 volumio volumio[1252]: info: [1769510424693] Starting Upmpd Daemon Jan 27 11:40:24 volumio volumio[1252]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 27 11:40:24 volumio volumio[1252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:40:24 volumio volumio[1252]: Error: listen EADDRINUSE: address already in use :::6599 Jan 27 11:40:24 volumio volumio[1252]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Jan 27 11:40:24 volumio volumio[1252]: at listenInCluster (node:net:1920:12) Jan 27 11:40:24 volumio volumio[1252]: at Server.listen (node:net:2008:7) Jan 27 11:40:24 volumio volumio[1252]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 27 11:40:24 volumio volumio[1252]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 27 11:40:24 volumio volumio[1252]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 27 11:40:24 volumio volumio[1252]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 27 11:40:24 volumio volumio[1252]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Jan 27 11:40:24 volumio volumio[1252]: code: 'EADDRINUSE', Jan 27 11:40:24 volumio volumio[1252]: errno: -98, Jan 27 11:40:24 volumio volumio[1252]: syscall: 'listen', Jan 27 11:40:24 volumio volumio[1252]: address: '::', Jan 27 11:40:24 volumio volumio[1252]: port: 6599 Jan 27 11:40:24 volumio volumio[1252]: } Jan 27 11:40:24 volumio volumio[1252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 11:40:29 volumio sudo[2982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 11:39' Jan 27 11:40:29 volumio sudo[2982]: 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"