-- Logs begin at Fri 2024-07-05 11:40:50 CST, end at Fri 2024-07-05 12:11:12 CST. -- Jul 05 12:10:06 volumio volumio[5258]: info: Preload queue cleared Jul 05 12:10:06 volumio volumio[5258]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::ClearQueue Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::stop Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::clearPlayQueue Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::saveQueue Jul 05 12:10:06 volumio volumio[5258]: info: CoreCommandRouter::volumioPushQueue Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::addQueueItems Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::addQueueItems Jul 05 12:10:06 volumio volumio[5258]: info: Preload queue cleared Jul 05 12:10:06 volumio volumio[5258]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271 Jul 05 12:10:06 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 05 12:10:06 volumio volumio[5258]: info: CoreCommandRouter::volumioPushQueue Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::saveQueue Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::updateTrackBlock Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::getTrackBlock Jul 05 12:10:06 volumio volumio[5258]: info: CoreCommandRouter::volumioPlay Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::play index 0 Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::stop Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::play index undefined Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::startPlaybackTimer Jul 05 12:10:06 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:06 volumio volumio[5258]: info: [1720152606501] ControllerWebradio::clearAddPlayTrack Jul 05 12:10:06 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 12:10:06 volumio volumio[5258]: info: sendMpdCommand stop took 2 milliseconds Jul 05 12:10:06 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 12:10:06 volumio volumio[5258]: info: Jul 05 12:10:06 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:06 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:06 volumio volumio[5258]: info: sendMpdCommand clear took 1 milliseconds Jul 05 12:10:06 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" Jul 05 12:10:06 volumio volumio[5258]: info: Jul 05 12:10:06 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:06 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:06 volumio volumio[5258]: info: Jul 05 12:10:06 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:06 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:06 volumio volumio[5258]: error: updateQueue error: null Jul 05 12:10:06 volumio volumio[5258]: info: ------------------------------ 4ms Jul 05 12:10:06 volumio volumio[5258]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1477271" took 323 milliseconds Jul 05 12:10:06 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 12:10:06 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand play Jul 05 12:10:06 volumio volumio[5258]: info: Jul 05 12:10:06 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:06 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:06 volumio volumio[5258]: info: ------------------------------ 325ms Jul 05 12:10:06 volumio volumio[5258]: info: ------------------------------ 325ms Jul 05 12:10:06 volumio volumio[5258]: info: sendMpdCommand play took 3 milliseconds Jul 05 12:10:06 volumio volumio[5258]: info: Jul 05 12:10:06 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:06 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:06 volumio volumio[5258]: info: Jul 05 12:10:06 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:06 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:06 volumio volumio[5258]: info: ------------------------------ 8ms Jul 05 12:10:06 volumio volumio[5258]: info: ------------------------------ 6ms Jul 05 12:10:06 volumio volumio[5258]: info: ------------------------------ 5ms Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 5 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 3 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 2 milliseconds Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: 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":"2585_128.mp3","artist":"SmoothJazz.com Global","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus stop Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: 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":"2585_128.mp3","artist":"SmoothJazz.com Global","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: 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":"2585_128.mp3","artist":"SmoothJazz.com Global","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 26ms Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 48ms Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 47ms Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:08 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:08 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:08 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:08 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 14ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 12 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 12ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 10 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 11ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 8 milliseconds Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:08 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:08 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:08 volumio volumio[5258]: info: Jul 05 12:10:08 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 18ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 14 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 10 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 8ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 5 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 5ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand status took 3 milliseconds Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Funkee Boy - A Better Way feat Timmy Maia ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Funkee Boy - A Better Way feat Timmy Maia ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Funkee Boy - A Better Way feat Timmy Maia ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 82ms Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 79ms Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 78ms Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 56 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 05 12:10:08 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 55 milliseconds Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Funkee Boy - A Better Way feat Timmy Maia ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Funkee Boy - A Better Way feat Timmy Maia ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:08 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:08 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":888,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Funkee Boy - A Better Way feat Timmy Maia ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} Jul 05 12:10:08 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:08 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:08 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 130ms Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 118ms Jul 05 12:10:08 volumio volumio[5258]: info: ------------------------------ 117ms Jul 05 12:10:34 volumio volumio[5258]: info: Preload queue cleared Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::ClearQueue Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::stop Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::stPlaybackTimer Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::updateTrackBlock Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrackBlock Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::serviceStop Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::serviceStop Jul 05 12:10:34 volumio volumio[5258]: info: [1720152634236] ControllerWebradio::stop Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::clearPlayQueue Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::saveQueue Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioPushQueue Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::addQueueItems Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::addQueueItems Jul 05 12:10:34 volumio volumio[5258]: info: Preload queue cleared Jul 05 12:10:34 volumio volumio[5258]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1747698 Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioPushQueue Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::saveQueue Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::updateTrackBlock Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrackBlock Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioPlay Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::play index 0 Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::stop Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::play index undefined Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::startPlaybackTimer Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: [1720152634262] ControllerWebradio::clearAddPlayTrack Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand stop took 31 milliseconds Jul 05 12:10:34 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand stop took 5 milliseconds Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:34 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:34 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:34 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand status took 6 milliseconds Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand clear took 5 milliseconds Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand status took 4 milliseconds Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand status took 3 milliseconds Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1747698" Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:34 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 05 12:10:34 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:34 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:34 volumio volumio[5258]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 28ms Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 28ms Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:34 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:34 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:34 volumio volumio[5258]: error: updateQueue error: null Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 30ms Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:34 volumio volumio[5258]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 38ms Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1747698" took 325 milliseconds Jul 05 12:10:34 volumio volumio[5258]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 12:10:34 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand play Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:34 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 306ms Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 305ms Jul 05 12:10:34 volumio volumio[5258]: info: sendMpdCommand play took 3 milliseconds Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:34 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:34 volumio volumio[5258]: info: Jul 05 12:10:34 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:34 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 7ms Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 4ms Jul 05 12:10:34 volumio volumio[5258]: info: ------------------------------ 3ms Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand status took 4 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand status took 3 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand status took 2 milliseconds Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: 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":"ccountry_mobile_aac","artist":"1.FM - Classic Country (www.1.fm)","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:35 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus stop Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: 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":"ccountry_mobile_aac","artist":"1.FM - Classic Country (www.1.fm)","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:35 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:35 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: 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":"ccountry_mobile_aac","artist":"1.FM - Classic Country (www.1.fm)","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:35 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:35 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 25ms Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 47ms Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 47ms Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:35 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:35 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:35 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:35 volumio volumio[5258]: info: Jul 05 12:10:35 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 13ms Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand status took 12 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 12ms Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand status took 10 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 10ms Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand status took 6 milliseconds Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 05 12:10:35 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:35 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Charley Pride - Kiss An Angel Good Morning","artist":"ccountry","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:35 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:35 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Charley Pride - Kiss An Angel Good Morning","artist":"ccountry","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:35 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:35 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:35 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:35 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Charley Pride - Kiss An Angel Good Morning","artist":"ccountry","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:35 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:35 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:35 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 74ms Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 72ms Jul 05 12:10:35 volumio volumio[5258]: info: ------------------------------ 69ms Jul 05 12:10:43 volumio volumio[5258]: info: CoreCommandRouter::volumioGetState Jul 05 12:10:49 volumio volumio[5258]: info: Jul 05 12:10:49 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:49 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:49 volumio volumio[5258]: info: Jul 05 12:10:49 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:49 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:49 volumio volumio[5258]: info: Jul 05 12:10:49 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:49 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:49 volumio volumio[5258]: info: ------------------------------ 6ms Jul 05 12:10:49 volumio volumio[5258]: info: ------------------------------ 4ms Jul 05 12:10:49 volumio volumio[5258]: info: Jul 05 12:10:49 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:49 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:49 volumio volumio[5258]: info: Jul 05 12:10:49 volumio volumio[5258]: ---------------------------- MPD announces system playlist update Jul 05 12:10:49 volumio volumio[5258]: info: Ignoring MPD Status Update Jul 05 12:10:49 volumio volumio[5258]: info: sendMpdCommand status took 12 milliseconds Jul 05 12:10:49 volumio volumio[5258]: info: sendMpdCommand status took 8 milliseconds Jul 05 12:10:49 volumio volumio[5258]: info: ------------------------------ 8ms Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:49 volumio volumio[5258]: info: Jul 05 12:10:49 volumio volumio[5258]: ---------------------------- MPD announces state update: player Jul 05 12:10:49 volumio volumio[5258]: info: ControllerMpd::getState Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand status Jul 05 12:10:49 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 05 12:10:49 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 05 12:10:49 volumio volumio[5258]: info: sendMpdCommand status took 2 milliseconds Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::parseState Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 12:10:49 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:49 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:49 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ray Wylie Hubbard - Redneck Mother","artist":"ccountry","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:49 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:49 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:49 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:49 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:49 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ray Wylie Hubbard - Redneck Mother","artist":"ccountry","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:49 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:49 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:49 volumio volumio[5258]: info: ------------------------------ 75ms Jul 05 12:10:49 volumio volumio[5258]: info: ------------------------------ 73ms Jul 05 12:10:49 volumio volumio[5258]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 05 12:10:49 volumio volumio[5258]: verbose: ControllerMpd::parseTrackInfo Jul 05 12:10:49 volumio volumio[5258]: info: ControllerMpd::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::servicePushState Jul 05 12:10:49 volumio volumio[5258]: info: CorePlayQueue::getTrack 0 Jul 05 12:10:49 volumio volumio[5258]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Ray Wylie Hubbard - Redneck Mother","artist":"ccountry","album":null,"uri":"http://185.33.21.111:80/ccountry_mobile_aac","trackType":""} Jul 05 12:10:49 volumio volumio[5258]: verbose: CURRENT POSITION 0 Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::syncState stateService play Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::syncState currentStatus play Jul 05 12:10:49 volumio volumio[5258]: info: Received an update from plugin. extracting info from payload Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreStateMachine::pushState Jul 05 12:10:49 volumio volumio[5258]: info: CoreCommandRouter::volumioPushState Jul 05 12:10:49 volumio volumio[5258]: info: ------------------------------ 77ms Jul 05 12:11:11 volumio volumio[5258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 12:11:11 volumio volumio[5258]: Error: connect ETIMEDOUT 108.160.165.53:80 Jul 05 12:11:11 volumio volumio[5258]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 05 12:11:11 volumio volumio[5258]: errno: -110, Jul 05 12:11:11 volumio volumio[5258]: code: 'ETIMEDOUT', Jul 05 12:11:11 volumio volumio[5258]: syscall: 'connect', Jul 05 12:11:11 volumio volumio[5258]: address: '108.160.165.53', Jul 05 12:11:11 volumio volumio[5258]: port: 80 Jul 05 12:11:11 volumio volumio[5258]: } Jul 05 12:11:11 volumio volumio[5258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 12:11:12 volumio sudo[5638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 12:10 Jul 05 12:11:12 volumio sudo[5638]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"