-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2025-10-24 12:37:34 CEST. -- Oct 24 12:36:25 testvolumio go-librespot[2919]: time="2025-10-24T12:36:25+02:00" level=trace msg="sent dealer ping" Oct 24 12:36:25 testvolumio go-librespot[2919]: time="2025-10-24T12:36:25+02:00" level=trace msg="received dealer pong" Oct 24 12:36:55 testvolumio go-librespot[2919]: time="2025-10-24T12:36:55+02:00" level=trace msg="sent dealer ping" Oct 24 12:36:55 testvolumio go-librespot[2919]: time="2025-10-24T12:36:55+02:00" level=trace msg="received dealer pong" Oct 24 12:37:18 testvolumio volumio[690]: info: CoreCommandRouter::volumioGetState Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 12:37:23 testvolumio volumio[690]: info: Retrieving Cloud Streaming UI Oct 24 12:37:23 testvolumio volumio[690]: info: Getting Tidal Cloud Configuration Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 12:37:23 testvolumio volumio[690]: info: Getting Qobuz Cloud Configuration Oct 24 12:37:23 testvolumio volumio[690]: info: Asking plugin for UI Config Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 12:37:23 testvolumio volumio[690]: info: Getting Spotify Cloud Configuration Oct 24 12:37:23 testvolumio volumio[690]: info: Asking plugin for UI Config Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 12:37:23 testvolumio volumio[690]: info: Saving Spotify Acccount Oct 24 12:37:23 testvolumio volumio[690]: info: Got Tidal Cloud Configuration Oct 24 12:37:23 testvolumio volumio[690]: info: Got it Oct 24 12:37:23 testvolumio volumio[690]: info: Got it Oct 24 12:37:23 testvolumio volumio[690]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 12:37:23 testvolumio volumio[690]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 12:37:23 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 24 12:37:24 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand update Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: info: Oct 24 12:37:24 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand update took 13 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: info: Oct 24 12:37:24 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:24 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: info: Oct 24 12:37:24 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:24 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: info: Oct 24 12:37:24 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:24 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand status took 22 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand status took 18 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand status took 17 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand status took 15 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand status took 14 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: info: sendMpdCommand status took 12 milliseconds Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:24 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:24 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:24 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:24 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:24 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:24 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:24 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:24 testvolumio volumio[690]: info: Oct 24 12:37:24 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:25 testvolumio volumio[690]: info: Oct 24 12:37:25 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand status took 242 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand status took 239 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 144 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 101 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 58 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand status took 15 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand status took 12 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand status took 11 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand status took 10 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:25 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:25 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:25 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:25 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:25 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:25 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:25 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:25 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:25 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:25 testvolumio volumio[690]: info: ------------------------------ 523ms Oct 24 12:37:25 testvolumio volumio[690]: info: ------------------------------ 519ms Oct 24 12:37:25 testvolumio volumio[690]: info: ------------------------------ 515ms Oct 24 12:37:25 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:25 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:25 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 236 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 190 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 147 milliseconds Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:25 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:25 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:25 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:25 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:25 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:25 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:25 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:25 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:25 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:25 testvolumio volumio[690]: info: ------------------------------ 606ms Oct 24 12:37:25 testvolumio volumio[690]: info: ------------------------------ 376ms Oct 24 12:37:25 testvolumio volumio[690]: info: ------------------------------ 372ms Oct 24 12:37:25 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:25 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:25 testvolumio go-librespot[2919]: time="2025-10-24T12:37:25+02:00" level=trace msg="received accesspoint ping" Oct 24 12:37:25 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:25 testvolumio go-librespot[2919]: time="2025-10-24T12:37:25+02:00" level=trace msg="received accesspoint pong ack" Oct 24 12:37:25 testvolumio go-librespot[2919]: time="2025-10-24T12:37:25+02:00" level=trace msg="sent dealer ping" Oct 24 12:37:25 testvolumio go-librespot[2919]: time="2025-10-24T12:37:25+02:00" level=trace msg="received dealer pong" Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand rescan Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: Oct 24 12:37:26 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand rescan took 7 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: Oct 24 12:37:26 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: Oct 24 12:37:26 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: Oct 24 12:37:26 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 17 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 15 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 14 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 12 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 11 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 9 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: Oct 24 12:37:26 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: Oct 24 12:37:26 testvolumio volumio[690]: ---------------------------- MPD announces state update: update Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::getState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand status Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 236 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 234 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 170 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 127 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 70 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 27 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 24 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 23 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand status took 22 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: info: Command Router : Notfying DB Updatefalse Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::Close All Modals sent Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseState Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:26 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:26 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:26 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:26 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:26 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:26 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:26 testvolumio volumio[690]: info: ------------------------------ 516ms Oct 24 12:37:26 testvolumio volumio[690]: info: ------------------------------ 513ms Oct 24 12:37:26 testvolumio volumio[690]: info: ------------------------------ 511ms Oct 24 12:37:26 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:26 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:26 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 238 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 194 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: info: sendMpdCommand playlistinfo took 151 milliseconds Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:26 testvolumio volumio[690]: verbose: ControllerMpd::parseTrackInfo Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:26 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:26 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:26 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:26 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:26 testvolumio volumio[690]: info: ControllerMpd::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::servicePushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreStateMachine::pushState Oct 24 12:37:26 testvolumio volumio[690]: info: CoreCommandRouter::volumioPushState Oct 24 12:37:26 testvolumio volumio[690]: info: CorePlayQueue::getTrack 0 Oct 24 12:37:26 testvolumio volumio[690]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 24 12:37:26 testvolumio volumio[690]: info: ------------------------------ 606ms Oct 24 12:37:26 testvolumio volumio[690]: info: ------------------------------ 393ms Oct 24 12:37:26 testvolumio volumio[690]: info: ------------------------------ 390ms Oct 24 12:37:26 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:26 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:26 testvolumio volumio[690]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 24 12:37:27 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 12:37:28 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 24 12:37:28 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 24 12:37:29 testvolumio volumio[690]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L Fenger-Server.local. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L EPSON-ET7750.local. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L fritz.box. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L fritz.box. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L EPSON-MULTI.local. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: info: Error browsing device FritzFenger for shares: Command failed: smbclient --no-pass --debuglevel=0 -L fritz.box. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: Unable to initialize messaging context Oct 24 12:37:30 testvolumio volumio[690]: info: Error browsing device fritz-box for shares: Command failed: smbclient --no-pass --debuglevel=0 -L fritz.box. -m SMB3_11 Oct 24 12:37:30 testvolumio volumio[690]: Unable to initialize messaging context Oct 24 12:37:31 testvolumio volumio[690]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 12:37:33 testvolumio volumio[690]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 12:37:33 testvolumio volumio[690]: Error: dns service error: unknown Oct 24 12:37:33 testvolumio volumio[690]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Oct 24 12:37:33 testvolumio volumio[690]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 24 12:37:33 testvolumio volumio[690]: errorCode: -65537 Oct 24 12:37:33 testvolumio volumio[690]: } Oct 24 12:37:33 testvolumio volumio[690]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 12:37:34 testvolumio sudo[7379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 12:36 Oct 24 12:37:34 testvolumio sudo[7379]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"