May 28 08:03:16 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:18 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 28 08:03:18 volumio volumio[1301]: info: UPNP Browser: No servers found, reinitializing and searching... May 28 08:03:21 volumio volumio[1301]: info: UPNP Browser: Returning 0 server(s) after 3s wait May 28 08:03:23 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:26 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:26 volumio volumio[1301]: info: TuneIn handleBrowseUri: tunein/local May 28 08:03:43 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:45 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:48 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:51 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::ClearQueue May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::stop May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::serviceStop May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrack 2 May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::serviceStop May 28 08:03:53 volumio volumio[1301]: info: ControllerMpd::stop May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::clearPlayQueue May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::saveQueue May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::volumioPushQueue May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::addQueueItems May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::addQueueItems May 28 08:03:53 volumio volumio[1301]: info: Preload queue cleared May 28 08:03:53 volumio volumio[1301]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996 May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::volumioPushQueue May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::saveQueue May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::volumioPlay May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::play index 0 May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::stop May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::play index undefined May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:53 volumio volumio[1301]: info: [1779955433573] ControllerWebradio::clearAddPlayTrack May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop May 28 08:03:53 volumio volumio[1301]: info: May 28 08:03:53 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:53 volumio volumio[1301]: info: sendMpdCommand stop took 9 milliseconds May 28 08:03:53 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:53 volumio volumio[1301]: info: sendMpdCommand stop took 2 milliseconds May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand clear May 28 08:03:53 volumio volumio[1301]: info: May 28 08:03:53 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:53 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:53 volumio volumio[1301]: info: May 28 08:03:53 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:53 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:53 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:53 volumio volumio[1301]: info: sendMpdCommand clear took 0 milliseconds May 28 08:03:53 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" May 28 08:03:53 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:53 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:53 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:03:53 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:53 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:53 volumio volumio[1301]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 28 08:03:53 volumio volumio[1301]: info: ------------------------------ 7ms May 28 08:03:53 volumio volumio[1301]: info: May 28 08:03:53 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:53 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:54 volumio volumio[1301]: error: updateQueue error: null May 28 08:03:54 volumio volumio[1301]: info: May 28 08:03:54 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:54 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:54 volumio volumio[1301]: info: ------------------------------ 584ms May 28 08:03:54 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 583 milliseconds May 28 08:03:54 volumio volumio[1301]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 583 milliseconds May 28 08:03:54 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:54 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 08:03:54 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand play May 28 08:03:54 volumio volumio[1301]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 08:03:54 volumio volumio[1301]: info: ------------------------------ 586ms May 28 08:03:54 volumio volumio[1301]: info: ------------------------------ 578ms May 28 08:03:54 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:03:54 volumio volumio[1301]: info: sendMpdCommand play took 0 milliseconds May 28 08:03:54 volumio volumio[1301]: info: May 28 08:03:54 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:54 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:54 volumio volumio[1301]: info: ------------------------------ 0ms May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 7 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 6 milliseconds May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5048/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus stop May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5048/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 11ms May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 12ms May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 7 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 7 milliseconds May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5048/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 15ms May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 11 milliseconds May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 5048/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 23ms May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:57 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:57 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:57 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:03:57 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:03:57 volumio volumio[1301]: info: May 28 08:03:57 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 2ms May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Nachrichten","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Nachrichten","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Nachrichten","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:03:57 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:03:57 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Nachrichten","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:03:57 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:03:57 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:03:57 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 23ms May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 23ms May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 22ms May 28 08:03:57 volumio volumio[1301]: info: ------------------------------ 22ms May 28 08:04:01 volumio volumio[1301]: info: May 28 08:04:01 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:04:01 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:04:01 volumio volumio[1301]: info: May 28 08:04:01 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:01 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:01 volumio volumio[1301]: info: May 28 08:04:01 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:04:01 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:04:01 volumio volumio[1301]: info: May 28 08:04:01 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:01 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:01 volumio volumio[1301]: info: ------------------------------ 0ms May 28 08:04:01 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:04:01 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:04:01 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:01 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 28 08:04:01 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:01 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:01 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:01 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:01 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5358,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Verkehrsservice","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:04:01 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:04:01 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:01 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:01 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:01 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5358,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Verkehrsservice","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} May 28 08:04:01 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:04:01 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:01 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:01 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:01 volumio volumio[1301]: info: ------------------------------ 11ms May 28 08:04:01 volumio volumio[1301]: info: ------------------------------ 11ms May 28 08:04:02 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:04:02 volumio volumio[1301]: info: TuneIn handleBrowseUri: tunein/popular May 28 08:04:09 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:04:09 volumio volumio[1301]: info: TuneIn handleBrowseUri: tunein/best May 28 08:04:13 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 08:04:13 volumio volumio[1301]: info: Getting BBC Radios May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::ClearQueue May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::stop May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::stPlaybackTimer May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::serviceStop May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::serviceStop May 28 08:04:19 volumio volumio[1301]: info: [1779955459688] ControllerWebradio::stop May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::clearPlayQueue May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::saveQueue May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::volumioPushQueue May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::addQueueItems May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::addQueueItems May 28 08:04:19 volumio volumio[1301]: info: Preload queue cleared May 28 08:04:19 volumio volumio[1301]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8 May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::volumioPushQueue May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::saveQueue May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::updateTrackBlock May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrackBlock May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::volumioPlay May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::play index 0 May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::stop May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::play index undefined May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::startPlaybackTimer May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:19 volumio volumio[1301]: info: [1779955459694] ControllerWebradio::clearAddPlayTrack May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand stop May 28 08:04:19 volumio volumio[1301]: info: May 28 08:04:19 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand stop took 86 milliseconds May 28 08:04:19 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand stop took 79 milliseconds May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand clear May 28 08:04:19 volumio volumio[1301]: info: May 28 08:04:19 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:19 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:19 volumio volumio[1301]: info: May 28 08:04:19 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:04:19 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand clear took 0 milliseconds May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8" May 28 08:04:19 volumio volumio[1301]: info: May 28 08:04:19 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:04:19 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:04:19 volumio volumio[1301]: error: updateQueue error: null May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:04:19 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:19 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:04:19 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:19 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:19 volumio volumio[1301]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 28 08:04:19 volumio volumio[1301]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 08:04:19 volumio volumio[1301]: info: ------------------------------ 5ms May 28 08:04:19 volumio volumio[1301]: info: ------------------------------ 6ms May 28 08:04:19 volumio volumio[1301]: info: May 28 08:04:19 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:04:19 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8" took 7 milliseconds May 28 08:04:19 volumio volumio[1301]: info: ------------------------------ 6ms May 28 08:04:19 volumio volumio[1301]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 08:04:19 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand play May 28 08:04:19 volumio volumio[1301]: info: May 28 08:04:19 volumio volumio[1301]: ---------------------------- MPD announces system playlist update May 28 08:04:19 volumio volumio[1301]: info: Ignoring MPD Status Update May 28 08:04:19 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:04:19 volumio volumio[1301]: info: sendMpdCommand play took 1 milliseconds May 28 08:04:19 volumio volumio[1301]: info: ------------------------------ 1ms May 28 08:04:23 volumio volumio[1301]: info: May 28 08:04:23 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:23 volumio volumio[1301]: info: May 28 08:04:23 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:23 volumio volumio[1301]: info: May 28 08:04:23 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand status took 44 milliseconds May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand status took 44 milliseconds May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:23 volumio volumio[1301]: info: May 28 08:04:23 volumio volumio[1301]: ---------------------------- MPD announces state update: player May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::getState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand status took 0 milliseconds May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 0 milliseconds May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseState May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 28 08:04:23 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus stop May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":482,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 28 08:04:23 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:04:23 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:23 volumio volumio[1301]: info: ------------------------------ 47ms May 28 08:04:23 volumio volumio[1301]: info: ------------------------------ 50ms May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 5 milliseconds May 28 08:04:23 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 6 milliseconds May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:23 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 28 08:04:23 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:04:23 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:23 volumio volumio[1301]: info: ControllerMpd::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::servicePushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_radio_one-audio%3d128000.norewind.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_01505109/live/ww/bbc_radio_one/bbc_radio_one.isml/bbc_radio_one-audio%3d128000.norewind.m3u8","trackType":"m3u8"} May 28 08:04:23 volumio volumio[1301]: verbose: CURRENT POSITION 0 May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play May 28 08:04:23 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:23 volumio volumio[1301]: info: CorePlayQueue::getTrack 0 May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:23 volumio volumio[1301]: info: CoreStateMachine::pushState May 28 08:04:23 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState May 28 08:04:23 volumio volumio[1301]: info: ------------------------------ 16ms May 28 08:04:23 volumio volumio[1301]: info: ------------------------------ 17ms May 28 08:04:28 volumio volumio[1301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 08:04:28 volumio volumio[1301]: Error: connect ETIMEDOUT 108.160.166.148:443 May 28 08:04:28 volumio volumio[1301]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { May 28 08:04:28 volumio volumio[1301]: errno: -110, May 28 08:04:28 volumio volumio[1301]: code: 'ETIMEDOUT', May 28 08:04:28 volumio volumio[1301]: syscall: 'connect', May 28 08:04:28 volumio volumio[1301]: address: '108.160.166.148', May 28 08:04:28 volumio volumio[1301]: port: 443 May 28 08:04:28 volumio volumio[1301]: } May 28 08:04:28 volumio volumio[1301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 08:04:29 volumio sudo[4309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 08:03' May 28 08:04:29 volumio sudo[4309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"