Jan 27 09:49:00 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:00 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:00 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:00 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:00 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 27 09:49:00 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:00 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:00 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:00 yechezkel volumio[1377]: info: Listing playlists Jan 27 09:49:00 yechezkel volumio[1377]: info: Preload queue cleared Jan 27 09:49:01 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:01 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:01 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:01 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:02 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:02 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:02 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:02 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:02 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 27 09:49:02 yechezkel volumio[1377]: info: Preload queue cleared Jan 27 09:49:03 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:03 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:03 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:03 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:04 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:04 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:04 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:04 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:05 yechezkel go-librespot[1822]: time="2026-01-27T09:49:05+02:00" level=trace msg="sent dealer ping" Jan 27 09:49:05 yechezkel go-librespot[1822]: time="2026-01-27T09:49:05+02:00" level=trace msg="received dealer pong" Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: Preload queue cleared Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::ClearQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::stop Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::clearPlayQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::saveQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::addQueueItems Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::addQueueItems Jan 27 09:49:05 yechezkel volumio[1377]: info: Preload queue cleared Jan 27 09:49:05 yechezkel volumio[1377]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s127947 Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::saveQueue Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::updateTrackBlock Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::getTrackBlock Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPlay Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::play index 0 Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::stop Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::play index undefined Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreStateMachine::startPlaybackTimer Jan 27 09:49:05 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetVisibleSources Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 27 09:49:05 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 27 09:49:05 yechezkel volumio[1377]: info: [1769500145850] ControllerWebradio::clearAddPlayTrack Jan 27 09:49:05 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:49:05 yechezkel volumio[1377]: info: sendMpdCommand stop took 0 milliseconds Jan 27 09:49:05 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand clear Jan 27 09:49:05 yechezkel volumio[1377]: info: Jan 27 09:49:05 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:05 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:05 yechezkel volumio[1377]: info: sendMpdCommand clear took 0 milliseconds Jan 27 09:49:05 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand load "http://glzwizzlv.bynetcdn.com/glz_mp3" Jan 27 09:49:05 yechezkel volumio[1377]: info: Jan 27 09:49:05 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:05 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:05 yechezkel volumio[1377]: info: Jan 27 09:49:05 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:05 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:05 yechezkel volumio[1377]: info: Jan 27 09:49:05 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:05 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:05 yechezkel volumio[1377]: info: Jan 27 09:49:05 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:05 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:05 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:05 yechezkel volumio[1377]: info: ------------------------------ 2ms Jan 27 09:49:06 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:06 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:06 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:06 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand add "http://glzwizzlv.bynetcdn.com/glz_mp3" Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 284ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 284ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 284ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 283ms Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand add "http://glzwizzlv.bynetcdn.com/glz_mp3" took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand play Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 2ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand play took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 6 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 8 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 11ms Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 3 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 29ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 28ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 28ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 28ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 21ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 20ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: 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":"glz_mp3","artist":null,"album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 29ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 29ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 29ms Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:06 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:06 yechezkel volumio[1377]: info: Jan 27 09:49:06 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 2ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 2ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 3ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"glz_mp3","artist":"glz","album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"glz_mp3","artist":"glz","album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"glz_mp3","artist":"glz","album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"glz_mp3","artist":"glz","album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 19ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 19ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 18ms Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 18ms Jan 27 09:49:06 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 27 09:49:06 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:06 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"glz_mp3","artist":"glz","album":null,"uri":"http://glzwizzlv.bynetcdn.com/glz_mp3","trackType":""} Jan 27 09:49:06 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:06 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:06 yechezkel volumio[1377]: info: ------------------------------ 22ms Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:06 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:07 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:07 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:07 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:07 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:08 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:08 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:08 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:08 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:09 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:09 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:09 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:09 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:10 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:10 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:10 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:10 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:11 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:11 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:11 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:11 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:12 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:12 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:12 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:12 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:13 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:13 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:13 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:13 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:14 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:14 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:14 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:14 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioStop Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::stop Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::updateTrackBlock Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrackBlock Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::serviceStop Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::serviceStop Jan 27 09:49:15 yechezkel volumio[1377]: info: [1769500155582] ControllerWebradio::stop Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand stop Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand stop took 7 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: Jan 27 09:49:15 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:15 yechezkel volumio[1377]: info: Jan 27 09:49:15 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:15 yechezkel volumio[1377]: info: Jan 27 09:49:15 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:15 yechezkel volumio[1377]: info: Jan 27 09:49:15 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:15 yechezkel volumio[1377]: info: Jan 27 09:49:15 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand status took 4 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand status took 4 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand status took 3 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 27 09:49:15 yechezkel volumio[1377]: info: ------------------------------ 9ms Jan 27 09:49:15 yechezkel volumio[1377]: info: ------------------------------ 9ms Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:15 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 27 09:49:15 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:15 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:15 yechezkel volumio[1377]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 27 09:49:15 yechezkel volumio[1377]: info: ------------------------------ 14ms Jan 27 09:49:15 yechezkel volumio[1377]: info: ------------------------------ 13ms Jan 27 09:49:15 yechezkel volumio[1377]: info: ------------------------------ 11ms Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:15 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:24 yechezkel systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 27 09:49:24 yechezkel systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 27 09:49:24 yechezkel systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 09:49:24 yechezkel volumio[1377]: info: Retrieving Cloud Streaming UI Jan 27 09:49:24 yechezkel volumio[1377]: info: Getting Tidal Cloud Configuration Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 09:49:24 yechezkel volumio[1377]: info: Getting Qobuz Cloud Configuration Jan 27 09:49:24 yechezkel volumio[1377]: info: Asking plugin for UI Config Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 09:49:24 yechezkel volumio[1377]: info: Getting Spotify Cloud Configuration Jan 27 09:49:24 yechezkel volumio[1377]: info: Asking plugin for UI Config Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 09:49:24 yechezkel volumio[1377]: info: Saving Spotify Acccount Jan 27 09:49:24 yechezkel volumio[1377]: info: Got it Jan 27 09:49:24 yechezkel volumio[1377]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 27 09:49:24 yechezkel volumio[1377]: info: Got Tidal Cloud Configuration Jan 27 09:49:24 yechezkel volumio[1377]: info: Got it Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 09:49:24 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetBrowseSources Jan 27 09:49:25 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:25 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 27 09:49:29 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:33 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:35 yechezkel go-librespot[1822]: time="2026-01-27T09:49:35+02:00" level=trace msg="sent dealer ping" Jan 27 09:49:35 yechezkel go-librespot[1822]: time="2026-01-27T09:49:35+02:00" level=trace msg="received dealer pong" Jan 27 09:49:37 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:41 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:45 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:49 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:52 yechezkel volumio[1377]: info: Clearing queue after UPNP request Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreStateMachine::ClearQueue Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreStateMachine::stop Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:49:52 yechezkel volumio[1377]: info: CorePlayQueue::clearPlayQueue Jan 27 09:49:52 yechezkel volumio[1377]: info: CorePlayQueue::saveQueue Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushQueue Jan 27 09:49:52 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:52 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:52 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:52 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:52 yechezkel volumio[1377]: info: Jan 27 09:49:52 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:52 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:52 yechezkel volumio[1377]: info: Jan 27 09:49:52 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:52 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:52 yechezkel volumio[1377]: info: Jan 27 09:49:52 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:52 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:52 yechezkel volumio[1377]: info: Jan 27 09:49:52 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:52 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:52 yechezkel volumio[1377]: info: Jan 27 09:49:52 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:52 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:52 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:52 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:52 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:52 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:52 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:52 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:52 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:52 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:52 yechezkel volumio[1377]: error: updateQueue error: null Jan 27 09:49:52 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:52 yechezkel volumio[1377]: info: Starting UPNP Playback Jan 27 09:49:52 yechezkel volumio[1377]: info: Preparing playback through UPNP Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:52 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:49:52 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 2ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 2ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 1ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces system playlist update Jan 27 09:49:53 yechezkel volumio[1377]: info: Ignoring MPD Status Update Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 0ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 14 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 14 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 14 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 13 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus stop Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 17ms Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: Jan 27 09:49:53 yechezkel volumio[1377]: ---------------------------- MPD announces state update: player Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::getState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand status Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 2 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand status took 1 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel fsct-plugin[1377]: Playback notifications stream lagged 18 events. Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 42ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 42ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 48ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 48ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 37ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 36ms Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: verbose: ControllerMpd::parseTrackInfo Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":103,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: info: ControllerMpd::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::servicePushState Jan 27 09:49:53 yechezkel volumio[1377]: verbose: In UPNP mode Jan 27 09:49:53 yechezkel volumio[1377]: verbose: STATE SERVICE {"status":"play","position":0,"seek":111,"duration":308,"samplerate":"5.64 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"11289 Kbps","isStreaming":false,"title":"Purpose","artist":"Fabiano Do Nascimento","album":"Aquáticos","uri":"http://10.0.0.10:49155/audirvana/audio_f_2.dff","trackType":"dff"} Jan 27 09:49:53 yechezkel volumio[1377]: verbose: CURRENT POSITION 0 Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState stateService play Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::syncState currentStatus play Jan 27 09:49:53 yechezkel volumio[1377]: info: Received an update from plugin. extracting info from payload Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:49:53 yechezkel volumio[1377]: error: ControllerMpd::pushError: Error: channel closed Jan 27 09:49:53 yechezkel volumio[1377]: error: ControllerMpd::pushError: Error: channel closed Jan 27 09:49:53 yechezkel volumio[1377]: error: ControllerMpd::pushError: Error: channel closed Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 45ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 45ms Jan 27 09:49:53 yechezkel volumio[1377]: info: ------------------------------ 44ms Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: SPOTIFY VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Jan 27 09:49:53 yechezkel volumio[1377]: SPOTIFY: DELTA VOLUME ENOUGH: false Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:53 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:54 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:54 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:54 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:54 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:55 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:55 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:55 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:55 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:56 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:56 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:56 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:56 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:57 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:57 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:57 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:57 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:57 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:49:58 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:58 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:58 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:58 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 27 09:49:59 yechezkel volumio[1377]: info: Received Get System Info Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 27 09:49:59 yechezkel volumio[1377]: info: Discovery: Getting this device information Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:49:59 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:49:59 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:00 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:00 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:00 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:00 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:01 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:01 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:01 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:01 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:01 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:50:02 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:02 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:02 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:02 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:03 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:03 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:03 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:03 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:04 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:04 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:04 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:04 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:05 yechezkel go-librespot[1822]: time="2026-01-27T09:50:05+02:00" level=trace msg="sent dealer ping" Jan 27 09:50:05 yechezkel go-librespot[1822]: time="2026-01-27T09:50:05+02:00" level=trace msg="received dealer pong" Jan 27 09:50:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:05 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:05 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:05 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:05 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:50:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:06 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:06 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:06 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:07 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:07 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:07 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:07 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:08 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:08 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:08 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:08 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:09 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:09 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:09 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:09 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:09 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:50:10 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:10 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:10 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:10 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:11 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:11 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:11 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:11 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:12 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:12 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:12 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:12 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:13 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:13 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:13 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:13 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:13 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:50:14 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:14 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:14 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:14 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:15 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:15 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:15 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:16 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:16 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:16 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:16 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:17 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetState Jan 27 09:50:17 yechezkel volumio[1377]: info: CoreCommandRouter::volumioGetQueue Jan 27 09:50:17 yechezkel volumio[1377]: info: CoreStateMachine::getQueue Jan 27 09:50:17 yechezkel volumio[1377]: info: CorePlayQueue::getQueue Jan 27 09:50:17 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 27 09:50:18 yechezkel volumio[1377]: info: Clearing queue after UPNP request Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreStateMachine::ClearQueue Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreStateMachine::stop Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreStateMachine::stPlaybackTimer Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreStateMachine::updateTrackBlock Jan 27 09:50:18 yechezkel volumio[1377]: info: CorePlayQueue::getTrackBlock Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreStateMachine::pushState Jan 27 09:50:18 yechezkel volumio[1377]: info: CorePlayQueue::getTrack 0 Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 09:50:18 yechezkel volumio[1377]: info: CoreCommandRouter::volumioPushState Jan 27 09:50:18 yechezkel volumio[1377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 09:50:18 yechezkel volumio[1377]: Error: channel closed Jan 27 09:50:18 yechezkel volumio[1377]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:71:12) Jan 27 09:50:18 yechezkel volumio[1377]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Jan 27 09:50:18 yechezkel volumio[1377]: at /volumio/app/index.js:326:81 Jan 27 09:50:18 yechezkel volumio[1377]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jan 27 09:50:18 yechezkel volumio[1377]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jan 27 09:50:18 yechezkel volumio[1377]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Jan 27 09:50:18 yechezkel volumio[1377]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Jan 27 09:50:18 yechezkel volumio[1377]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Jan 27 09:50:18 yechezkel volumio[1377]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Jan 27 09:50:18 yechezkel volumio[1377]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:293:37) Jan 27 09:50:18 yechezkel volumio[1377]: at listOnTimeout (node:internal/timers:573:17) Jan 27 09:50:18 yechezkel volumio[1377]: at process.processTimers (node:internal/timers:514:7) { Jan 27 09:50:18 yechezkel volumio[1377]: code: 'GenericFailure' Jan 27 09:50:18 yechezkel volumio[1377]: } Jan 27 09:50:18 yechezkel volumio[1377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 09:50:18 yechezkel sudo[21843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 09:49' Jan 27 09:50:18 yechezkel sudo[21843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"