-- Logs begin at Sun 2024-05-05 23:08:34 CEST, end at Mon 2024-05-06 11:30:41 CEST. -- May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand status took 20514 milliseconds May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand status took 20520 milliseconds May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand status took 20519 milliseconds May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:01 volumio volumio[806]: info: Command Router : Notfying DB Updatefalse May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand playlistinfo took 12764 milliseconds May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand playlistinfo took 12763 milliseconds May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:01 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:01 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:01 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:01 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:01 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:01 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:01 volumio volumio[806]: info: ------------------------------ 20746ms May 06 11:29:01 volumio volumio[806]: info: ------------------------------ 20743ms May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:01 volumio volumio[806]: Upnp client error: Error: This socket has been ended by the other party May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand playlistinfo took 6812 milliseconds May 06 11:29:01 volumio volumio[806]: info: sendMpdCommand playlistinfo took 6812 milliseconds May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:01 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:01 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:01 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:01 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:01 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:01 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:01 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:01 volumio volumio[806]: info: ------------------------------ 21044ms May 06 11:29:01 volumio volumio[806]: info: ------------------------------ 21042ms May 06 11:29:01 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:02 volumio volumio[806]: info: sendMpdCommand playlistinfo took 632 milliseconds May 06 11:29:02 volumio volumio[806]: info: sendMpdCommand playlistinfo took 631 milliseconds May 06 11:29:02 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:02 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:02 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:02 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:02 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:02 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:02 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:02 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:02 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:02 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:02 volumio volumio[806]: info: ------------------------------ 21232ms May 06 11:29:02 volumio volumio[806]: info: ------------------------------ 21231ms May 06 11:29:05 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:09 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:13 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:17 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:21 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand update May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:24 volumio volumio[806]: info: May 06 11:29:24 volumio volumio[806]: ---------------------------- MPD announces state update: update May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand update took 9 milliseconds May 06 11:29:24 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:24 volumio volumio[806]: info: May 06 11:29:24 volumio volumio[806]: ---------------------------- MPD announces state update: update May 06 11:29:24 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:24 volumio volumio[806]: info: May 06 11:29:24 volumio volumio[806]: ---------------------------- MPD announces state update: update May 06 11:29:24 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand status took 13 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand status took 9 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand status took 8 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand status took 7 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand status took 5 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand status took 5 milliseconds May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:24 volumio volumio[806]: info: Command Router : Notfying DB Updatetrue May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:24 volumio volumio[806]: info: Command Router : Notfying DB Updatetrue May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:24 volumio volumio[806]: info: Command Router : Notfying DB Updatetrue May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand playlistinfo took 74 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand playlistinfo took 45 milliseconds May 06 11:29:24 volumio volumio[806]: info: sendMpdCommand playlistinfo took 18 milliseconds May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:24 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:29:24 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:24 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:24 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:24 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:24 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:24 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:24 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:29:24 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:29:24 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:29:24 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:29:24 volumio volumio[806]: info: ------------------------------ 152ms May 06 11:29:24 volumio volumio[806]: info: ------------------------------ 146ms May 06 11:29:24 volumio volumio[806]: info: ------------------------------ 143ms May 06 11:29:25 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:29 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:33 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:37 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:41 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:43 volumio volumio[806]: info: MPD Database updated - AlbumList cache refreshed May 06 11:29:43 volumio volumio[806]: info: May 06 11:29:43 volumio volumio[806]: ---------------------------- MPD announces state update: database May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: May 06 11:29:43 volumio volumio[806]: ---------------------------- MPD announces state update: update May 06 11:29:43 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: MPD Database updated - AlbumList cache refreshed May 06 11:29:43 volumio volumio[806]: info: May 06 11:29:43 volumio volumio[806]: ---------------------------- MPD announces state update: database May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: May 06 11:29:43 volumio volumio[806]: ---------------------------- MPD announces state update: update May 06 11:29:43 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: MPD Database updated - AlbumList cache refreshed May 06 11:29:43 volumio volumio[806]: info: May 06 11:29:43 volumio volumio[806]: ---------------------------- MPD announces state update: database May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: May 06 11:29:43 volumio volumio[806]: ---------------------------- MPD announces state update: update May 06 11:29:43 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:43 volumio volumio[806]: info: ControllerMpd::getState May 06 11:29:43 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand status May 06 11:29:47 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:51 volumio volumio[806]: info: sendMpdCommand status took 7843 milliseconds May 06 11:29:51 volumio volumio[806]: info: sendMpdCommand status took 7842 milliseconds May 06 11:29:51 volumio volumio[806]: info: sendMpdCommand status took 7842 milliseconds May 06 11:29:51 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:51 volumio volumio[806]: info: Command Router : Notfying DB Updatefalse May 06 11:29:51 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:29:51 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:51 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:51 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:51 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:51 volumio volumio[806]: Upnp client error: Error: This socket has been ended by the other party May 06 11:29:51 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:54 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:29:57 volumio volumio[806]: info: sendMpdCommand status took 14315 milliseconds May 06 11:29:57 volumio volumio[806]: info: sendMpdCommand status took 14317 milliseconds May 06 11:29:57 volumio volumio[806]: info: sendMpdCommand status took 14317 milliseconds May 06 11:29:57 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:57 volumio volumio[806]: info: Command Router : Notfying DB Updatefalse May 06 11:29:57 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:29:57 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:57 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:57 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:29:57 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:29:57 volumio volumio[806]: Upnp client error: Error: This socket has been ended by the other party May 06 11:29:59 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:30:03 volumio volumio[806]: info: sendMpdCommand status took 20259 milliseconds May 06 11:30:03 volumio volumio[806]: info: sendMpdCommand status took 20267 milliseconds May 06 11:30:03 volumio volumio[806]: info: sendMpdCommand status took 20267 milliseconds May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:30:03 volumio volumio[806]: info: Command Router : Notfying DB Updatefalse May 06 11:30:03 volumio volumio[806]: info: CoreCommandRouter::Close All Modals sent May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::parseState May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 06 11:30:03 volumio volumio[806]: info: sendMpdCommand playlistinfo took 12573 milliseconds May 06 11:30:03 volumio volumio[806]: info: sendMpdCommand playlistinfo took 12573 milliseconds May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:30:03 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:30:03 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:30:03 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:03 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:03 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:30:03 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:30:03 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:03 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:03 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:30:03 volumio volumio[806]: info: ------------------------------ 20502ms May 06 11:30:03 volumio volumio[806]: info: ------------------------------ 20502ms May 06 11:30:03 volumio volumio[806]: Upnp client error: Error: This socket has been ended by the other party May 06 11:30:03 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:30:04 volumio volumio[806]: info: sendMpdCommand playlistinfo took 6436 milliseconds May 06 11:30:04 volumio volumio[806]: info: sendMpdCommand playlistinfo took 6435 milliseconds May 06 11:30:04 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:30:04 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:30:04 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:30:04 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:04 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:04 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:30:04 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:30:04 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:04 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:04 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:30:04 volumio volumio[806]: info: ------------------------------ 20818ms May 06 11:30:04 volumio volumio[806]: info: ------------------------------ 20818ms May 06 11:30:04 volumio volumio[806]: info: sendMpdCommand playlistinfo took 643 milliseconds May 06 11:30:04 volumio volumio[806]: info: sendMpdCommand playlistinfo took 642 milliseconds May 06 11:30:04 volumio volumio[806]: verbose: ControllerMpd::parseTrackInfo May 06 11:30:04 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:30:04 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:04 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:04 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:30:04 volumio volumio[806]: info: ControllerMpd::pushState May 06 11:30:04 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:04 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:04 volumio volumio[806]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd May 06 11:30:04 volumio volumio[806]: info: ------------------------------ 20994ms May 06 11:30:04 volumio volumio[806]: info: ------------------------------ 20995ms May 06 11:30:05 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:30:09 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:30:13 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:30:17 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 06 11:30:19 volumio volumio[806]: info: CoreCommandRouter::volumioGetState May 06 11:30:19 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:22 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 06 11:30:22 volumio volumio[806]: info: CURURI: music-library May 06 11:30:22 volumio volumio[806]: info: Preload queue cleared May 06 11:30:23 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 06 11:30:23 volumio volumio[806]: info: CURURI: music-library/USB May 06 11:30:23 volumio volumio[806]: info: Preload queue cleared May 06 11:30:24 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 06 11:30:24 volumio volumio[806]: info: CURURI: music-library/USB/Music May 06 11:30:24 volumio volumio[806]: info: Preload queue cleared May 06 11:30:32 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 06 11:30:32 volumio volumio[806]: info: CURURI: music-library/USB/Music/2002 - Duets May 06 11:30:32 volumio volumio[806]: info: Preload queue cleared May 06 11:30:40 volumio volumio[806]: info: Preload queue cleared May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::volumioReplaceandPlayItems May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::ClearQueue May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::stop May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::setConsumeUpdateService undefined May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::stPlaybackTimer May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::updateTrackBlock May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrackBlock May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::pushState May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::volumioPushState May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::serviceStop May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::serviceStop May 06 11:30:40 volumio volumio[806]: info: ControllerMpd::stop May 06 11:30:40 volumio volumio[806]: verbose: ControllerMpd::sendMpdCommand stop May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::clearPlayQueue May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::saveQueue May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::volumioPushQueue May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::addQueueItems May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::addQueueItems May 06 11:30:40 volumio volumio[806]: info: Preload queue cleared May 06 11:30:40 volumio volumio[806]: info: Adding Item to queue: music-library/USB/Music/2002 - Duets May 06 11:30:40 volumio volumio[806]: info: Exploding uri music-library/USB/Music/2002 - Duets in service mpd May 06 11:30:40 volumio volumio[806]: info: sendMpdCommand stop took 38 milliseconds May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::servicePushState May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::pushState May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::volumioPushState May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:40 volumio volumio[806]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0032Y8XH8.jpg","uri":"https://stream.radioparadise.com/flac","name":"Timshel","title":"Timshel","artist":"Mumford & Sons","album":"Sigh No More","streaming":true,"disableUiControls":true,"duration":168,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} May 06 11:30:40 volumio volumio[806]: verbose: CURRENT POSITION 0 May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::syncState stateService stop May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::syncState currentStatus stop May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::pushState May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::volumioPushState May 06 11:30:40 volumio volumio[806]: info: No code May 06 11:30:40 volumio volumio[806]: info: CoreStateMachine::pushState May 06 11:30:40 volumio volumio[806]: info: CorePlayQueue::getTrack 0 May 06 11:30:40 volumio volumio[806]: info: CoreCommandRouter::volumioPushState May 06 11:30:40 volumio volumio[806]: info: May 06 11:30:40 volumio volumio[806]: ---------------------------- MPD announces state update: player May 06 11:30:40 volumio volumio[806]: info: ALBUMART /albumart?cacheid=741&web=Barbra%20Streisand/Duets/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F2002%20-%20Duets%2FBarbra%20Streisand%20-%20Duets.cue&metadata=false May 06 11:30:40 volumio volumio[806]: info: URI /mnt/USB/Music/2002 - Duets/Barbra Streisand - Duets.cue May 06 11:30:40 volumio volumio[806]: info: ALBUMART /albumart?cacheid=741&web=Barbra%20Streisand/Duets/extralarge&path=%2Fmnt%2FUSB%2FMusic%2F2002%20-%20Duets%2FBarbra%20Streisand%20-%20Duets.cue&metadata=false May 06 11:30:40 volumio volumio[806]: info: URI /mnt/USB/Music/2002 - Duets/Barbra Streisand - Duets.cue May 06 11:30:40 volumio volumio[806]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 11:30:40 volumio volumio[806]: Error: Unable to resolve or reject the same promise twice May 06 11:30:40 volumio volumio[806]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 06 11:30:40 volumio volumio[806]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 May 06 11:30:40 volumio volumio[806]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 06 11:30:40 volumio volumio[806]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 06 11:30:40 volumio volumio[806]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 06 11:30:40 volumio volumio[806]: at Socket.emit (events.js:315:20) May 06 11:30:40 volumio volumio[806]: at addChunk (internal/streams/readable.js:309:12) May 06 11:30:40 volumio volumio[806]: at readableAddChunk (internal/streams/readable.js:280:11) May 06 11:30:40 volumio volumio[806]: at Socket.Readable.push (internal/streams/readable.js:223:10) May 06 11:30:40 volumio volumio[806]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 06 11:30:40 volumio volumio[806]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 06 11:30:41 volumio sudo[2947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-06 11:29 May 06 11:30:41 volumio sudo[2947]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"