Dec 27 14:29:13 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 27 14:29:13 volumio volumio[1184]: info: TuneIn handleBrowseUri: tunein/location Dec 27 14:29:13 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:16 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 27 14:29:16 volumio volumio[1184]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101217 Dec 27 14:29:16 volumio volumio[1184]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101217 Dec 27 14:29:16 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:17 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 27 14:29:17 volumio volumio[1184]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101309 Dec 27 14:29:17 volumio volumio[1184]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101309 Dec 27 14:29:17 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:17 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:20 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 27 14:29:20 volumio volumio[1184]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101309&pivot=name&filter=country Dec 27 14:29:20 volumio volumio[1184]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101309&pivot=name&filter=country Dec 27 14:29:21 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:23 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 27 14:29:23 volumio volumio[1184]: info: TuneIn handleBrowseUri: tunein/browse/?id=r101309&filter=s:~Q:country Dec 27 14:29:23 volumio volumio[1184]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=r101309&filter=s:~Q:country Dec 27 14:29:24 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:28 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::ClearQueue Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::stPlaybackTimer Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::serviceStop Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::serviceStop Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::stop Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::clearPlayQueue Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::saveQueue Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::addQueueItems Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::addQueueItems Dec 27 14:29:28 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:29:28 volumio volumio[1184]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s230486&filter=s:~Q:country Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 27 14:29:28 volumio volumio[1184]: info: Dec 27 14:29:28 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand stop took 40 milliseconds Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:28 volumio volumio[1184]: info: Dec 27 14:29:28 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:28 volumio volumio[1184]: info: Dec 27 14:29:28 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand status took 7 milliseconds Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand status took 6 milliseconds Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: 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":"Skin & Bones","artist":"The Sundays","album":"Reading, Writing and Arithmetic (1990, Interscope High Fidelity)","uri":"NAS/Found_Stuff/Found Stuff/Music/(2024) The Sundays - Reading, Writing and Arithmetic (1990, Interscope High Fidelity) [FLAC]/01. Skin & Bones.flac","trackType":"flac"} Dec 27 14:29:28 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: No code Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: 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":"Skin & Bones","artist":"The Sundays","album":"Reading, Writing and Arithmetic (1990, Interscope High Fidelity)","uri":"NAS/Found_Stuff/Found Stuff/Music/(2024) The Sundays - Reading, Writing and Arithmetic (1990, Interscope High Fidelity) [FLAC]/01. Skin & Bones.flac","trackType":"flac"} Dec 27 14:29:28 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: No code Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: 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":"Skin & Bones","artist":"The Sundays","album":"Reading, Writing and Arithmetic (1990, Interscope High Fidelity)","uri":"NAS/Found_Stuff/Found Stuff/Music/(2024) The Sundays - Reading, Writing and Arithmetic (1990, Interscope High Fidelity) [FLAC]/01. Skin & Bones.flac","trackType":"flac"} Dec 27 14:29:28 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::syncState stateService stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: No code Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:28 volumio volumio[1184]: info: ------------------------------ 92ms Dec 27 14:29:28 volumio volumio[1184]: info: ------------------------------ 91ms Dec 27 14:29:28 volumio volumio[1184]: info: ------------------------------ 90ms Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPushQueue Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::saveQueue Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::updateTrackBlock Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrackBlock Dec 27 14:29:28 volumio volumio[1184]: info: CoreCommandRouter::volumioPlay Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::play index 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::stop Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::play index undefined Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: CoreStateMachine::startPlaybackTimer Dec 27 14:29:28 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:28 volumio volumio[1184]: info: [1766845768456] ControllerWebradio::clearAddPlayTrack Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand stop took 3 milliseconds Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 14:29:28 volumio volumio[1184]: info: Dec 27 14:29:28 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:28 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:28 volumio volumio[1184]: info: sendMpdCommand clear took 1 milliseconds Dec 27 14:29:28 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand load "https://server10.reliastream.com/proxy/trevor1?mp=/stream" Dec 27 14:29:28 volumio volumio[1184]: info: Dec 27 14:29:28 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:28 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:28 volumio volumio[1184]: info: Dec 27 14:29:28 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:28 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:28 volumio volumio[1184]: error: updateQueue error: null Dec 27 14:29:28 volumio volumio[1184]: info: ------------------------------ 6ms Dec 27 14:29:30 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand add "https://server10.reliastream.com/proxy/trevor1?mp=/stream" Dec 27 14:29:30 volumio volumio[1184]: error: updateQueue error: null Dec 27 14:29:30 volumio volumio[1184]: error: updateQueue error: null Dec 27 14:29:30 volumio volumio[1184]: info: ------------------------------ 1565ms Dec 27 14:29:30 volumio volumio[1184]: info: ------------------------------ 1563ms Dec 27 14:29:30 volumio volumio[1184]: info: Dec 27 14:29:30 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:30 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:30 volumio volumio[1184]: info: sendMpdCommand add "https://server10.reliastream.com/proxy/trevor1?mp=/stream" took 3 milliseconds Dec 27 14:29:30 volumio volumio[1184]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 14:29:30 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand play Dec 27 14:29:30 volumio volumio[1184]: info: Dec 27 14:29:30 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:30 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:30 volumio volumio[1184]: info: Dec 27 14:29:30 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:30 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:30 volumio volumio[1184]: info: ------------------------------ 7ms Dec 27 14:29:30 volumio volumio[1184]: info: sendMpdCommand play took 7 milliseconds Dec 27 14:29:30 volumio volumio[1184]: info: ------------------------------ 6ms Dec 27 14:29:30 volumio volumio[1184]: info: ------------------------------ 5ms Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 3 milliseconds Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 7 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 7 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: 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":null,"album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus stop Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: 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":null,"album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 33ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 43ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 35 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 32 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: 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":null,"album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: 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":null,"album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: 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":null,"album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 97ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 97ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 93ms Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:31 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 83 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: 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":null,"album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 140ms Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:31 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:31 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:31 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 28ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 26 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 6ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 5 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 5ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 4 milliseconds Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:31 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:29:31 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:29:31 volumio volumio[1184]: info: Dec 27 14:29:31 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 15ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 13 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 11ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 10 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 10ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand status took 9 milliseconds Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Break - - Break","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Break - - Break","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Break - - Break","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 91ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 70ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 70ms Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 51 milliseconds Dec 27 14:29:31 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Break - - Break","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Break - - Break","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:29:31 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:29:31 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Break - - Break","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:29:31 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:29:31 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:29:31 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 136ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 131ms Dec 27 14:29:31 volumio volumio[1184]: info: ------------------------------ 131ms Dec 27 14:29:38 volumio volumio[1184]: info: Saving local item radio-favourites Dec 27 14:29:38 volumio volumio[1184]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s230486&filter=s:~Q:country","favourite":false} Dec 27 14:29:58 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 14:29:58 volumio volumio[1184]: info: CURURI: albums:// Dec 27 14:29:58 volumio volumio[1184]: info: listAlbums - loading Albums from cache Dec 27 14:29:58 volumio volumio[1184]: info: Preload queue cleared Dec 27 14:30:11 volumio volumio[1184]: info: Dec 27 14:30:11 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:30:11 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:30:11 volumio volumio[1184]: info: Dec 27 14:30:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:30:11 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:30:11 volumio volumio[1184]: info: Dec 27 14:30:11 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:30:11 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:30:11 volumio volumio[1184]: info: Dec 27 14:30:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:30:11 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:30:11 volumio volumio[1184]: info: Dec 27 14:30:11 volumio volumio[1184]: ---------------------------- MPD announces system playlist update Dec 27 14:30:11 volumio volumio[1184]: info: Ignoring MPD Status Update Dec 27 14:30:11 volumio volumio[1184]: info: Dec 27 14:30:11 volumio volumio[1184]: ---------------------------- MPD announces state update: player Dec 27 14:30:11 volumio volumio[1184]: info: ControllerMpd::getState Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand status Dec 27 14:30:11 volumio volumio[1184]: info: ------------------------------ 7ms Dec 27 14:30:11 volumio volumio[1184]: info: sendMpdCommand status took 6 milliseconds Dec 27 14:30:11 volumio volumio[1184]: info: ------------------------------ 5ms Dec 27 14:30:11 volumio volumio[1184]: info: sendMpdCommand status took 5 milliseconds Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:30:11 volumio volumio[1184]: info: ------------------------------ 9ms Dec 27 14:30:11 volumio volumio[1184]: info: sendMpdCommand status took 7 milliseconds Dec 27 14:30:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 14:30:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::parseState Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:30:11 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:30:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:30:11 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Roger Mathews - On Quasar - The Album Station","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:30:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:30:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:30:11 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:30:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:30:11 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Roger Mathews - On Quasar - The Album Station","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:30:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:30:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:30:11 volumio volumio[1184]: info: ------------------------------ 58ms Dec 27 14:30:11 volumio volumio[1184]: info: ------------------------------ 56ms Dec 27 14:30:11 volumio volumio[1184]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 27 14:30:11 volumio volumio[1184]: verbose: ControllerMpd::parseTrackInfo Dec 27 14:30:11 volumio volumio[1184]: info: ControllerMpd::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::servicePushState Dec 27 14:30:11 volumio volumio[1184]: info: CorePlayQueue::getTrack 0 Dec 27 14:30:11 volumio volumio[1184]: verbose: STATE SERVICE {"status":"play","position":0,"seek":41231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Roger Mathews - On Quasar - The Album Station","artist":"no name","album":null,"uri":"https://server10.reliastream.com/proxy/trevor1?mp=/stream","trackType":""} Dec 27 14:30:11 volumio volumio[1184]: verbose: CURRENT POSITION 0 Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::syncState stateService play Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::syncState currentStatus play Dec 27 14:30:11 volumio volumio[1184]: info: Received an update from plugin. extracting info from payload Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreStateMachine::pushState Dec 27 14:30:11 volumio volumio[1184]: info: CoreCommandRouter::volumioPushState Dec 27 14:30:11 volumio volumio[1184]: info: ------------------------------ 77ms Dec 27 14:30:19 volumio volumio[1184]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 14:30:19 volumio volumio[1184]: Error: Client network socket disconnected before secure TLS connection was established Dec 27 14:30:19 volumio volumio[1184]: at connResetException (node:internal/errors:720:14) Dec 27 14:30:19 volumio volumio[1184]: at TLSSocket.onConnectEnd (node:_tls_wrap:1682:19) Dec 27 14:30:19 volumio volumio[1184]: at TLSSocket.emit (node:events:526:35) Dec 27 14:30:19 volumio volumio[1184]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 27 14:30:19 volumio volumio[1184]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 27 14:30:19 volumio volumio[1184]: code: 'ECONNRESET', Dec 27 14:30:19 volumio volumio[1184]: path: null, Dec 27 14:30:19 volumio volumio[1184]: host: 'lastfm.freetls.fastly.net', Dec 27 14:30:19 volumio volumio[1184]: port: 443, Dec 27 14:30:19 volumio volumio[1184]: localAddress: undefined Dec 27 14:30:19 volumio volumio[1184]: } Dec 27 14:30:19 volumio volumio[1184]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 14:30:20 volumio sudo[14936]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 14:29' Dec 27 14:30:20 volumio sudo[14936]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"