-- Logs begin at Thu 2025-07-10 22:08:25 CST, end at Thu 2025-07-10 22:28:00 CST. -- Jul 10 22:27:02 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 22:27:06 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 22:27:10 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 22:27:14 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand update Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:18 volumio volumio[3334]: info: Jul 10 22:27:18 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand update took 8 milliseconds Jul 10 22:27:18 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:18 volumio volumio[3334]: info: Jul 10 22:27:18 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:18 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand status took 10 milliseconds Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand status took 8 milliseconds Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:18 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand status took 93 milliseconds Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand status took 91 milliseconds Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:18 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:18 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:18 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:18 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:18 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:18 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:18 volumio volumio[3334]: info: ------------------------------ 189ms Jul 10 22:27:18 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:18 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 35 milliseconds Jul 10 22:27:18 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:18 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:18 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:18 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:18 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:18 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:18 volumio volumio[3334]: info: ------------------------------ 212ms Jul 10 22:27:18 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand update Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand update took 2 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 7 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 6 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatetrue Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 64 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 62 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 129ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 146ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand update Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand update took 3 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 9 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 73 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 66 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 65 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 135ms Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 128ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand update Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand update took 3 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 3 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 62 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 61 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 60 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 42 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 125ms Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 124ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand update Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand update took 3 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 4 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 4 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 3 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 102 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 117ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 136ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand update Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand update took 3 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: Jul 10 22:27:19 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 4 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 63 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 63 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand status took 62 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 46 milliseconds Jul 10 22:27:19 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:19 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:19 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:19 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 133ms Jul 10 22:27:19 volumio volumio[3334]: info: ------------------------------ 131ms Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:19 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand rescan Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:20 volumio volumio[3334]: info: Jul 10 22:27:20 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand rescan took 8 milliseconds Jul 10 22:27:20 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:20 volumio volumio[3334]: info: Jul 10 22:27:20 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:20 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand status took 8 milliseconds Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand status took 6 milliseconds Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:20 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand status took 76 milliseconds Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand status took 73 milliseconds Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:20 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:20 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:20 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:20 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:20 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:20 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:20 volumio volumio[3334]: info: ------------------------------ 131ms Jul 10 22:27:20 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:20 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 10 22:27:20 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:20 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:20 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:20 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:20 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:20 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:20 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:20 volumio volumio[3334]: info: ------------------------------ 148ms Jul 10 22:27:20 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand rescan Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: info: Jul 10 22:27:21 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand rescan took 3 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: info: Jul 10 22:27:21 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 4 milliseconds Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 62 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 61 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 60 milliseconds Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 44 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:21 volumio volumio[3334]: info: ------------------------------ 128ms Jul 10 22:27:21 volumio volumio[3334]: info: ------------------------------ 125ms Jul 10 22:27:21 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:21 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand rescan Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: info: Jul 10 22:27:21 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand rescan took 4 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: info: Jul 10 22:27:21 volumio volumio[3334]: ---------------------------- MPD announces state update: update Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::getState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand status Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 4 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 3 milliseconds Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 69 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand status took 69 milliseconds Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 6 milliseconds Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: info: Command Router : Notfying DB Updatefalse Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::Close All Modals sent Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseState Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:21 volumio volumio[3334]: info: ------------------------------ 123ms Jul 10 22:27:21 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:21 volumio volumio[3334]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 10 22:27:21 volumio volumio[3334]: verbose: ControllerMpd::parseTrackInfo Jul 10 22:27:21 volumio volumio[3334]: info: ControllerMpd::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::servicePushState Jul 10 22:27:21 volumio volumio[3334]: info: CoreStateMachine::pushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: CoreCommandRouter::volumioPushState Jul 10 22:27:21 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:21 volumio volumio[3334]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 22:27:21 volumio volumio[3334]: info: ------------------------------ 141ms Jul 10 22:27:21 volumio volumio[3334]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 10 22:27:22 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 22:27:23 volumio volumio[3334]: info: CoreCommandRouter::volumioGetState Jul 10 22:27:23 volumio volumio[3334]: info: CorePlayQueue::getTrack 0 Jul 10 22:27:27 volumio go-librespot[4017]: time="2025-07-10T22:27:27+08:00" level=trace msg="received accesspoint ping" Jul 10 22:27:27 volumio go-librespot[4017]: time="2025-07-10T22:27:27+08:00" level=trace msg="received accesspoint pong ack" Jul 10 22:27:28 volumio go-librespot[4017]: time="2025-07-10T22:27:28+08:00" level=trace msg="sent dealer ping" Jul 10 22:27:28 volumio go-librespot[4017]: time="2025-07-10T22:27:28+08:00" level=trace msg="received dealer pong" Jul 10 22:27:36 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:36 volumio volumio[3334]: info: CURURI: playlists Jul 10 22:27:36 volumio volumio[3334]: info: Listing playlists Jul 10 22:27:36 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:37 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:37 volumio volumio[3334]: info: CURURI: music-library Jul 10 22:27:38 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:39 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:39 volumio volumio[3334]: info: CURURI: music-library/NAS Jul 10 22:27:39 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:40 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:40 volumio volumio[3334]: info: CURURI: music-library/NAS/群晖bgm Jul 10 22:27:40 volumio volumio[3334]: error: Failed LSINFO: null Jul 10 22:27:40 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:43 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:43 volumio volumio[3334]: info: CURURI: music-library/INTERNAL Jul 10 22:27:43 volumio volumio[3334]: error: Failed LSINFO: null Jul 10 22:27:43 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:45 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:45 volumio volumio[3334]: info: CURURI: music-library/USB Jul 10 22:27:45 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:46 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:46 volumio volumio[3334]: info: CURURI: music-library/USB/Ventoy Jul 10 22:27:46 volumio volumio[3334]: error: Failed LSINFO: null Jul 10 22:27:46 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:49 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:49 volumio volumio[3334]: info: CURURI: music-library/USB/VTOYEFI Jul 10 22:27:49 volumio volumio[3334]: error: Failed LSINFO: null Jul 10 22:27:49 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:51 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 10 22:27:51 volumio volumio[3334]: info: CURURI: music-library/INTERNAL Jul 10 22:27:51 volumio volumio[3334]: error: Failed LSINFO: null Jul 10 22:27:51 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:53 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 22:27:53 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:55 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 22:27:55 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:55 volumio CrashCore[3363]: time="2025-07-10T22:27:55.453366787+08:00" level=info msg="[TCP] 10.10.10.31:38772 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:55 volumio CrashCore[3363]: time="2025-07-10T22:27:55.453927934+08:00" level=info msg="[TCP] 10.10.10.31:38762 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:55 volumio CrashCore[3363]: time="2025-07-10T22:27:55.453935722+08:00" level=info msg="[TCP] 10.10.10.31:38744 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:55 volumio CrashCore[3363]: time="2025-07-10T22:27:55.455629428+08:00" level=info msg="[TCP] 10.10.10.31:38758 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:57 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 22:27:57 volumio volumio[3334]: info: Preload queue cleared Jul 10 22:27:57 volumio CrashCore[3363]: time="2025-07-10T22:27:57.610449861+08:00" level=info msg="[TCP] 10.10.10.31:38784 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:57 volumio CrashCore[3363]: time="2025-07-10T22:27:57.63044346+08:00" level=info msg="[TCP] 10.10.10.31:38812 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:57 volumio CrashCore[3363]: time="2025-07-10T22:27:57.63787962+08:00" level=info msg="[TCP] 10.10.10.31:38818 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:57 volumio CrashCore[3363]: time="2025-07-10T22:27:57.638141525+08:00" level=info msg="[TCP] 10.10.10.31:38798 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:57 volumio CrashCore[3363]: time="2025-07-10T22:27:57.646361486+08:00" level=info msg="[TCP] 10.10.10.31:38832 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:57 volumio CrashCore[3363]: time="2025-07-10T22:27:57.659322183+08:00" level=info msg="[TCP] 10.10.10.31:38842 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:58 volumio CrashCore[3363]: time="2025-07-10T22:27:58.012327172+08:00" level=info msg="[TCP] 10.10.10.31:38852 --> ws.audioscrobbler.com:80 match Match using 🐟 漏网之鱼[美国-bytevirt4837]" Jul 10 22:27:58 volumio go-librespot[4017]: time="2025-07-10T22:27:58+08:00" level=trace msg="sent dealer ping" Jul 10 22:27:58 volumio go-librespot[4017]: time="2025-07-10T22:27:58+08:00" level=trace msg="received dealer pong" Jul 10 22:28:00 volumio volumio[3334]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 10 22:28:00 volumio volumio[3334]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 22:28:00 volumio volumio[3334]: TypeError: Cannot read property 'length' of undefined Jul 10 22:28:00 volumio volumio[3334]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 10 22:28:00 volumio volumio[3334]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 10 22:28:00 volumio volumio[3334]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 10 22:28:00 volumio volumio[3334]: at Parser.emit (events.js:400:28) Jul 10 22:28:00 volumio volumio[3334]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 10 22:28:00 volumio volumio[3334]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 10 22:28:00 volumio volumio[3334]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 10 22:28:00 volumio volumio[3334]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 10 22:28:00 volumio volumio[3334]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 10 22:28:00 volumio volumio[3334]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 10 22:28:00 volumio volumio[3334]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 10 22:28:00 volumio volumio[3334]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 10 22:28:00 volumio volumio[3334]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 10 22:28:00 volumio volumio[3334]: at IncomingMessage.emit (events.js:412:35) Jul 10 22:28:00 volumio volumio[3334]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 10 22:28:00 volumio volumio[3334]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 10 22:28:00 volumio volumio[3334]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 22:28:00 volumio sudo[9185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 22:27 Jul 10 22:28:00 volumio sudo[9185]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"