-- Logs begin at Sat 2025-05-17 17:43:19 UTC, end at Sat 2025-05-24 15:47:12 UTC. -- May 24 15:46:22 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 15:46:22 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 15:46:22 volumio1 volumio[1159]: info: Discovery: Getting this device information May 24 15:46:22 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetState May 24 15:46:22 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 15:46:37 volumio1 volumio[1159]: verbose: New Socket.io Connection to 192.168.178.34 from 192.168.178.43 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 May 24 15:46:37 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetState May 24 15:46:37 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 15:46:37 volumio1 volumio[1159]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 24 15:46:37 volumio1 volumio[1159]: info: Listing playlists May 24 15:46:37 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetQueue May 24 15:46:37 volumio1 volumio[1159]: info: CoreStateMachine::getQueue May 24 15:46:37 volumio1 volumio[1159]: info: CorePlayQueue::getQueue May 24 15:46:37 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:46:37 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:46:38 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::ClearQueue May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::stop May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::stPlaybackTimer May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::updateTrackBlock May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrackBlock May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::serviceStop May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::serviceStop May 24 15:46:38 volumio1 volumio[1159]: info: [1748101598656] ControllerWebradio::stop May 24 15:46:38 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::clearPlayQueue May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::saveQueue May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::addQueueItems May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::addQueueItems May 24 15:46:38 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:46:38 volumio1 volumio[1159]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s309360 May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 24 15:46:38 volumio1 volumio[1159]: info: May 24 15:46:38 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:38 volumio1 volumio[1159]: info: sendMpdCommand stop took 22 milliseconds May 24 15:46:38 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:38 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:38 volumio1 volumio[1159]: info: sendMpdCommand status took 2 milliseconds May 24 15:46:38 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:38 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:38 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:46:38 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:38 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:38 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fun Fun - Give Me Your Love","artist":"80s80s Digital Web","album":null,"uri":"http://regiocast.streamabc.net/regc-80s80smweb2517500-mp3-192-1672667?sABC=6831r8sp%230%23oss590s8013q9027q2735524r44qp231%23gharva&aw_0_1st.playerid=tunein&amsparams=playerid:tunein;skey:1748101372","trackType":""} May 24 15:46:38 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService stop May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:38 volumio1 volumio[1159]: info: No code May 24 15:46:38 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:38 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:38 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:38 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:38 volumio1 volumio[1159]: info: ------------------------------ 37ms May 24 15:46:39 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 24 15:46:39 volumio1 volumio[1159]: info: CorePlayQueue::saveQueue May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::updateTrackBlock May 24 15:46:39 volumio1 volumio[1159]: info: CorePlayQueue::getTrackBlock May 24 15:46:39 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPlay May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::play index 0 May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::stop May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::play index undefined May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:39 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:39 volumio1 volumio[1159]: info: CoreStateMachine::startPlaybackTimer May 24 15:46:39 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:39 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:46:39 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:46:39 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 24 15:46:39 volumio1 volumio[1159]: info: [1748101599021] ControllerWebradio::clearAddPlayTrack May 24 15:46:39 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 24 15:46:39 volumio1 volumio[1159]: info: sendMpdCommand stop took 2 milliseconds May 24 15:46:39 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand clear May 24 15:46:39 volumio1 volumio[1159]: info: May 24 15:46:39 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:39 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:39 volumio1 volumio[1159]: info: sendMpdCommand clear took 2 milliseconds May 24 15:46:39 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand load "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" May 24 15:46:39 volumio1 volumio[1159]: error: updateQueue error: null May 24 15:46:39 volumio1 volumio[1159]: info: ------------------------------ 2ms May 24 15:46:41 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand add "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" May 24 15:46:41 volumio1 volumio[1159]: info: May 24 15:46:41 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:41 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:41 volumio1 volumio[1159]: info: sendMpdCommand add "http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/" took 1 milliseconds May 24 15:46:41 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 15:46:41 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand play May 24 15:46:41 volumio1 volumio[1159]: info: ------------------------------ 2ms May 24 15:46:41 volumio1 volumio[1159]: info: sendMpdCommand play took 1 milliseconds May 24 15:46:42 volumio1 volumio[1159]: info: May 24 15:46:42 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:42 volumio1 volumio[1159]: info: May 24 15:46:42 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand status took 3 milliseconds May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand status took 2 milliseconds May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:42 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:42 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} May 24 15:46:42 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 24 15:46:42 volumio1 volumio[1159]: info: ------------------------------ 10ms May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 3 milliseconds May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:42 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:42 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} May 24 15:46:42 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:42 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:42 volumio1 volumio[1159]: info: ------------------------------ 23ms May 24 15:46:42 volumio1 volumio[1159]: info: May 24 15:46:42 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:42 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:42 volumio1 volumio[1159]: info: May 24 15:46:42 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:42 volumio1 volumio[1159]: info: ------------------------------ 7ms May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand status took 3 milliseconds May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 2 milliseconds May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:42 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:42 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"RADIO BOB - Livestream NRW","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} May 24 15:46:42 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:42 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:42 volumio1 volumio[1159]: info: ------------------------------ 29ms May 24 15:46:42 volumio1 volumio[1159]: info: May 24 15:46:42 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:42 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:42 volumio1 volumio[1159]: info: May 24 15:46:42 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:42 volumio1 volumio[1159]: info: ------------------------------ 4ms May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand status took 3 milliseconds May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:42 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:46:42 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:42 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:42 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:42 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1861,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Poison - Talk Dirty to Me","artist":"RADIO BOB - Livestream NRW","album":null,"uri":"http://streams.radiobob.de/live-nrw-mitte/mp3-192/tunein/","trackType":""} May 24 15:46:42 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:42 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:42 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:42 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:42 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:42 volumio1 volumio[1159]: info: ------------------------------ 19ms May 24 15:46:47 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::ClearQueue May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::stop May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::stPlaybackTimer May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::updateTrackBlock May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::getTrackBlock May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:47 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:47 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::serviceStop May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::serviceStop May 24 15:46:47 volumio1 volumio[1159]: info: [1748101607603] ControllerWebradio::stop May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::clearPlayQueue May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::saveQueue May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::addQueueItems May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::addQueueItems May 24 15:46:47 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:46:47 volumio1 volumio[1159]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012 May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::saveQueue May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::updateTrackBlock May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::getTrackBlock May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPlay May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::play index 0 May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::stop May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::play index undefined May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:47 volumio1 volumio[1159]: info: CoreStateMachine::startPlaybackTimer May 24 15:46:47 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetVisibleSources May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 15:46:47 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 24 15:46:47 volumio1 volumio[1159]: info: [1748101607625] ControllerWebradio::clearAddPlayTrack May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 24 15:46:47 volumio1 volumio[1159]: info: May 24 15:46:47 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:47 volumio1 volumio[1159]: info: sendMpdCommand stop took 28 milliseconds May 24 15:46:47 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:47 volumio1 volumio[1159]: info: sendMpdCommand stop took 6 milliseconds May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand clear May 24 15:46:47 volumio1 volumio[1159]: info: May 24 15:46:47 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:47 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:47 volumio1 volumio[1159]: info: sendMpdCommand status took 2 milliseconds May 24 15:46:47 volumio1 volumio[1159]: info: sendMpdCommand clear took 1 milliseconds May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" May 24 15:46:47 volumio1 volumio[1159]: error: updateQueue error: null May 24 15:46:47 volumio1 volumio[1159]: info: ------------------------------ 2ms May 24 15:46:47 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:46:47 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:47 volumio1 volumio[1159]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 24 15:46:47 volumio1 volumio[1159]: info: ------------------------------ 5ms May 24 15:46:48 volumio1 volumio[1159]: info: May 24 15:46:48 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:48 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:48 volumio1 volumio[1159]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99498012" took 1080 milliseconds May 24 15:46:48 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService mpd May 24 15:46:48 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand play May 24 15:46:48 volumio1 volumio[1159]: info: ------------------------------ 2ms May 24 15:46:48 volumio1 volumio[1159]: info: sendMpdCommand play took 1 milliseconds May 24 15:46:49 volumio1 volumio[1159]: info: May 24 15:46:49 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:49 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:49 volumio1 volumio[1159]: info: May 24 15:46:49 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:49 volumio1 volumio[1159]: info: sendMpdCommand status took 1 milliseconds May 24 15:46:49 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:49 volumio1 volumio[1159]: info: sendMpdCommand status took 1 milliseconds May 24 15:46:49 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 0 milliseconds May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:49 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:49 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:49 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:49 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 3716/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:49 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:49 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:49 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 24 15:46:49 volumio1 volumio[1159]: info: ------------------------------ 4ms May 24 15:46:49 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:49 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:49 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:49 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:49 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne","artist":"(#1 - 3716/500000) ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:49 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:49 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:49 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:49 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:49 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:49 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:49 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:49 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:49 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:49 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:49 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:49 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:49 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:49 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:49 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:49 volumio1 volumio[1159]: info: ------------------------------ 20ms May 24 15:46:49 volumio1 volumio[1159]: info: May 24 15:46:49 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:49 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:49 volumio1 volumio[1159]: info: May 24 15:46:49 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:49 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:49 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:50 volumio1 volumio[1159]: info: May 24 15:46:50 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:50 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:50 volumio1 volumio[1159]: info: May 24 15:46:50 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:50 volumio1 volumio[1159]: info: May 24 15:46:50 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:50 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:50 volumio1 volumio[1159]: info: May 24 15:46:50 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:50 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:50 volumio1 volumio[1159]: info: ------------------------------ 10ms May 24 15:46:50 volumio1 volumio[1159]: info: sendMpdCommand status took 9 milliseconds May 24 15:46:50 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:50 volumio1 volumio[1159]: info: ------------------------------ 8ms May 24 15:46:50 volumio1 volumio[1159]: info: sendMpdCommand status took 7 milliseconds May 24 15:46:50 volumio1 volumio[1159]: info: ------------------------------ 9ms May 24 15:46:50 volumio1 volumio[1159]: info: sendMpdCommand status took 10 milliseconds May 24 15:46:50 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 10 milliseconds May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:50 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:50 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:50 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:50 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:50 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:50 volumio1 volumio[1159]: info: ------------------------------ 36ms May 24 15:46:50 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 15 milliseconds May 24 15:46:50 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 16 milliseconds May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:50 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:50 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:50 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:50 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:50 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:50 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:50 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:50 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:50 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1102,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:50 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:50 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:50 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:50 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:50 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:50 volumio1 volumio[1159]: info: ------------------------------ 46ms May 24 15:46:50 volumio1 volumio[1159]: info: ------------------------------ 46ms May 24 15:46:51 volumio1 volumio[1159]: info: May 24 15:46:51 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:51 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:51 volumio1 volumio[1159]: info: May 24 15:46:51 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:51 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:51 volumio1 volumio[1159]: info: ------------------------------ 2ms May 24 15:46:51 volumio1 volumio[1159]: info: sendMpdCommand status took 1 milliseconds May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:51 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:51 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:51 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:51 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:51 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:51 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:51 volumio1 volumio[1159]: info: ------------------------------ 19ms May 24 15:46:51 volumio1 volumio[1159]: info: May 24 15:46:51 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:51 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:51 volumio1 volumio[1159]: info: May 24 15:46:51 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:51 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:51 volumio1 volumio[1159]: info: ------------------------------ 1ms May 24 15:46:51 volumio1 volumio[1159]: info: sendMpdCommand status took 2 milliseconds May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:51 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 0 milliseconds May 24 15:46:51 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:51 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:51 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:51 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":3235,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:51 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:51 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:51 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:51 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:51 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:51 volumio1 volumio[1159]: info: ------------------------------ 17ms May 24 15:46:52 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 24 15:46:52 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 24 15:46:52 volumio1 volumio[1159]: info: Discovery: Getting this device information May 24 15:46:52 volumio1 volumio[1159]: info: CoreCommandRouter::volumioGetState May 24 15:46:52 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 24 15:46:53 volumio1 volumio[1159]: info: May 24 15:46:53 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:46:53 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:46:53 volumio1 volumio[1159]: info: May 24 15:46:53 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:46:53 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:46:53 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:46:53 volumio1 volumio[1159]: info: ------------------------------ 2ms May 24 15:46:53 volumio1 volumio[1159]: info: sendMpdCommand status took 1 milliseconds May 24 15:46:53 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:46:53 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:46:53 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 0 milliseconds May 24 15:46:53 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:46:53 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:46:53 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:46:53 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:46:53 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4604,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:46:53 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:46:53 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService play May 24 15:46:53 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus play May 24 15:46:53 volumio1 volumio[1159]: info: Received an update from plugin. extracting info from payload May 24 15:46:53 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 24 15:46:53 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:53 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:46:53 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:53 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:53 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:53 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:46:53 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:46:53 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:46:53 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:46:53 volumio1 volumio[1159]: info: ------------------------------ 16ms May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri May 24 15:47:03 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track0 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track1 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track2 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track3 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track4 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track5 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track6 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track7 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track8 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track9 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track10 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track11 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track12 May 24 15:47:03 volumio1 volumio[1159]: info: Preloading song: cdaudio/track13 May 24 15:47:03 volumio1 volumio[1159]: info: Executing endpoint metavolumio May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 15:47:03 volumio1 volumio[1159]: info: Executing endpoint metavolumio May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 15:47:03 volumio1 volumio[1159]: info: Executing endpoint metavolumio May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track0 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Executing endpoint metavolumio May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 15:47:03 volumio1 volumio[1159]: info: Executing endpoint metavolumio May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 15:47:03 volumio1 volumio[1159]: info: Executing endpoint metavolumio May 24 15:47:03 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track1 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track2 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track3 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track4 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track5 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track6 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track7 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track8 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track9 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track10 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track11 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track12 in service cd_controller May 24 15:47:03 volumio1 volumio[1159]: info: Exploding uri cdaudio/track13 in service cd_controller May 24 15:47:05 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioReplaceandPlayItems May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::ClearQueue May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::stop May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::stPlaybackTimer May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::updateTrackBlock May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrackBlock May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::serviceStop May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::serviceStop May 24 15:47:05 volumio1 volumio[1159]: info: [1748101625401] ControllerWebradio::stop May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::clearPlayQueue May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::saveQueue May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::addQueueItems May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::addQueueItems May 24 15:47:05 volumio1 volumio[1159]: info: Preload queue cleared May 24 15:47:05 volumio1 volumio[1159]: info: Adding Item to queue: audiocd May 24 15:47:05 volumio1 volumio[1159]: info: Exploding uri audiocd in service cd_controller May 24 15:47:05 volumio1 volumio[1159]: info: May 24 15:47:05 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand stop took 16 milliseconds May 24 15:47:05 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand status took 1 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 1 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:47:05 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.antenne.de:80/rockantenne","trackType":""} May 24 15:47:05 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService stop May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: No code May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: ------------------------------ 31ms May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushQueue May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::saveQueue May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::updateTrackBlock May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrackBlock May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPlay May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::play index 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::stop May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::play index undefined May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::startPlaybackTimer May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::clearAddPlayTracks cdda:///1 May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand stop May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand stop took 14 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand clear May 24 15:47:05 volumio1 volumio[1159]: info: May 24 15:47:05 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:47:05 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand clear took 3 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand add "cdda:///1" May 24 15:47:05 volumio1 volumio[1159]: error: updateQueue error: null May 24 15:47:05 volumio1 volumio[1159]: info: May 24 15:47:05 volumio1 volumio[1159]: ---------------------------- MPD announces system playlist update May 24 15:47:05 volumio1 volumio[1159]: info: Ignoring MPD Status Update May 24 15:47:05 volumio1 volumio[1159]: info: ------------------------------ 3ms May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand add "cdda:///1" took 3 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand play May 24 15:47:05 volumio1 volumio[1159]: info: ------------------------------ 3ms May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand play took 2 milliseconds May 24 15:47:05 volumio1 volumio[1159]: info: May 24 15:47:05 volumio1 volumio[1159]: ---------------------------- MPD announces state update: player May 24 15:47:05 volumio1 volumio[1159]: info: ControllerMpd::getState May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand status May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand clearerror May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand status took 6 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::parseState May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand clearerror took 2 milliseconds May 24 15:47:05 volumio1 volumio[1159]: info: sendMpdCommand playlistinfo took 0 milliseconds May 24 15:47:05 volumio1 volumio[1159]: verbose: ControllerMpd::parseTrackInfo May 24 15:47:05 volumio1 volumio[1159]: info: ControllerMpd::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::servicePushState May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"cdda:///1","trackType":"CD Audio"} May 24 15:47:05 volumio1 volumio[1159]: verbose: CURRENT POSITION 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::syncState stateService stop May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::syncState currentStatus stop May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: No code May 24 15:47:05 volumio1 volumio[1159]: info: CoreStateMachine::pushState May 24 15:47:05 volumio1 volumio[1159]: info: CorePlayQueue::getTrack 0 May 24 15:47:05 volumio1 volumio[1159]: info: CoreCommandRouter::volumioPushState May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output update for this device May 24 15:47:05 volumio1 volumio[1159]: info: MRS: Pushing multiroomSync output May 24 15:47:05 volumio1 volumio[1159]: info: ------------------------------ 32ms May 24 15:47:12 volumio1 volumio[1159]: info: CALLMETHOD: music_service cd_controller getRipInfo May 24 15:47:12 volumio1 volumio[1159]: info: CoreCommandRouter::executeOnPlugin: cd_controller , getRipInfo May 24 15:47:12 volumio1 volumio[1159]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:47:12 volumio1 volumio[1159]: Error: ENOTCONN: socket is not connected, lstat '/mnt/USB/TOSHIBA_EXT' May 24 15:47:12 volumio1 volumio[1159]: at Object.lstatSync (fs.js:1120:3) May 24 15:47:12 volumio1 volumio[1159]: at Object.lstatSync (/volumio/node_modules/graceful-fs/polyfills.js:308:16) May 24 15:47:12 volumio1 volumio[1159]: at /myvolumio/plugins/music_service/cd_controller/cd_controller_real:1:14222 May 24 15:47:12 volumio1 volumio[1159]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) May 24 15:47:12 volumio1 volumio[1159]: at FSReqCallback.oncomplete (fs.js:180:23) { May 24 15:47:12 volumio1 volumio[1159]: errno: -107, May 24 15:47:12 volumio1 volumio[1159]: syscall: 'lstat', May 24 15:47:12 volumio1 volumio[1159]: code: 'ENOTCONN', May 24 15:47:12 volumio1 volumio[1159]: path: '/mnt/USB/TOSHIBA_EXT' May 24 15:47:12 volumio1 volumio[1159]: } May 24 15:47:12 volumio1 volumio[1159]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 15:47:12 volumio1 sudo[20724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 15:46 May 24 15:47:12 volumio1 sudo[20724]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:53:51 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="53825f995260683aee752aed6b29f24e"