-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2024-05-13 13:27:56 CST. -- May 13 13:26:03 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 13 13:26:03 volumio volumio[4182]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 13 13:26:03 volumio volumio[4182]: info: CoreCommandRouter::volumioGetState May 13 13:26:04 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::ClearQueue May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::stPlaybackTimer May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::serviceStop May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::serviceStop May 13 13:26:04 volumio volumio[4182]: info: [1715577964520] ControllerWebradio::stop May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::clearPlayQueue May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::addQueueItems May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::addQueueItems May 13 13:26:04 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:04 volumio volumio[4182]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsc_de/aacp_96 May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioPlay May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::play index 0 May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::play index undefined May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::startPlaybackTimer May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: [1715577964541] ControllerWebradio::clearAddPlayTrack May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:04 volumio volumio[4182]: info: May 13 13:26:04 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand stop took 27 milliseconds May 13 13:26:04 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand stop took 7 milliseconds May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand clear May 13 13:26:04 volumio volumio[4182]: info: May 13 13:26:04 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:04 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:04 volumio volumio[4182]: info: May 13 13:26:04 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:04 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:04 volumio volumio[4182]: info: May 13 13:26:04 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:04 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand status took 7 milliseconds May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand clear took 6 milliseconds May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand status took 5 milliseconds May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsc_de/aacp_96" May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:04 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:04 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:04 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:04 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:04 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:04 volumio volumio[4182]: info: ------------------------------ 25ms May 13 13:26:04 volumio volumio[4182]: info: ------------------------------ 24ms May 13 13:26:04 volumio volumio[4182]: info: May 13 13:26:04 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:04 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:04 volumio volumio[4182]: info: May 13 13:26:04 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:04 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:04 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:04 volumio volumio[4182]: info: ------------------------------ 41ms May 13 13:26:04 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 37 milliseconds May 13 13:26:04 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:04 volumio volumio[4182]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 13 13:26:04 volumio volumio[4182]: info: ------------------------------ 49ms May 13 13:26:05 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:05 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:05 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsc_de/aacp_96" May 13 13:26:05 volumio volumio[4182]: info: ------------------------------ 1364ms May 13 13:26:05 volumio volumio[4182]: info: ------------------------------ 1351ms May 13 13:26:05 volumio volumio[4182]: info: May 13 13:26:05 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:05 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:05 volumio volumio[4182]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsc_de/aacp_96" took 1 milliseconds May 13 13:26:05 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 13:26:05 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand play May 13 13:26:05 volumio volumio[4182]: info: May 13 13:26:05 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:05 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:05 volumio volumio[4182]: info: May 13 13:26:05 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:05 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:05 volumio volumio[4182]: info: ------------------------------ 4ms May 13 13:26:05 volumio volumio[4182]: info: sendMpdCommand play took 3 milliseconds May 13 13:26:05 volumio volumio[4182]: info: ------------------------------ 3ms May 13 13:26:05 volumio volumio[4182]: info: ------------------------------ 2ms May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 5 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:06 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 36 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 35 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 35 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 34 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 32 milliseconds May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus stop May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"aacp_96","artist":null,"album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 58ms May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 71ms May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 69ms May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:06 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:06 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:06 volumio volumio[4182]: info: May 13 13:26:06 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 39ms May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 36 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 36 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 35 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 35 milliseconds May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 8ms May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 7 milliseconds May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 5ms May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Johann Nepomuk Hummel - Air ? la Tirolienne avec variations","artist":"/SwissClassic_Room1.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Johann Nepomuk Hummel - Air ? la Tirolienne avec variations","artist":"/SwissClassic_Room1.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johann Nepomuk Hummel - Air ? la Tirolienne avec variations","artist":"/SwissClassic_Room1.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 111ms May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 105ms May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 105ms May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 54 milliseconds May 13 13:26:06 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 53 milliseconds May 13 13:26:06 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johann Nepomuk Hummel - Air ? la Tirolienne avec variations","artist":"/SwissClassic_Room1.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":388,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johann Nepomuk Hummel - Air ? la Tirolienne avec variations","artist":"/SwissClassic_Room1.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:06 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:06 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Johann Nepomuk Hummel - Air ? la Tirolienne avec variations","artist":"/SwissClassic_Room1.aac","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 13 13:26:06 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:06 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:06 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 125ms May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 95ms May 13 13:26:06 volumio volumio[4182]: info: ------------------------------ 91ms May 13 13:26:07 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 13:26:07 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 13:26:07 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::volumioGetBrowseSources May 13 13:26:08 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 13:26:09 volumio volumio[4182]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 13 13:26:09 volumio volumio[4182]: info: Completed starting MyVolumio Plugin May 13 13:26:09 volumio volumio[4182]: info: BOOT COMPLETED May 13 13:26:09 volumio volumio[4182]: [Metrics] CommandRouter: 63s 826.45ms May 13 13:26:09 volumio volumio[4182]: info: CoreCommandRouter::volumiosetStartupVolume May 13 13:26:09 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 13:26:09 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 13:26:09 volumio volumio[4182]: info: CoreCommandRouter::Close All Modals sent May 13 13:26:09 volumio volumio[4182]: info: CoreCommandRouter::Close All Modals sent May 13 13:26:09 volumio volumio[4182]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 13 13:26:09 volumio volumio[4182]: aplay: main:828: audio open error: Device or resource busy May 13 13:26:10 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 13 13:26:10 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 13 13:26:10 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 13 13:26:13 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::ClearQueue May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::stPlaybackTimer May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::serviceStop May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::serviceStop May 13 13:26:13 volumio volumio[4182]: info: [1715577973076] ControllerWebradio::stop May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::clearPlayQueue May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::addQueueItems May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::addQueueItems May 13 13:26:13 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:13 volumio volumio[4182]: info: Adding Item to queue: http://mscp3.live-streams.nl:8340/jazz-flac.flac May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioPlay May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::play index 0 May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::play index undefined May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::startPlaybackTimer May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: [1715577973088] ControllerWebradio::clearAddPlayTrack May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:13 volumio volumio[4182]: info: May 13 13:26:13 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand stop took 17 milliseconds May 13 13:26:13 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand stop took 5 milliseconds May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand clear May 13 13:26:13 volumio volumio[4182]: info: May 13 13:26:13 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:13 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:13 volumio volumio[4182]: info: May 13 13:26:13 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:13 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:13 volumio volumio[4182]: info: May 13 13:26:13 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:13 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand clear took 3 milliseconds May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand status took 2 milliseconds May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:13 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:13 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:13 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:13 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:13 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:13 volumio volumio[4182]: info: ------------------------------ 21ms May 13 13:26:13 volumio volumio[4182]: info: ------------------------------ 20ms May 13 13:26:13 volumio volumio[4182]: info: May 13 13:26:13 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:13 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:13 volumio volumio[4182]: info: May 13 13:26:13 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:13 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:13 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:13 volumio volumio[4182]: info: ------------------------------ 22ms May 13 13:26:13 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 22 milliseconds May 13 13:26:13 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:13 volumio volumio[4182]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 13 13:26:13 volumio volumio[4182]: info: ------------------------------ 28ms May 13 13:26:14 volumio volumio[4182]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20129%2F1000)%20VCR%20%7C%20Venice%20Classic%20Radio%20*%20Live/321d2229-2e1f-4b5c-bc2f-324d4476e8c0.jpg' May 13 13:26:14 volumio volumio[4182]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20129%2F1000)%20VCR%20%7C%20Venice%20Classic%20Radio%20*%20Live/321d2229-2e1f-4b5c-bc2f-324d4476e8c0.jpg' May 13 13:26:15 volumio volumio[4182]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20129%2F1000)%20VCR%20%7C%20Venice%20Classic%20Radio%20*%20Live/321d2229-2e1f-4b5c-bc2f-324d4476e8c0.jpg' May 13 13:26:15 volumio volumio[4182]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%20129%2F1000)%20VCR%20%7C%20Venice%20Classic%20Radio%20*%20Live/321d2229-2e1f-4b5c-bc2f-324d4476e8c0.jpg' May 13 13:26:15 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 13 13:26:15 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:15 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:15 volumio volumio[4182]: info: ------------------------------ 2398ms May 13 13:26:15 volumio volumio[4182]: info: ------------------------------ 2397ms May 13 13:26:15 volumio volumio[4182]: info: May 13 13:26:15 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:15 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:15 volumio volumio[4182]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" took 2 milliseconds May 13 13:26:15 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 13:26:15 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand play May 13 13:26:15 volumio volumio[4182]: info: May 13 13:26:15 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:15 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:15 volumio volumio[4182]: info: May 13 13:26:15 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:15 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:15 volumio volumio[4182]: info: ------------------------------ 6ms May 13 13:26:15 volumio volumio[4182]: info: sendMpdCommand play took 5 milliseconds May 13 13:26:15 volumio volumio[4182]: info: ------------------------------ 5ms May 13 13:26:15 volumio volumio[4182]: info: ------------------------------ 3ms May 13 13:26:16 volumio volumio[4182]: info: May 13 13:26:16 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:16 volumio volumio[4182]: info: May 13 13:26:16 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:16 volumio volumio[4182]: info: May 13 13:26:16 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:16 volumio volumio[4182]: info: May 13 13:26:16 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand status took 5 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand status took 2 milliseconds May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:16 volumio volumio[4182]: info: May 13 13:26:16 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:16 volumio volumio[4182]: info: May 13 13:26:16 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand status took 8 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 6 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 6 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 6 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:16 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus stop May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:16 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:16 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:16 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:16 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: ------------------------------ 36ms May 13 13:26:16 volumio volumio[4182]: info: ------------------------------ 46ms May 13 13:26:16 volumio volumio[4182]: info: ------------------------------ 46ms May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 34 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 33 milliseconds May 13 13:26:16 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 32 milliseconds May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:16 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:16 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:16 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:16 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:16 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:16 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:16 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:16 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:16 volumio volumio[4182]: info: ------------------------------ 82ms May 13 13:26:16 volumio volumio[4182]: info: ------------------------------ 78ms May 13 13:26:16 volumio volumio[4182]: info: ------------------------------ 77ms May 13 13:26:18 volumio volumio[4182]: info: May 13 13:26:18 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:18 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:18 volumio volumio[4182]: info: May 13 13:26:18 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:18 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:18 volumio volumio[4182]: info: May 13 13:26:18 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:18 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:18 volumio volumio[4182]: info: May 13 13:26:18 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:18 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:18 volumio volumio[4182]: info: ------------------------------ 9ms May 13 13:26:18 volumio volumio[4182]: info: sendMpdCommand status took 6 milliseconds May 13 13:26:18 volumio volumio[4182]: info: ------------------------------ 5ms May 13 13:26:18 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:18 volumio volumio[4182]: info: May 13 13:26:18 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:18 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:18 volumio volumio[4182]: info: May 13 13:26:18 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:18 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:18 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 5 milliseconds May 13 13:26:18 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 4 milliseconds May 13 13:26:18 volumio volumio[4182]: info: ------------------------------ 3ms May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:18 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:18 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:18 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:18 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:18 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:18 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:18 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:18 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:18 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:18 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:18 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:18 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:18 volumio volumio[4182]: info: ------------------------------ 39ms May 13 13:26:18 volumio volumio[4182]: info: ------------------------------ 36ms May 13 13:26:18 volumio volumio[4182]: info: sendMpdCommand status took 27 milliseconds May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:18 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 9 milliseconds May 13 13:26:18 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:18 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:18 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:18 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":297,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} May 13 13:26:18 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:18 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:18 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:18 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:18 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:18 volumio volumio[4182]: info: ------------------------------ 51ms May 13 13:26:20 volumio volumio-remote-updater[500]: No test mode May 13 13:26:20 volumio volumio-remote-updater[500]: No alpha test mode May 13 13:26:20 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 13 13:26:21 volumio volumio[4182]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES





NEW ADDITIONS

 

 

","title":"Update v3.661","updateavailable":true} May 13 13:26:21 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 13 13:26:36 volumio volumio[4182]: info: May 13 13:26:36 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:36 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:36 volumio volumio[4182]: info: May 13 13:26:36 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:36 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:36 volumio volumio[4182]: info: sendMpdCommand status took 2 milliseconds May 13 13:26:36 volumio volumio[4182]: info: sendMpdCommand status took 1 milliseconds May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:36 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 13 13:26:36 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::syncState stateService stop May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::play index undefined May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:36 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::startPlaybackTimer May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: [1715577996070] ControllerWebradio::clearAddPlayTrack May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:36 volumio volumio[4182]: info: ------------------------------ 30ms May 13 13:26:36 volumio volumio[4182]: info: ------------------------------ 29ms May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:36 volumio volumio[4182]: info: May 13 13:26:36 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:36 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:36 volumio volumio[4182]: info: sendMpdCommand stop took 10 milliseconds May 13 13:26:36 volumio volumio[4182]: info: sendMpdCommand status took 2 milliseconds May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand clear May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:36 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:36 volumio volumio[4182]: info: ------------------------------ 12ms May 13 13:26:36 volumio volumio[4182]: info: May 13 13:26:36 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:36 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:36 volumio volumio[4182]: info: sendMpdCommand clear took 19 milliseconds May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 13 13:26:36 volumio volumio[4182]: info: May 13 13:26:36 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:36 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:36 volumio volumio[4182]: info: May 13 13:26:36 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:36 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:36 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:36 volumio volumio[4182]: info: ------------------------------ 9ms May 13 13:26:36 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::ClearQueue May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::clearPlayQueue May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::addQueueItems May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::addQueueItems May 13 13:26:36 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:36 volumio volumio[4182]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553377 May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:36 volumio volumio[4182]: info: CoreCommandRouter::volumioPlay May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::play index 0 May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::play index undefined May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: CoreStateMachine::startPlaybackTimer May 13 13:26:36 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:36 volumio volumio[4182]: info: [1715577996744] ControllerWebradio::clearAddPlayTrack May 13 13:26:36 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:37 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" May 13 13:26:37 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:37 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 1332ms May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 1328ms May 13 13:26:37 volumio volumio[4182]: info: sendMpdCommand stop took 689 milliseconds May 13 13:26:37 volumio volumio[4182]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" took 4 milliseconds May 13 13:26:37 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand clear May 13 13:26:37 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 13:26:37 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand play May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:37 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 8ms May 13 13:26:37 volumio volumio[4182]: info: sendMpdCommand clear took 6 milliseconds May 13 13:26:37 volumio volumio[4182]: info: sendMpdCommand play took 6 milliseconds May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 5ms May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 4ms May 13 13:26:37 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553377" May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 10ms May 13 13:26:37 volumio volumio[4182]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553377" took 416 milliseconds May 13 13:26:37 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 13:26:37 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand play May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 418ms May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 417ms May 13 13:26:37 volumio volumio[4182]: info: sendMpdCommand play took 2 milliseconds May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: May 13 13:26:37 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:37 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 4ms May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 2ms May 13 13:26:37 volumio volumio[4182]: info: ------------------------------ 1ms May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 8 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 7 milliseconds May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:38 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 19 milliseconds May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 16 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 13 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 12 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 10 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 6 milliseconds May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus stop May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 40ms May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 46ms May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:38 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:38 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:38 volumio volumio[4182]: info: May 13 13:26:38 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 34ms May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 32 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 29 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 28 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 28 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 28 milliseconds May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 8ms May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 7 milliseconds May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 6ms May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":205,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":319,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 125ms May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 124ms May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 116ms May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 111ms May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 92 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 90 milliseconds May 13 13:26:38 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 91 milliseconds May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":376,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":376,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:38 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:38 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":376,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"143 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:38 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:38 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:38 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 164ms May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 136ms May 13 13:26:38 volumio volumio[4182]: info: ------------------------------ 134ms May 13 13:26:39 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioReplaceandPlayItems May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::ClearQueue May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::stPlaybackTimer May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::serviceStop May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::serviceStop May 13 13:26:39 volumio volumio[4182]: info: [1715577999010] ControllerWebradio::stop May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::clearPlayQueue May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::addQueueItems May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::addQueueItems May 13 13:26:39 volumio volumio[4182]: info: Preload queue cleared May 13 13:26:39 volumio volumio[4182]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553377 May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushQueue May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::saveQueue May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::updateTrackBlock May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrackBlock May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPlay May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::play index 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::stop May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::play index undefined May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::startPlaybackTimer May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: [1715577999023] ControllerWebradio::clearAddPlayTrack May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand stop May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand stop took 17 milliseconds May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand stop took 4 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand clear May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 3 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand clear took 2 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 2 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 2 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553377" May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 15ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 14ms May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: error: updateQueue error: null May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 17ms May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 16 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 22ms May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553377" took 238 milliseconds May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::setConsumeUpdateService mpd May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand play May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 228ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 227ms May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand play took 5 milliseconds May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 6ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 4ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 4ms May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 8 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 7 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 5 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 4 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus stop May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 33ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 44ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 44ms May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces system playlist update May 13 13:26:39 volumio volumio[4182]: info: Ignoring MPD Status Update May 13 13:26:39 volumio volumio[4182]: info: May 13 13:26:39 volumio volumio[4182]: ---------------------------- MPD announces state update: player May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::getState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand status May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 39ms May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 39 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 37 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 36 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 36 milliseconds May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 9ms May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 8 milliseconds May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 8ms May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand status took 7 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseState May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":57,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 94ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 89ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 88ms May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 60 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 60 milliseconds May 13 13:26:39 volumio volumio[4182]: info: sendMpdCommand playlistinfo took 59 milliseconds May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: verbose: ControllerMpd::parseTrackInfo May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":228,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":376,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ControllerMpd::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::servicePushState May 13 13:26:39 volumio volumio[4182]: info: CorePlayQueue::getTrack 0 May 13 13:26:39 volumio volumio[4182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":376,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"129 Kbps","isStreaming":false,"title":"Edvard Grieg (1843-1907) - 'Lyriske stykker' - 'Bryllupsdag på Troldhaugen' per pianoforte n.6 op.65 (05:19) (Alice Sara Ott, pianoforte) {+info: veniceclassicradio.eu}","artist":"VCR | Venice Classic Radio * Live","album":null,"uri":"http://116.202.241.212:8020/stream","trackType":""} May 13 13:26:39 volumio volumio[4182]: verbose: CURRENT POSITION 0 May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState stateService play May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::syncState currentStatus play May 13 13:26:39 volumio volumio[4182]: info: Received an update from plugin. extracting info from payload May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: CoreStateMachine::pushState May 13 13:26:39 volumio volumio[4182]: info: CoreCommandRouter::volumioPushState May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 143ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 110ms May 13 13:26:39 volumio volumio[4182]: info: ------------------------------ 109ms May 13 13:27:56 volumio volumio[4182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 13:27:56 volumio volumio[4182]: Error: connect ETIMEDOUT 157.240.17.14:80 May 13 13:27:56 volumio volumio[4182]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 13 13:27:56 volumio volumio[4182]: errno: -110, May 13 13:27:56 volumio volumio[4182]: code: 'ETIMEDOUT', May 13 13:27:56 volumio volumio[4182]: syscall: 'connect', May 13 13:27:56 volumio volumio[4182]: address: '157.240.17.14', May 13 13:27:56 volumio volumio[4182]: port: 80 May 13 13:27:56 volumio volumio[4182]: } May 13 13:27:56 volumio volumio[4182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 13:27:56 volumio sudo[4502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-13 13:26 May 13 13:27:56 volumio sudo[4502]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"