-- Logs begin at Tue 2025-07-01 03:09:10 UTC, end at Tue 2025-07-01 03:19:09 UTC. -- Jul 01 03:18:01 volumio volumio[1170]: info: Jul 01 03:18:01 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:01 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:01 volumio volumio[1170]: info: Jul 01 03:18:01 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:01 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:01 volumio volumio[1170]: info: Jul 01 03:18:01 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:01 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand status took 8 milliseconds Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand status took 8 milliseconds Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand status took 19 milliseconds Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand clearerror took 13 milliseconds Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand clearerror took 12 milliseconds Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:01 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:01 volumio volumio[1170]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radio-Chopin","artist":"Radio Chopin","album":null,"uri":"http://listen.radionomy.com/Radio-Chopin","trackType":""} Jul 01 03:18:01 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::syncState stateService stop Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: No code Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: ------------------------------ 57ms Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 45 milliseconds Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand clearerror took 39 milliseconds Jul 01 03:18:01 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:01 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:01 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:01 volumio volumio[1170]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radio-Chopin","artist":"Radio Chopin","album":null,"uri":"http://listen.radionomy.com/Radio-Chopin","trackType":""} Jul 01 03:18:01 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::syncState stateService stop Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: No code Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:01 volumio volumio[1170]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Radio-Chopin","artist":"Radio Chopin","album":null,"uri":"http://listen.radionomy.com/Radio-Chopin","trackType":""} Jul 01 03:18:01 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::syncState stateService stop Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: No code Jul 01 03:18:01 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:01 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:01 volumio volumio[1170]: info: ------------------------------ 111ms Jul 01 03:18:01 volumio volumio[1170]: info: ------------------------------ 110ms Jul 01 03:18:09 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:09 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:09 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:18:09 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:09 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99217899 Jul 01 03:18:09 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:18:09 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:09 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:09 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:18:09 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:09 volumio volumio[1170]: info: [1751339889901] ControllerWebradio::clearAddPlayTrack Jul 01 03:18:09 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:09 volumio volumio[1170]: info: sendMpdCommand stop took 1 milliseconds Jul 01 03:18:09 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:18:09 volumio volumio[1170]: info: sendMpdCommand clear took 1 milliseconds Jul 01 03:18:09 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99217899" Jul 01 03:18:09 volumio volumio[1170]: info: Jul 01 03:18:09 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:09 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:09 volumio volumio[1170]: info: Jul 01 03:18:09 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:09 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:09 volumio volumio[1170]: info: Jul 01 03:18:09 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:09 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99217899" took 226 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:18:10 volumio volumio[1170]: info: Jul 01 03:18:10 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:10 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:10 volumio volumio[1170]: info: ------------------------------ 228ms Jul 01 03:18:10 volumio volumio[1170]: info: ------------------------------ 227ms Jul 01 03:18:10 volumio volumio[1170]: info: ------------------------------ 226ms Jul 01 03:18:10 volumio volumio[1170]: info: Jul 01 03:18:10 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:10 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:10 volumio volumio[1170]: info: Jul 01 03:18:10 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:10 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand play took 4 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:10 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:18:10 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:10 volumio volumio[1170]: info: Jul 01 03:18:10 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:10 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:10 volumio volumio[1170]: info: Jul 01 03:18:10 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:10 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:10 volumio volumio[1170]: info: Jul 01 03:18:10 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:10 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand status took 7 milliseconds Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand status took 7 milliseconds Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand status took 18 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand clearerror took 13 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand clearerror took 12 milliseconds Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 8 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand clearerror took 3 milliseconds Jul 01 03:18:10 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 01 03:18:10 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:10 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:10 volumio volumio[1170]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"faithwave","artist":"Faithwave","album":null,"uri":"http://listen.shoutcast.com/faithwave","trackType":""} Jul 01 03:18:10 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::syncState stateService stop Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: No code Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:10 volumio volumio[1170]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"faithwave","artist":"Faithwave","album":null,"uri":"http://listen.shoutcast.com/faithwave","trackType":""} Jul 01 03:18:10 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::syncState stateService stop Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: No code Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:10 volumio volumio[1170]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"faithwave","artist":"Faithwave","album":null,"uri":"http://listen.shoutcast.com/faithwave","trackType":""} Jul 01 03:18:10 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::syncState stateService stop Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:10 volumio volumio[1170]: info: No code Jul 01 03:18:10 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:10 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:11 volumio volumio[1170]: info: ------------------------------ 105ms Jul 01 03:18:11 volumio volumio[1170]: info: ------------------------------ 105ms Jul 01 03:18:11 volumio volumio[1170]: info: ------------------------------ 106ms Jul 01 03:18:14 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 03:18:14 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 03:18:14 volumio volumio[1170]: info: Discovery: Getting this device information Jul 01 03:18:14 volumio volumio[1170]: info: CoreCommandRouter::volumioGetState Jul 01 03:18:14 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 03:18:15 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:15 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:15 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:18:15 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:15 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859 Jul 01 03:18:15 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:18:15 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:15 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:18:15 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:15 volumio volumio[1170]: info: [1751339895267] ControllerWebradio::clearAddPlayTrack Jul 01 03:18:15 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:15 volumio volumio[1170]: info: sendMpdCommand stop took 1 milliseconds Jul 01 03:18:15 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:18:15 volumio volumio[1170]: info: sendMpdCommand clear took 0 milliseconds Jul 01 03:18:15 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859" Jul 01 03:18:15 volumio volumio[1170]: info: Jul 01 03:18:15 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:15 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:15 volumio volumio[1170]: info: Jul 01 03:18:15 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:15 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:15 volumio volumio[1170]: info: Jul 01 03:18:15 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:15 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:15 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859" took 208 milliseconds Jul 01 03:18:15 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:18:15 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:18:15 volumio volumio[1170]: info: Jul 01 03:18:15 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:15 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:15 volumio volumio[1170]: info: ------------------------------ 208ms Jul 01 03:18:15 volumio volumio[1170]: info: ------------------------------ 207ms Jul 01 03:18:15 volumio volumio[1170]: info: Jul 01 03:18:15 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:15 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:15 volumio volumio[1170]: info: Jul 01 03:18:15 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:15 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:15 volumio volumio[1170]: info: sendMpdCommand play took 3 milliseconds Jul 01 03:18:15 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:15 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:15 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 21 milliseconds Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 25 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 24 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 30ms Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 6 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 1 milliseconds Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 84ms Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 63ms Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 54 milliseconds Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 107ms Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 102ms Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:16 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:16 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:16 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:16 volumio volumio[1170]: info: Jul 01 03:18:16 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 5ms Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 01 03:18:16 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":854,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":854,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:16 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:16 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":854,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:16 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:16 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:16 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 44ms Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 44ms Jul 01 03:18:16 volumio volumio[1170]: info: ------------------------------ 43ms Jul 01 03:18:20 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::stPlaybackTimer Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::serviceStop Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::serviceStop Jul 01 03:18:20 volumio volumio[1170]: info: [1751339900829] ControllerWebradio::stop Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:18:20 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:20 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519452 Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: [1751339900844] ControllerWebradio::clearAddPlayTrack Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:20 volumio volumio[1170]: info: Jul 01 03:18:20 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:20 volumio volumio[1170]: info: sendMpdCommand stop took 44 milliseconds Jul 01 03:18:20 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:20 volumio volumio[1170]: info: sendMpdCommand stop took 28 milliseconds Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:18:20 volumio volumio[1170]: info: Jul 01 03:18:20 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:20 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:20 volumio volumio[1170]: info: Jul 01 03:18:20 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:20 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:20 volumio volumio[1170]: info: Jul 01 03:18:20 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:20 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:20 volumio volumio[1170]: info: sendMpdCommand status took 5 milliseconds Jul 01 03:18:20 volumio volumio[1170]: info: sendMpdCommand clear took 5 milliseconds Jul 01 03:18:20 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519452" Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:20 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:18:20 volumio volumio[1170]: info: ------------------------------ 12ms Jul 01 03:18:20 volumio volumio[1170]: info: Jul 01 03:18:20 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:20 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:20 volumio volumio[1170]: info: Jul 01 03:18:20 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:20 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:20 volumio volumio[1170]: info: sendMpdCommand status took 11 milliseconds Jul 01 03:18:20 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:20 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:20 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:20 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:20 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:18:20 volumio volumio[1170]: info: ------------------------------ 24ms Jul 01 03:18:21 volumio volumio[1170]: error: updateQueue error: null Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:21 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 244ms Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 243 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519452" took 243 milliseconds Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:18:21 volumio volumio[1170]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 251ms Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 237ms Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 237ms Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand play took 2 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:21 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:21 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand status took 25 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand status took 26 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand status took 25 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: 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":"stream","artist":"(#1 - 4/8000) CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:21 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 30ms Jul 01 03:18:21 volumio volumio[1170]: info: Jul 01 03:18:21 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand status took 1 milliseconds Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: 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":"stream","artist":"(#1 - 4/8000) CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:21 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:21 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: 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":"stream","artist":"(#1 - 4/8000) CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:21 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:21 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: 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":"stream","artist":"(#1 - 4/8000) CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:21 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:21 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: 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":"stream","artist":"(#1 - 4/8000) CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:21 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:21 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 54ms Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 32ms Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 30ms Jul 01 03:18:21 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 24 milliseconds Jul 01 03:18:21 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:21 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: 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":"stream","artist":"(#1 - 4/8000) CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:21 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:21 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:21 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:21 volumio volumio[1170]: info: ------------------------------ 34ms Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:22 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:22 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:22 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:22 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 6ms Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand status took 5 milliseconds Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:22 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:22 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:22 volumio volumio[1170]: info: Jul 01 03:18:22 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 8ms Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand status took 6 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 6ms Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand status took 6 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 6ms Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand status took 5 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:22 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:22 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":429,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chamber Choir of Europe, Nicol Matt - Mozart Complete Edition, Disk 118 - Mozart: Canon. Hei wenn die Gläser, K. 89a","artist":"CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:22 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:22 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 23ms Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand status took 18 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 18ms Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand status took 17 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:22 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:22 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chamber Choir of Europe, Nicol Matt - Mozart Complete Edition, Disk 118 - Mozart: Canon. Hei wenn die Gläser, K. 89a","artist":"CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:22 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:22 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:22 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:22 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chamber Choir of Europe, Nicol Matt - Mozart Complete Edition, Disk 118 - Mozart: Canon. Hei wenn die Gläser, K. 89a","artist":"CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:22 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:22 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:22 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:22 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chamber Choir of Europe, Nicol Matt - Mozart Complete Edition, Disk 118 - Mozart: Canon. Hei wenn die Gläser, K. 89a","artist":"CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:22 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:22 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 64ms Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 63ms Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 63ms Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 64 milliseconds Jul 01 03:18:22 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 65 milliseconds Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:22 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:22 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:22 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chamber Choir of Europe, Nicol Matt - Mozart Complete Edition, Disk 118 - Mozart: Canon. Hei wenn die Gläser, K. 89a","artist":"CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:22 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:22 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:22 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:22 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Chamber Choir of Europe, Nicol Matt - Mozart Complete Edition, Disk 118 - Mozart: Canon. Hei wenn die Gläser, K. 89a","artist":"CALMRADIO.COM - Light Mozart","album":null,"uri":"http://216.235.95.103:31160/stream","trackType":""} Jul 01 03:18:22 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:22 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:22 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 105ms Jul 01 03:18:22 volumio volumio[1170]: info: ------------------------------ 104ms Jul 01 03:18:28 volumio volumio[1170]: info: Saving local item radio-favourites Jul 01 03:18:28 volumio volumio[1170]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519452","favourite":false} Jul 01 03:18:31 volumio volumio[1170]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%204%2F8000)%20CALMRADIO.COM%20-%20Light%20Mozart/a2688525-ef67-43fc-a80a-6d0c3a59c00c.jpg' Jul 01 03:18:33 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::stPlaybackTimer Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::serviceStop Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::serviceStop Jul 01 03:18:33 volumio volumio[1170]: info: [1751339913910] ControllerWebradio::stop Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:18:33 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:33 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859 Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: [1751339913920] ControllerWebradio::clearAddPlayTrack Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:33 volumio volumio[1170]: info: Jul 01 03:18:33 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand stop took 32 milliseconds Jul 01 03:18:33 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand stop took 24 milliseconds Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:18:33 volumio volumio[1170]: info: Jul 01 03:18:33 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:33 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:33 volumio volumio[1170]: info: Jul 01 03:18:33 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:33 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:33 volumio volumio[1170]: info: Jul 01 03:18:33 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:33 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand clear took 4 milliseconds Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859" Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:33 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:18:33 volumio volumio[1170]: info: ------------------------------ 12ms Jul 01 03:18:33 volumio volumio[1170]: info: Jul 01 03:18:33 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:33 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:33 volumio volumio[1170]: info: Jul 01 03:18:33 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:33 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:33 volumio volumio[1170]: error: updateQueue error: null Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand status took 15 milliseconds Jul 01 03:18:33 volumio volumio[1170]: info: ------------------------------ 15ms Jul 01 03:18:33 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 13 milliseconds Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:33 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:33 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:33 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:33 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:33 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:33 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:18:33 volumio volumio[1170]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 01 03:18:33 volumio volumio[1170]: info: ------------------------------ 24ms Jul 01 03:18:33 volumio volumio[1170]: info: ------------------------------ 26ms Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859" took 243 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:34 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 235ms Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 234ms Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:34 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:34 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand play took 4 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 1ms Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 22 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 23 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 23 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 28ms Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 4 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 1 milliseconds Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 54ms Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 54ms Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 34ms Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 52ms Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:34 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:34 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:34 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:34 volumio volumio[1170]: info: Jul 01 03:18:34 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 21ms Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 21ms Jul 01 03:18:34 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 15 milliseconds Jul 01 03:18:34 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:34 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:34 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:34 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Best of Brahms","album":null,"uri":"http://216.235.95.105:26232/stream","trackType":""} Jul 01 03:18:34 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:34 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:34 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:34 volumio volumio[1170]: info: ------------------------------ 28ms Jul 01 03:18:35 volumio volumio[1170]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%201%2F8000)%20CALMRADIO.COM%20-%20Best%20of%20Brahms/8d489e29-93c4-4a95-86a2-ffa3089550b0.jpg' Jul 01 03:18:42 volumio volumio[1170]: info: Saving local item radio-favourites Jul 01 03:18:42 volumio volumio[1170]: info: Pushing Favourites {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99520859","favourite":false} Jul 01 03:18:44 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 01 03:18:44 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 01 03:18:44 volumio volumio[1170]: info: Discovery: Getting this device information Jul 01 03:18:44 volumio volumio[1170]: info: CoreCommandRouter::volumioGetState Jul 01 03:18:44 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 01 03:18:45 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::stPlaybackTimer Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:45 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:45 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::serviceStop Jul 01 03:18:45 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::serviceStop Jul 01 03:18:45 volumio volumio[1170]: info: [1751339925995] ControllerWebradio::stop Jul 01 03:18:45 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:45 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:18:45 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:45 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:18:45 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:18:45 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:18:45 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519428 Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:18:45 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: [1751339926003] ControllerWebradio::clearAddPlayTrack Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand stop took 28 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand stop took 21 milliseconds Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:46 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 9 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand clear took 8 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519428" Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 18ms Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:46 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:46 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:46 volumio volumio[1170]: error: updateQueue error: null Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 13 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 13ms Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 11 milliseconds Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:18:46 volumio volumio[1170]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 34ms Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 42ms Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99519428" took 400 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:46 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 393ms Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 392ms Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:46 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:46 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand play took 3 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 26 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 25 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 26 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: Jul 01 03:18:46 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:46 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:46 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:46 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:46 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:46 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 42ms Jul 01 03:18:46 volumio volumio[1170]: info: ------------------------------ 47ms Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 01 03:18:46 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 01 03:18:46 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:46 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:46 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:46 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:46 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:46 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: 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":"stream","artist":"(#1 - 1/8000) CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:46 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:46 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:46 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:46 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:46 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 45ms Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 43ms Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 43ms Jul 01 03:18:47 volumio volumio[1170]: info: Jul 01 03:18:47 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:47 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:47 volumio volumio[1170]: info: Jul 01 03:18:47 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:47 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:47 volumio volumio[1170]: info: Jul 01 03:18:47 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:47 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:47 volumio volumio[1170]: info: Jul 01 03:18:47 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:47 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:47 volumio volumio[1170]: info: Jul 01 03:18:47 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:18:47 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:18:47 volumio volumio[1170]: info: Jul 01 03:18:47 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:18:47 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 5ms Jul 01 03:18:47 volumio volumio[1170]: info: sendMpdCommand status took 5 milliseconds Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 5ms Jul 01 03:18:47 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:18:47 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:18:47 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 01 03:18:47 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:18:47 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:18:47 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:47 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:47 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:47 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:47 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:47 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:47 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:47 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:47 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:18:47 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:18:47 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Calm Radio - This Channel is reserved for Premium Members - Upgrade to Premium","artist":"CALMRADIO.COM - Light Classical","album":null,"uri":"http://216.235.95.102:21128/stream","trackType":""} Jul 01 03:18:47 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:18:47 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:18:47 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 28ms Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 28ms Jul 01 03:18:47 volumio volumio[1170]: info: ------------------------------ 27ms Jul 01 03:19:02 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::stPlaybackTimer Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::serviceStop Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::serviceStop Jul 01 03:19:02 volumio volumio[1170]: info: [1751339942783] ControllerWebradio::stop Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:19:02 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:19:02 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99513978 Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: [1751339942793] ControllerWebradio::clearAddPlayTrack Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand stop took 31 milliseconds Jul 01 03:19:02 volumio volumio[1170]: info: Jul 01 03:19:02 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:02 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand stop took 22 milliseconds Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:19:02 volumio volumio[1170]: info: Jul 01 03:19:02 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:02 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:02 volumio volumio[1170]: info: Jul 01 03:19:02 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:02 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:02 volumio volumio[1170]: info: Jul 01 03:19:02 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:02 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand clear took 4 milliseconds Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99513978" Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:02 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:19:02 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:02 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:02 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:02 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:19:02 volumio volumio[1170]: info: ------------------------------ 15ms Jul 01 03:19:02 volumio volumio[1170]: info: ------------------------------ 15ms Jul 01 03:19:02 volumio volumio[1170]: info: Jul 01 03:19:02 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:02 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:02 volumio volumio[1170]: info: Jul 01 03:19:02 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:02 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:02 volumio volumio[1170]: error: updateQueue error: null Jul 01 03:19:02 volumio volumio[1170]: info: ------------------------------ 17ms Jul 01 03:19:02 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 01 03:19:02 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:02 volumio volumio[1170]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 01 03:19:02 volumio volumio[1170]: info: ------------------------------ 21ms Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99513978" took 253 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:03 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 245ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 244ms Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:03 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:03 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand play took 7 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 8ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 31 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 30 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 29 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 1 milliseconds Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: 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":"stream","artist":"(#1 - 54/8000) CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: 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":"stream","artist":"(#1 - 54/8000) CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: 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":"stream","artist":"(#1 - 54/8000) CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 45ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 48ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 54ms Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 21 milliseconds Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: 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":"stream","artist":"(#1 - 54/8000) CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: 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":"stream","artist":"(#1 - 54/8000) CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: 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":"stream","artist":"(#1 - 54/8000) CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 48ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 46ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 46ms Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:03 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:03 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:03 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:03 volumio volumio[1170]: info: Jul 01 03:19:03 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 2ms Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand status took 1 milliseconds Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:19:03 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Peter Kater - Wings (Peter Kater) - Hold Me Close","artist":"CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Peter Kater - Wings (Peter Kater) - Hold Me Close","artist":"CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:03 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:03 volumio volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Peter Kater - Wings (Peter Kater) - Hold Me Close","artist":"CALMRADIO.COM - Solo Piano","album":null,"uri":"http://216.235.95.100:1228/stream","trackType":""} Jul 01 03:19:03 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:03 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:03 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 29ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 29ms Jul 01 03:19:03 volumio volumio[1170]: info: ------------------------------ 30ms Jul 01 03:19:07 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::ClearQueue Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::stPlaybackTimer Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::serviceStop Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::serviceStop Jul 01 03:19:07 volumio volumio[1170]: info: [1751339947571] ControllerWebradio::stop Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::clearPlayQueue Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::addQueueItems Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::addQueueItems Jul 01 03:19:07 volumio volumio[1170]: info: Preload queue cleared Jul 01 03:19:07 volumio volumio[1170]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1794570 Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::volumioPushQueue Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::saveQueue Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::updateTrackBlock Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrackBlock Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::volumioPlay Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::play index 0 Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::stop Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::play index undefined Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::startPlaybackTimer Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:07 volumio volumio[1170]: info: [1751339947580] ControllerWebradio::clearAddPlayTrack Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand stop Jul 01 03:19:07 volumio volumio[1170]: info: Jul 01 03:19:07 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:07 volumio volumio[1170]: info: sendMpdCommand stop took 34 milliseconds Jul 01 03:19:07 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:07 volumio volumio[1170]: info: Jul 01 03:19:07 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:07 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:07 volumio volumio[1170]: info: sendMpdCommand stop took 27 milliseconds Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand clear Jul 01 03:19:07 volumio volumio[1170]: info: Jul 01 03:19:07 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:07 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:07 volumio volumio[1170]: info: Jul 01 03:19:07 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:07 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:07 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:19:07 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:19:07 volumio volumio[1170]: info: sendMpdCommand clear took 2 milliseconds Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1794570" Jul 01 03:19:07 volumio volumio[1170]: info: Jul 01 03:19:07 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:07 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:07 volumio volumio[1170]: info: Jul 01 03:19:07 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:07 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:07 volumio volumio[1170]: error: updateQueue error: null Jul 01 03:19:07 volumio volumio[1170]: info: sendMpdCommand status took 4 milliseconds Jul 01 03:19:07 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:19:07 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:07 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:07 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:07 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:07 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:07 volumio volumio[1170]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 01 03:19:07 volumio volumio[1170]: info: ------------------------------ 13ms Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:08 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 409 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1794570" took 409 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 408ms Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand play Jul 01 03:19:08 volumio volumio[1170]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 416ms Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 415ms Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:08 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces system playlist update Jul 01 03:19:08 volumio volumio[1170]: info: Ignoring MPD Status Update Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 4ms Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand play took 4 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 3ms Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand status took 33 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand status took 32 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand status took 33 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:08 volumio volumio[1170]: info: Jul 01 03:19:08 volumio volumio[1170]: ---------------------------- MPD announces state update: player Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::getState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand status Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand status took 3 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand status took 2 milliseconds Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseState Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: 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":"1864_128?fromyp=true","artist":".113FM Classic One","album":null,"uri":"http://216.235.82.15:80/1864_128?fromyp=true","trackType":""} Jul 01 03:19:08 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus stop Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: 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":"1864_128?fromyp=true","artist":".113FM Classic One","album":null,"uri":"http://216.235.82.15:80/1864_128?fromyp=true","trackType":""} Jul 01 03:19:08 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:08 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: 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":"1864_128?fromyp=true","artist":".113FM Classic One","album":null,"uri":"http://216.235.82.15:80/1864_128?fromyp=true","trackType":""} Jul 01 03:19:08 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:08 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 54ms Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 58ms Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 59ms Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 01 03:19:08 volumio volumio[1170]: info: sendMpdCommand playlistinfo took 22 milliseconds Jul 01 03:19:08 volumio volumio[1170]: verbose: ControllerMpd::parseTrackInfo Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: 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":"1864_128?fromyp=true","artist":".113FM Classic One","album":null,"uri":"http://216.235.82.15:80/1864_128?fromyp=true","trackType":""} Jul 01 03:19:08 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:08 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: 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":"1864_128?fromyp=true","artist":".113FM Classic One","album":null,"uri":"http://216.235.82.15:80/1864_128?fromyp=true","trackType":""} Jul 01 03:19:08 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:08 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: ControllerMpd::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::servicePushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: 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":"1864_128?fromyp=true","artist":".113FM Classic One","album":null,"uri":"http://216.235.82.15:80/1864_128?fromyp=true","trackType":""} Jul 01 03:19:08 volumio volumio[1170]: verbose: CURRENT POSITION 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState stateService play Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::syncState currentStatus play Jul 01 03:19:08 volumio volumio[1170]: info: Received an update from plugin. extracting info from payload Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreStateMachine::pushState Jul 01 03:19:08 volumio volumio[1170]: info: CoreCommandRouter::volumioPushState Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 47ms Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 46ms Jul 01 03:19:08 volumio volumio[1170]: info: ------------------------------ 45ms Jul 01 03:19:08 volumio volumio[1170]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 03:19:08 volumio volumio[1170]: Error: connect ETIMEDOUT 128.242.240.149:80 Jul 01 03:19:08 volumio volumio[1170]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 01 03:19:08 volumio volumio[1170]: errno: -110, Jul 01 03:19:08 volumio volumio[1170]: code: 'ETIMEDOUT', Jul 01 03:19:08 volumio volumio[1170]: syscall: 'connect', Jul 01 03:19:08 volumio volumio[1170]: address: '128.242.240.149', Jul 01 03:19:08 volumio volumio[1170]: port: 80 Jul 01 03:19:08 volumio volumio[1170]: } Jul 01 03:19:08 volumio volumio[1170]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 03:19:09 volumio sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 03:18 Jul 01 03:19:09 volumio sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"