-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-07-08 22:16:51 CST. -- Jul 08 22:15:38 volumio volumio[957]: info: Preload queue cleared Jul 08 22:15:47 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:15:47 volumio volumio[957]: info: Preload queue cleared Jul 08 22:15:53 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:15:53 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:09 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:16:09 volumio volumio[957]: info: TuneIn handleBrowseUri: tunein/popular Jul 08 22:16:10 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:16:10 volumio volumio[957]: info: TuneIn handleBrowseUri: tunein/popular Jul 08 22:16:11 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:16:11 volumio volumio[957]: info: TuneIn handleBrowseUri: tunein/popular Jul 08 22:16:12 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:16:12 volumio volumio[957]: info: TuneIn handleBrowseUri: tunein/best Jul 08 22:16:15 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:16:15 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:22 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 08 22:16:23 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:30 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::ClearQueue Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::stPlaybackTimer Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 2 Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::serviceStop Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 2 Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::serviceStop Jul 08 22:16:30 volumio volumio[957]: info: ControllerMpd::stop Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::addQueueItems Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::addQueueItems Jul 08 22:16:30 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:30 volumio volumio[957]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321 Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::play index 0 Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:30 volumio volumio[957]: info: [1751984190583] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:30 volumio volumio[957]: info: Jul 08 22:16:30 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand stop took 28 milliseconds Jul 08 22:16:30 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand stop took 5 milliseconds Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:30 volumio volumio[957]: info: Jul 08 22:16:30 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:30 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:30 volumio volumio[957]: info: Jul 08 22:16:30 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:30 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:30 volumio volumio[957]: info: Jul 08 22:16:30 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:30 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand status took 4 milliseconds Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand clear took 5 milliseconds Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand status took 3 milliseconds Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand status took 2 milliseconds Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:30 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:30 volumio volumio[957]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 08 22:16:30 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:30 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:30 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:30 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:30 volumio volumio[957]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 08 22:16:30 volumio volumio[957]: info: ------------------------------ 30ms Jul 08 22:16:30 volumio volumio[957]: info: ------------------------------ 30ms Jul 08 22:16:30 volumio volumio[957]: info: Jul 08 22:16:30 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:30 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:30 volumio volumio[957]: info: Jul 08 22:16:30 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:30 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:30 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:30 volumio volumio[957]: info: ------------------------------ 31ms Jul 08 22:16:30 volumio volumio[957]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 08 22:16:30 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:30 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 22:16:30 volumio volumio[957]: info: ------------------------------ 38ms Jul 08 22:16:31 volumio volumio[957]: info: Jul 08 22:16:31 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:31 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:31 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" took 1340 milliseconds Jul 08 22:16:31 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:31 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:31 volumio volumio[957]: info: ------------------------------ 1317ms Jul 08 22:16:31 volumio volumio[957]: info: ------------------------------ 1315ms Jul 08 22:16:31 volumio volumio[957]: info: Jul 08 22:16:31 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:31 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:31 volumio volumio[957]: info: Jul 08 22:16:31 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:31 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:31 volumio volumio[957]: info: ------------------------------ 8ms Jul 08 22:16:31 volumio volumio[957]: info: sendMpdCommand play took 7 milliseconds Jul 08 22:16:31 volumio volumio[957]: info: ------------------------------ 4ms Jul 08 22:16:31 volumio volumio[957]: info: ------------------------------ 4ms Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 3 milliseconds Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 8 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream3","artist":"(#1 - 13/3000) ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 14ms Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:32 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 22 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 19 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 6 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 4 milliseconds Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 59ms Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:32 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:32 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:32 volumio volumio[957]: info: Jul 08 22:16:32 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 38ms Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 37 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 31 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 32 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 7ms Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 6 milliseconds Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":472,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 161ms Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 159ms Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 151ms Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 144ms Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 119ms Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand status took 119 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 116 milliseconds Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 114 milliseconds Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 203ms Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 171ms Jul 08 22:16:32 volumio volumio[957]: info: sendMpdCommand playlistinfo took 51 milliseconds Jul 08 22:16:32 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:32 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"66 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:32 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:32 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:32 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:32 volumio volumio[957]: info: ------------------------------ 194ms Jul 08 22:16:36 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::ClearQueue Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::stPlaybackTimer Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::serviceStop Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::serviceStop Jul 08 22:16:36 volumio volumio[957]: info: [1751984196444] ControllerWebradio::stop Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::addQueueItems Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::addQueueItems Jul 08 22:16:36 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:36 volumio volumio[957]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450 Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::play index 0 Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: [1751984196464] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:36 volumio volumio[957]: info: Jul 08 22:16:36 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand stop took 22 milliseconds Jul 08 22:16:36 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand stop took 4 milliseconds Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:36 volumio volumio[957]: info: Jul 08 22:16:36 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:36 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:36 volumio volumio[957]: info: Jul 08 22:16:36 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:36 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:36 volumio volumio[957]: info: Jul 08 22:16:36 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:36 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand status took 8 milliseconds Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand clear took 8 milliseconds Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand status took 6 milliseconds Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand status took 2 milliseconds Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:36 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 08 22:16:36 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:36 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:36 volumio volumio[957]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 08 22:16:36 volumio volumio[957]: info: ------------------------------ 28ms Jul 08 22:16:36 volumio volumio[957]: info: ------------------------------ 25ms Jul 08 22:16:36 volumio volumio[957]: info: Jul 08 22:16:36 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:36 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:36 volumio volumio[957]: info: Jul 08 22:16:36 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:36 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:36 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:36 volumio volumio[957]: info: ------------------------------ 26ms Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:36 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 22:16:36 volumio volumio[957]: info: ------------------------------ 35ms Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 241 milliseconds Jul 08 22:16:36 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:36 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:36 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:36 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:36 volumio volumio[957]: info: ------------------------------ 222ms Jul 08 22:16:36 volumio volumio[957]: info: ------------------------------ 221ms Jul 08 22:16:36 volumio volumio[957]: info: sendMpdCommand play took 1 milliseconds Jul 08 22:16:40 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:40 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:40 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:40 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:40 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:40 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:40 volumio volumio[957]: info: [1751984200343] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:40 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:40 volumio volumio[957]: info: sendMpdCommand stop took 1 milliseconds Jul 08 22:16:40 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:40 volumio volumio[957]: info: sendMpdCommand clear took 1 milliseconds Jul 08 22:16:40 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Jul 08 22:16:40 volumio volumio[957]: info: Jul 08 22:16:40 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:40 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:40 volumio volumio[957]: info: Jul 08 22:16:40 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:40 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:40 volumio volumio[957]: info: Jul 08 22:16:40 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:40 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:40 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 242 milliseconds Jul 08 22:16:40 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:40 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:40 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:40 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:40 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:40 volumio volumio[957]: info: ------------------------------ 243ms Jul 08 22:16:40 volumio volumio[957]: info: ------------------------------ 242ms Jul 08 22:16:40 volumio volumio[957]: info: ------------------------------ 241ms Jul 08 22:16:40 volumio volumio[957]: info: sendMpdCommand play took 1 milliseconds Jul 08 22:16:41 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:41 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:41 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:41 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:41 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:41 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:41 volumio volumio[957]: info: [1751984201196] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:41 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:41 volumio volumio[957]: info: sendMpdCommand stop took 1 milliseconds Jul 08 22:16:41 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:41 volumio volumio[957]: info: Jul 08 22:16:41 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:41 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:41 volumio volumio[957]: info: sendMpdCommand clear took 1 milliseconds Jul 08 22:16:41 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Jul 08 22:16:41 volumio volumio[957]: info: Jul 08 22:16:41 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:41 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:41 volumio volumio[957]: info: Jul 08 22:16:41 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:41 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:41 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:41 volumio volumio[957]: info: ------------------------------ 3ms Jul 08 22:16:41 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 531 milliseconds Jul 08 22:16:41 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:41 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:41 volumio volumio[957]: info: Jul 08 22:16:41 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:41 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:41 volumio volumio[957]: info: ------------------------------ 539ms Jul 08 22:16:41 volumio volumio[957]: info: ------------------------------ 538ms Jul 08 22:16:41 volumio volumio[957]: info: Jul 08 22:16:41 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:41 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:41 volumio volumio[957]: info: Jul 08 22:16:41 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:41 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:41 volumio volumio[957]: info: sendMpdCommand play took 21 milliseconds Jul 08 22:16:41 volumio volumio[957]: info: ------------------------------ 20ms Jul 08 22:16:41 volumio volumio[957]: info: ------------------------------ 12ms Jul 08 22:16:41 volumio volumio[957]: info: ------------------------------ 15ms Jul 08 22:16:42 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::ClearQueue Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::addQueueItems Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::addQueueItems Jul 08 22:16:42 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:42 volumio volumio[957]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450 Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::play index 0 Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:42 volumio volumio[957]: info: [1751984202385] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand stop took 4 milliseconds Jul 08 22:16:42 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:42 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand status took 1 milliseconds Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand clear took 1 milliseconds Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:42 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:42 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:42 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 4ms Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:42 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 7ms Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 445 milliseconds Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:42 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand status took 446 milliseconds Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:42 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:42 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:42 volumio volumio[957]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 22:16:42 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:42 volumio volumio[957]: info: No code Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:42 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:42 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:42 volumio volumio[957]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jul 08 22:16:42 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::syncState stateService stop Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:42 volumio volumio[957]: info: No code Jul 08 22:16:42 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:42 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 500ms Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 499ms Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:42 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:42 volumio volumio[957]: info: Jul 08 22:16:42 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:42 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:42 volumio volumio[957]: info: sendMpdCommand play took 68 milliseconds Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 69ms Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 11ms Jul 08 22:16:42 volumio volumio[957]: info: ------------------------------ 23ms Jul 08 22:16:43 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::ClearQueue Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::addQueueItems Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::addQueueItems Jul 08 22:16:43 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:43 volumio volumio[957]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450 Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::play index 0 Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:43 volumio volumio[957]: info: [1751984203320] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:43 volumio volumio[957]: info: Jul 08 22:16:43 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand stop took 4 milliseconds Jul 08 22:16:43 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:43 volumio volumio[957]: info: Jul 08 22:16:43 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:43 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:43 volumio volumio[957]: info: Jul 08 22:16:43 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:43 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:43 volumio volumio[957]: info: Jul 08 22:16:43 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:43 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand status took 5 milliseconds Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand clear took 4 milliseconds Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand status took 4 milliseconds Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand status took 3 milliseconds Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:43 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:43 volumio volumio[957]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 08 22:16:43 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:43 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:43 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:43 volumio volumio[957]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 08 22:16:43 volumio volumio[957]: info: ------------------------------ 36ms Jul 08 22:16:43 volumio volumio[957]: info: ------------------------------ 35ms Jul 08 22:16:43 volumio volumio[957]: info: Jul 08 22:16:43 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:43 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:43 volumio volumio[957]: info: Jul 08 22:16:43 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:43 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:43 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:43 volumio volumio[957]: info: ------------------------------ 37ms Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand playlistinfo took 36 milliseconds Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:43 volumio volumio[957]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 08 22:16:43 volumio volumio[957]: info: ------------------------------ 43ms Jul 08 22:16:43 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:43 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1340450" took 243 milliseconds Jul 08 22:16:43 volumio volumio[957]: info: ------------------------------ 211ms Jul 08 22:16:43 volumio volumio[957]: info: ------------------------------ 210ms Jul 08 22:16:43 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:43 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:43 volumio volumio[957]: info: sendMpdCommand play took 1 milliseconds Jul 08 22:16:44 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:44 volumio volumio[957]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::ClearQueue Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::clearPlayQueue Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:44 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::addQueueItems Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::addQueueItems Jul 08 22:16:44 volumio volumio[957]: info: Preload queue cleared Jul 08 22:16:44 volumio volumio[957]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321 Jul 08 22:16:44 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 08 22:16:44 volumio volumio[957]: info: CoreCommandRouter::volumioPushQueue Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::saveQueue Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::updateTrackBlock Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::getTrackBlock Jul 08 22:16:44 volumio volumio[957]: info: CoreCommandRouter::volumioPlay Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::play index 0 Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::stop Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::play index undefined Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::startPlaybackTimer Jul 08 22:16:44 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:44 volumio volumio[957]: info: [1751984204665] ControllerWebradio::clearAddPlayTrack Jul 08 22:16:44 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 22:16:44 volumio volumio[957]: info: sendMpdCommand stop took 2 milliseconds Jul 08 22:16:44 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 22:16:44 volumio volumio[957]: info: Jul 08 22:16:44 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:44 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:44 volumio volumio[957]: info: sendMpdCommand clear took 2 milliseconds Jul 08 22:16:44 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" Jul 08 22:16:44 volumio volumio[957]: info: Jul 08 22:16:44 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:44 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:44 volumio volumio[957]: info: Jul 08 22:16:44 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:44 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:44 volumio volumio[957]: error: updateQueue error: null Jul 08 22:16:44 volumio volumio[957]: info: ------------------------------ 5ms Jul 08 22:16:44 volumio volumio[957]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1823321" took 243 milliseconds Jul 08 22:16:44 volumio volumio[957]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 08 22:16:44 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand play Jul 08 22:16:44 volumio volumio[957]: info: Jul 08 22:16:44 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:44 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:44 volumio volumio[957]: info: ------------------------------ 244ms Jul 08 22:16:44 volumio volumio[957]: info: ------------------------------ 245ms Jul 08 22:16:44 volumio volumio[957]: info: Jul 08 22:16:44 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:44 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:44 volumio volumio[957]: info: Jul 08 22:16:44 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:44 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:44 volumio volumio[957]: info: sendMpdCommand play took 14 milliseconds Jul 08 22:16:44 volumio volumio[957]: info: ------------------------------ 15ms Jul 08 22:16:44 volumio volumio[957]: info: ------------------------------ 12ms Jul 08 22:16:44 volumio volumio[957]: info: ------------------------------ 11ms Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:45 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 8 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 7 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 4 milliseconds Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:45 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 17 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 19ms Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 16 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 6 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 5ms Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 5 milliseconds Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus stop Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 54ms Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 80ms Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 78ms Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces system playlist update Jul 08 22:16:45 volumio volumio[957]: info: Ignoring MPD Status Update Jul 08 22:16:45 volumio volumio[957]: info: Jul 08 22:16:45 volumio volumio[957]: ---------------------------- MPD announces state update: player Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::getState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand status Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 57 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 4ms Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand status took 3 milliseconds Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseState Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 152ms Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 152ms Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 146ms Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 138ms Jul 08 22:16:45 volumio volumio[957]: info: sendMpdCommand playlistinfo took 91 milliseconds Jul 08 22:16:45 volumio volumio[957]: verbose: ControllerMpd::parseTrackInfo Jul 08 22:16:45 volumio volumio[957]: info: ControllerMpd::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::servicePushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":0,"samplerate":32,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"65 Kbps","isStreaming":false,"title":"stream3","artist":"ANTENA1 - 94 7 FM","album":null,"uri":"http://51.254.29.39:80/stream3","trackType":""} Jul 08 22:16:45 volumio volumio[957]: verbose: CURRENT POSITION 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState stateService play Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::syncState currentStatus play Jul 08 22:16:45 volumio volumio[957]: info: Received an update from plugin. extracting info from payload Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CorePlayQueue::getTrack 0 Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: CoreStateMachine::pushState Jul 08 22:16:45 volumio volumio[957]: info: CoreCommandRouter::volumioPushState Jul 08 22:16:45 volumio volumio[957]: info: ------------------------------ 115ms Jul 08 22:16:50 volumio volumio[957]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 22:16:50 volumio volumio[957]: Error: connect ETIMEDOUT 162.125.32.2:443 Jul 08 22:16:50 volumio volumio[957]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 08 22:16:50 volumio volumio[957]: errno: -110, Jul 08 22:16:50 volumio volumio[957]: code: 'ETIMEDOUT', Jul 08 22:16:50 volumio volumio[957]: syscall: 'connect', Jul 08 22:16:50 volumio volumio[957]: address: '162.125.32.2', Jul 08 22:16:50 volumio volumio[957]: port: 443 Jul 08 22:16:50 volumio volumio[957]: } Jul 08 22:16:50 volumio volumio[957]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 22:16:51 volumio sudo[13749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 22:15 Jul 08 22:16:51 volumio sudo[13749]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"