-- Logs begin at Wed 2025-05-21 14:20:00 UTC, end at Wed 2025-05-21 23:13:19 UTC. -- May 21 23:12:01 volumio volumio[7793]: info: MyVolumio login type: Token May 21 23:12:02 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:04 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:12:05 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:06 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:12:06 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:22 volumio volumio[7793]: info: Removing uri http://65.21.61.215:8000/citypopfour from favourites May 21 23:12:22 volumio volumio[7793]: info: Saving local item radio-favourites May 21 23:12:31 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:31 volumio volumio[7793]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 23:12:31 volumio volumio[7793]: info: Retrying Login Due to Network Error May 21 23:12:32 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:12:32 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:33 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 21 23:12:33 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:38 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioReplaceandPlayItems May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::ClearQueue May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::stop May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::stPlaybackTimer May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::updateTrackBlock May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrackBlock May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::serviceStop May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::serviceStop May 21 23:12:38 volumio volumio[7793]: info: [1747869158044] ControllerWebradio::stop May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::clearPlayQueue May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::saveQueue May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioPushQueue May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::addQueueItems May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::addQueueItems May 21 23:12:38 volumio volumio[7793]: info: Preload queue cleared May 21 23:12:38 volumio volumio[7793]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687 May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioPushQueue May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::saveQueue May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::updateTrackBlock May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrackBlock May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioPlay May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::play index 0 May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::stop May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::play index undefined May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::startPlaybackTimer May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: [1747869158063] ControllerWebradio::clearAddPlayTrack May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand stop May 21 23:12:38 volumio volumio[7793]: info: May 21 23:12:38 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand stop took 21 milliseconds May 21 23:12:38 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand stop took 4 milliseconds May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand clear May 21 23:12:38 volumio volumio[7793]: info: May 21 23:12:38 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:38 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:38 volumio volumio[7793]: info: May 21 23:12:38 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:38 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:38 volumio volumio[7793]: info: May 21 23:12:38 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:38 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand status took 4 milliseconds May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand clear took 4 milliseconds May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand status took 2 milliseconds May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:38 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 21 23:12:38 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:38 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:38 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:38 volumio volumio[7793]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 21 23:12:38 volumio volumio[7793]: info: ------------------------------ 24ms May 21 23:12:38 volumio volumio[7793]: info: ------------------------------ 24ms May 21 23:12:38 volumio volumio[7793]: info: May 21 23:12:38 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:38 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:38 volumio volumio[7793]: info: May 21 23:12:38 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:38 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:38 volumio volumio[7793]: error: updateQueue error: null May 21 23:12:38 volumio volumio[7793]: info: ------------------------------ 33ms May 21 23:12:38 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 31 milliseconds May 21 23:12:38 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:38 volumio volumio[7793]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 21 23:12:38 volumio volumio[7793]: info: ------------------------------ 38ms May 21 23:12:41 volumio volumio[7793]: info: MyVolumio login type: Token May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" took 8260 milliseconds May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand play May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:46 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:46 volumio volumio[7793]: info: ------------------------------ 8241ms May 21 23:12:46 volumio volumio[7793]: info: ------------------------------ 8236ms May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:46 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:46 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand play took 5 milliseconds May 21 23:12:46 volumio volumio[7793]: info: ------------------------------ 4ms May 21 23:12:46 volumio volumio[7793]: info: ------------------------------ 2ms May 21 23:12:46 volumio volumio[7793]: info: ------------------------------ 1ms May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand status took 4 milliseconds May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:46 volumio volumio[7793]: info: May 21 23:12:46 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand status took 8 milliseconds May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:46 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:46 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:46 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:46 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:46 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus stop May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:46 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:46 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:46 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:46 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:46 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:46 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:46 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:46 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:46 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:46 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:46 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:46 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:46 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:46 volumio volumio[7793]: info: ------------------------------ 36ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 63ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 62ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 51 milliseconds May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 49 milliseconds May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 50 milliseconds May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 121ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 115ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 115ms May 21 23:12:47 volumio volumio[7793]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%201%2F512)%20City%20Pop%20Four/f7599c3f-9f8d-439b-a953-344100441395.jpg' May 21 23:12:47 volumio volumio[7793]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%201%2F512)%20City%20Pop%20Four/f7599c3f-9f8d-439b-a953-344100441395.jpg' May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:47 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:47 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:47 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 6ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand status took 5 milliseconds May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 5ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand status took 4 milliseconds May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 10ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand status took 10 milliseconds May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"citypopfour","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"citypopfour","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 86ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 85ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 74 milliseconds May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"citypopfour","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 110ms May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:47 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:47 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces system playlist update May 21 23:12:47 volumio volumio[7793]: info: Ignoring MPD Status Update May 21 23:12:47 volumio volumio[7793]: info: May 21 23:12:47 volumio volumio[7793]: ---------------------------- MPD announces state update: player May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::getState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand status May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 4ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 3ms May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 3ms May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand status took 3 milliseconds May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseState May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 23:12:47 volumio volumio[7793]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:47 volumio volumio[7793]: verbose: ControllerMpd::parseTrackInfo May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ma Yu Fen · Fickleness Leads To Problems (1988)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ma Yu Fen · Fickleness Leads To Problems (1988)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ControllerMpd::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::servicePushState May 21 23:12:47 volumio volumio[7793]: info: CorePlayQueue::getTrack 0 May 21 23:12:47 volumio volumio[7793]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1005,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Ma Yu Fen · Fickleness Leads To Problems (1988)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} May 21 23:12:47 volumio volumio[7793]: verbose: CURRENT POSITION 0 May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState stateService play May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::syncState currentStatus play May 21 23:12:47 volumio volumio[7793]: info: Received an update from plugin. extracting info from payload May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:12:47 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 68ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 68ms May 21 23:12:47 volumio volumio[7793]: info: ------------------------------ 66ms May 21 23:12:54 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 21 23:12:54 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 21 23:12:58 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 21 23:12:58 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 21 23:13:06 volumio volumio[7793]: info: VolumeController::SetAlsaVolume23 May 21 23:13:06 volumio volumio[7793]: info: CoreStateMachine::pushState May 21 23:13:06 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 23:13:06 volumio volumio[7793]: info: CoreCommandRouter::volumioPushState May 21 23:13:11 volumio volumio[7793]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 21 23:13:11 volumio volumio[7793]: info: Retrying Login Due to Network Error May 21 23:13:12 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 21 23:13:12 volumio volumio[7793]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 21 23:13:19 volumio volumio[7793]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:13:19 volumio volumio[7793]: Error: connect ETIMEDOUT 199.96.62.21:80 May 21 23:13:19 volumio volumio[7793]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 21 23:13:19 volumio volumio[7793]: errno: -110, May 21 23:13:19 volumio volumio[7793]: code: 'ETIMEDOUT', May 21 23:13:19 volumio volumio[7793]: syscall: 'connect', May 21 23:13:19 volumio volumio[7793]: address: '199.96.62.21', May 21 23:13:19 volumio volumio[7793]: port: 80 May 21 23:13:19 volumio volumio[7793]: } May 21 23:13:19 volumio volumio[7793]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 23:13:19 volumio sudo[9036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 23:12 May 21 23:13:19 volumio sudo[9036]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"