-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Mon 2024-12-02 21:47:15 CST. -- Dec 02 21:46:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 02 21:46:16 volumio volumio[973]: info: Preload queue cleared Dec 02 21:46:19 volumio volumio[973]: info: Preload queue cleared Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::ClearQueue Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::stop Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::stPlaybackTimer Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::serviceStop Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::serviceStop Dec 02 21:46:19 volumio volumio[973]: info: [1733147179480] ControllerWebradio::stop Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::saveQueue Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::addQueueItems Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::addQueueItems Dec 02 21:46:19 volumio volumio[973]: info: Preload queue cleared Dec 02 21:46:19 volumio volumio[973]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687 Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::saveQueue Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::play index 0 Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::stop Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::play index undefined Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: [1733147179524] ControllerWebradio::clearAddPlayTrack Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 21:46:19 volumio volumio[973]: info: Dec 02 21:46:19 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand stop took 51 milliseconds Dec 02 21:46:19 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand stop took 8 milliseconds Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 21:46:19 volumio volumio[973]: info: Dec 02 21:46:19 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:19 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:19 volumio volumio[973]: info: Dec 02 21:46:19 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:19 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:19 volumio volumio[973]: info: Dec 02 21:46:19 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:19 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand status took 13 milliseconds Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand clear took 11 milliseconds Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand status took 10 milliseconds Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand status took 9 milliseconds Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:19 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 21:46:19 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:19 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:19 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 21:46:19 volumio volumio[973]: info: ------------------------------ 67ms Dec 02 21:46:19 volumio volumio[973]: info: ------------------------------ 67ms Dec 02 21:46:19 volumio volumio[973]: info: Dec 02 21:46:19 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:19 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:19 volumio volumio[973]: info: Dec 02 21:46:19 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:19 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:19 volumio volumio[973]: error: updateQueue error: null Dec 02 21:46:19 volumio volumio[973]: info: ------------------------------ 75ms Dec 02 21:46:19 volumio volumio[973]: info: sendMpdCommand playlistinfo took 70 milliseconds Dec 02 21:46:19 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:19 volumio volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 02 21:46:19 volumio volumio[973]: info: ------------------------------ 90ms Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" took 785 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand play Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:20 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:20 volumio volumio[973]: info: ------------------------------ 735ms Dec 02 21:46:20 volumio volumio[973]: info: ------------------------------ 734ms Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand play took 5 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:20 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:20 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:20 volumio volumio[973]: info: ------------------------------ 10ms Dec 02 21:46:20 volumio volumio[973]: info: ------------------------------ 7ms Dec 02 21:46:20 volumio volumio[973]: info: ------------------------------ 4ms Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand status took 8 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:20 volumio volumio[973]: info: Dec 02 21:46:20 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand status took 13 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Dec 02 21:46:20 volumio volumio[973]: info: sendMpdCommand status took 6 milliseconds Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:20 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:20 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:20 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:20 volumio volumio[973]: 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":""} Dec 02 21:46:20 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus stop Dec 02 21:46:20 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:20 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:20 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:20 volumio volumio[973]: 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":""} Dec 02 21:46:20 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:20 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:20 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:20 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:20 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:46:20 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:20 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: 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":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 65ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 126ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 125ms Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 105 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 103 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 102 milliseconds Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: 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":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: 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":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: 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":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 277ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 269ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 268ms Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:21 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:21 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:21 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:21 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 11ms Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 9ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 7ms Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:21 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:46:21 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:46:21 volumio volumio[973]: info: Dec 02 21:46:21 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 16ms Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand status took 13 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand status took 13 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 11ms Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand status took 15 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 14ms Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand status took 12 milliseconds Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 02 21:46:21 volumio volumio[973]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pauline Lan · Heavy Makeup (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pauline Lan · Heavy Makeup (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pauline Lan · Heavy Makeup (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:46:21 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:46:21 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Pauline Lan · Heavy Makeup (1984)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Dec 02 21:46:21 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:46:21 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:46:21 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 217ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 217ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 212ms Dec 02 21:46:21 volumio volumio[973]: info: ------------------------------ 209ms Dec 02 21:46:47 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 02 21:46:50 volumio volumio[973]: info: Preload queue cleared Dec 02 21:47:05 volumio volumio[973]: info: Preload queue cleared Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::ClearQueue Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::stop Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::stPlaybackTimer Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::serviceStop Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::serviceStop Dec 02 21:47:05 volumio volumio[973]: info: [1733147225282] ControllerWebradio::stop Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::saveQueue Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::addQueueItems Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::addQueueItems Dec 02 21:47:05 volumio volumio[973]: info: Preload queue cleared Dec 02 21:47:05 volumio volumio[973]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=473159 Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::saveQueue Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrackBlock Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioPlay Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::play index 0 Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::stop Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::play index undefined Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::startPlaybackTimer Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: [1733147225324] ControllerWebradio::clearAddPlayTrack Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand stop Dec 02 21:47:05 volumio volumio[973]: info: Dec 02 21:47:05 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand stop took 54 milliseconds Dec 02 21:47:05 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand stop took 12 milliseconds Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand clear Dec 02 21:47:05 volumio volumio[973]: info: Dec 02 21:47:05 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:05 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:05 volumio volumio[973]: info: Dec 02 21:47:05 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:05 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:05 volumio volumio[973]: info: Dec 02 21:47:05 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:05 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand status took 11 milliseconds Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand clear took 11 milliseconds Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand status took 9 milliseconds Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand status took 8 milliseconds Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=473159" Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:05 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 21:47:05 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:05 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:05 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:05 volumio volumio[973]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 02 21:47:05 volumio volumio[973]: info: ------------------------------ 64ms Dec 02 21:47:05 volumio volumio[973]: info: ------------------------------ 62ms Dec 02 21:47:05 volumio volumio[973]: info: Dec 02 21:47:05 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:05 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:05 volumio volumio[973]: info: Dec 02 21:47:05 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:05 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:05 volumio volumio[973]: error: updateQueue error: null Dec 02 21:47:05 volumio volumio[973]: info: ------------------------------ 66ms Dec 02 21:47:05 volumio volumio[973]: info: sendMpdCommand playlistinfo took 62 milliseconds Dec 02 21:47:05 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:05 volumio volumio[973]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 02 21:47:05 volumio volumio[973]: info: ------------------------------ 81ms Dec 02 21:47:06 volumio volumio[973]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=473159" took 1046 milliseconds Dec 02 21:47:06 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 21:47:06 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand play Dec 02 21:47:06 volumio volumio[973]: info: Dec 02 21:47:06 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:06 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:06 volumio volumio[973]: info: ------------------------------ 996ms Dec 02 21:47:06 volumio volumio[973]: info: ------------------------------ 996ms Dec 02 21:47:06 volumio volumio[973]: info: sendMpdCommand play took 4 milliseconds Dec 02 21:47:06 volumio volumio[973]: info: Dec 02 21:47:06 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:06 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:06 volumio volumio[973]: info: Dec 02 21:47:06 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:06 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:06 volumio volumio[973]: info: ------------------------------ 14ms Dec 02 21:47:06 volumio volumio[973]: info: ------------------------------ 7ms Dec 02 21:47:06 volumio volumio[973]: info: ------------------------------ 4ms Dec 02 21:47:10 volumio volumio[973]: info: Dec 02 21:47:10 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:10 volumio volumio[973]: info: Dec 02 21:47:10 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:10 volumio volumio[973]: info: Dec 02 21:47:10 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:10 volumio volumio[973]: info: Dec 02 21:47:10 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand status took 8 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand status took 7 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand status took 5 milliseconds Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:10 volumio volumio[973]: info: Dec 02 21:47:10 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:10 volumio volumio[973]: info: Dec 02 21:47:10 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand status took 29 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand playlistinfo took 26 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand status took 8 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand status took 6 milliseconds Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: 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":"ABC-Piano","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:10 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus stop Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: 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":"ABC-Piano","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:10 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:10 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: 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":"ABC-Piano","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:10 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:10 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: ------------------------------ 88ms Dec 02 21:47:10 volumio volumio[973]: info: ------------------------------ 143ms Dec 02 21:47:10 volumio volumio[973]: info: ------------------------------ 142ms Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand playlistinfo took 112 milliseconds Dec 02 21:47:10 volumio volumio[973]: info: sendMpdCommand playlistinfo took 111 milliseconds Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:10 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: 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":"ABC-Piano","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:10 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:10 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: 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":"ABC-Piano","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:10 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:10 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: 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":"ABC-Piano","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:10 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:10 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:10 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:10 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 309ms Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 304ms Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 287ms Dec 02 21:47:11 volumio volumio[973]: info: Dec 02 21:47:11 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:11 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:11 volumio volumio[973]: info: Dec 02 21:47:11 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:11 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:11 volumio volumio[973]: info: Dec 02 21:47:11 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:11 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:11 volumio volumio[973]: info: Dec 02 21:47:11 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:11 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:11 volumio volumio[973]: info: Dec 02 21:47:11 volumio volumio[973]: ---------------------------- MPD announces system playlist update Dec 02 21:47:11 volumio volumio[973]: info: Ignoring MPD Status Update Dec 02 21:47:11 volumio volumio[973]: info: Dec 02 21:47:11 volumio volumio[973]: ---------------------------- MPD announces state update: player Dec 02 21:47:11 volumio volumio[973]: info: ControllerMpd::getState Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand status Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 44ms Dec 02 21:47:11 volumio volumio[973]: info: sendMpdCommand status took 41 milliseconds Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 30ms Dec 02 21:47:11 volumio volumio[973]: info: sendMpdCommand status took 27 milliseconds Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 25ms Dec 02 21:47:11 volumio volumio[973]: info: sendMpdCommand status took 23 milliseconds Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::parseState Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 21:47:11 volumio volumio[973]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 02 21:47:11 volumio volumio[973]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 02 21:47:11 volumio volumio[973]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:11 volumio volumio[973]: verbose: ControllerMpd::parseTrackInfo Dec 02 21:47:11 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:11 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:11 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Håkon Austbø - Brahms: 8 Piano Pieces, Op. 76 - 5. Capriccio In C Sharp Minor","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:11 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:11 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:11 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:11 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Håkon Austbø - Brahms: 8 Piano Pieces, Op. 76 - 5. Capriccio In C Sharp Minor","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:11 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:11 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: ControllerMpd::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::servicePushState Dec 02 21:47:11 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Dec 02 21:47:11 volumio volumio[973]: verbose: STATE SERVICE {"status":"play","position":0,"seek":884,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Håkon Austbø - Brahms: 8 Piano Pieces, Op. 76 - 5. Capriccio In C Sharp Minor","artist":"ABC Piano","album":null,"uri":"http://listen.radionomy.com/ABC-Piano","trackType":""} Dec 02 21:47:11 volumio volumio[973]: verbose: CURRENT POSITION 0 Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::syncState stateService play Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::syncState currentStatus play Dec 02 21:47:11 volumio volumio[973]: info: Received an update from plugin. extracting info from payload Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: CoreStateMachine::pushState Dec 02 21:47:11 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 328ms Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 315ms Dec 02 21:47:11 volumio volumio[973]: info: ------------------------------ 311ms Dec 02 21:47:14 volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:47:14 volumio volumio[973]: Error: connect ETIMEDOUT 128.242.240.180:80 Dec 02 21:47:14 volumio volumio[973]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 02 21:47:14 volumio volumio[973]: errno: -110, Dec 02 21:47:14 volumio volumio[973]: code: 'ETIMEDOUT', Dec 02 21:47:14 volumio volumio[973]: syscall: 'connect', Dec 02 21:47:14 volumio volumio[973]: address: '128.242.240.180', Dec 02 21:47:14 volumio volumio[973]: port: 80 Dec 02 21:47:14 volumio volumio[973]: } Dec 02 21:47:14 volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 21:47:15 volumio sudo[5304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 21:46 Dec 02 21:47:15 volumio sudo[5304]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"