-- Logs begin at Thu 2019-02-14 03:11:58 MST, end at Mon 2025-08-04 11:15:48 MDT. -- Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: Preload queue cleared Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreStateMachine::ClearQueue Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreStateMachine::stop Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CorePlayQueue::clearPlayQueue Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CorePlayQueue::saveQueue Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushQueue Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreStateMachine::addQueueItems Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CorePlayQueue::addQueueItems Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: Preload queue cleared Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s57272 Aug 04 11:14:01 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: Preload queue cleared Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreStateMachine::ClearQueue Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreStateMachine::stop Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CorePlayQueue::clearPlayQueue Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CorePlayQueue::saveQueue Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushQueue Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreStateMachine::addQueueItems Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CorePlayQueue::addQueueItems Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: Preload queue cleared Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s57272 Aug 04 11:14:07 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: Preload queue cleared Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::ClearQueue Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::stop Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::clearPlayQueue Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::saveQueue Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushQueue Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::addQueueItems Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::addQueueItems Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: Preload queue cleared Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s57272 Aug 04 11:14:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 04 11:14:30 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioGetState Aug 04 11:14:30 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: Retrieving Cloud Streaming UI Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: Getting Tidal Cloud Configuration Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: Getting Qobuz Cloud Configuration Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: Asking plugin for UI Config Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 04 11:14:41 deqx-gen-4 volumio[5785]: info: Getting Spotify Cloud Configuration Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: Asking plugin for UI Config Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: Saving Spotify Acccount Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: Got it Aug 04 11:14:42 deqx-gen-4 volumio[5785]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: Got it Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: Got Tidal Cloud Configuration Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioGetBrowseSources Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioGetBrowseSources Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioGetBrowseSources Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:14:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 04 11:14:46 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:14:50 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:14:54 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:14:58 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:02 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:06 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:10 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:14 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand update took 5 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 6 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Command Router : Notfying DB Updatetrue Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 116 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: MPD Database updated - AlbumList cache refreshed Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: database Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces system playlist update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Ignoring MPD Status Update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: error: updateQueue error: null Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 56 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 56 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 55 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 53 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 25 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 36ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Command Router : Notfying DB Updatefalse Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::Close All Modals sent Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Command Router : Notfying DB Updatefalse Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::Close All Modals sent Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseTrackInfo Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 238ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 235ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 350ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 217ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: error: Upnp client error: Error: This socket has been ended by the other party Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: MPD Database updated - AlbumList cache refreshed Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: database Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces system playlist update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Ignoring MPD Status Update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: MPD Database updated - AlbumList cache refreshed Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: database Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces system playlist update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Ignoring MPD Status Update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Aug 04 11:15:15 deqx-gen-4 volumio[5785]: ---------------------------- MPD announces state update: update Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ControllerMpd::getState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::sendMpdCommand status Aug 04 11:15:15 deqx-gen-4 volumio[5785]: error: updateQueue error: null Aug 04 11:15:15 deqx-gen-4 volumio[5785]: error: updateQueue error: null Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 234 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 217 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 24 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 22 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 26ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 22 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 17 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 16 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: ------------------------------ 19ms Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: sendMpdCommand status took 15 milliseconds Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Command Router : Notfying DB Updatefalse Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::Close All Modals sent Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: Command Router : Notfying DB Updatefalse Aug 04 11:15:15 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::Close All Modals sent Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:15 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: Command Router : Notfying DB Updatefalse Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::Close All Modals sent Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: ControllerMpd::parseState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ControllerMpd::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::servicePushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 04 11:15:16 deqx-gen-4 volumio[5785]: verbose: CURRENT POSITION 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState stateService stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::syncState currentStatus stop Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: No code Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreStateMachine::pushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CorePlayQueue::getTrack 0 Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::volumioPushState Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ------------------------------ 655ms Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ------------------------------ 447ms Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ------------------------------ 448ms Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ------------------------------ 443ms Aug 04 11:15:16 deqx-gen-4 volumio[5785]: info: ------------------------------ 443ms Aug 04 11:15:18 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:22 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:26 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:29 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Aug 04 11:15:30 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:30 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 04 11:15:34 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:38 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Aug 04 11:15:42 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Aug 04 11:15:46 deqx-gen-4 volumio[5785]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 04 11:15:47 deqx-gen-4 volumio[5785]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 04 11:15:47 deqx-gen-4 volumio[5785]: Error: dns service error: unknown Aug 04 11:15:47 deqx-gen-4 volumio[5785]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 04 11:15:47 deqx-gen-4 volumio[5785]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 04 11:15:47 deqx-gen-4 volumio[5785]: errorCode: -65537 Aug 04 11:15:47 deqx-gen-4 volumio[5785]: } Aug 04 11:15:47 deqx-gen-4 volumio[5785]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 04 11:15:48 deqx-gen-4 sudo[1217]: volumio : unable to resolve host deqx-gen-4 Aug 04 11:15:48 deqx-gen-4 sudo[1217]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 04 11:15:48 deqx-gen-4 sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-04 11:14 Aug 04 11:15:48 deqx-gen-4 sudo[1217]: 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="900c37ee0cafd620e4a37a1118cc5316b54b45b3" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="ca3c0885e14bcba58c57ef609685ae76316b61d6" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="deqx" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 16 Apr 2024 02:17:33 PM CEST" VOLUMIO_VERSION="3.624" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_HASH="ca1050ae12f3dc501a57f6b4c284f5d2"