-- Logs begin at Mon 2025-12-22 19:22:14 +03, end at Wed 2025-12-24 21:28:01 +03. -- Dec 24 21:27:04 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::ClearQueue Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::stPlaybackTimer Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:04 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:04 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::serviceStop Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::serviceStop Dec 24 21:27:04 volumio volumio[1389]: info: [1766600824906] ControllerWebradio::stop Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::clearPlayQueue Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::addQueueItems Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::addQueueItems Dec 24 21:27:04 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:04 volumio volumio[1389]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99592548 Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioPlay Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::play index 0 Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::play index undefined Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:04 volumio volumio[1389]: info: CoreStateMachine::startPlaybackTimer Dec 24 21:27:04 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 21:27:04 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 24 21:27:04 volumio volumio[1389]: info: [1766600824923] ControllerWebradio::clearAddPlayTrack Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:04 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:04 volumio volumio[1389]: info: Dec 24 21:27:04 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:04 volumio volumio[1389]: info: sendMpdCommand stop took 30 milliseconds Dec 24 21:27:04 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:04 volumio volumio[1389]: info: sendMpdCommand stop took 14 milliseconds Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Dec 24 21:27:04 volumio volumio[1389]: info: Dec 24 21:27:04 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:04 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:04 volumio volumio[1389]: info: sendMpdCommand status took 2 milliseconds Dec 24 21:27:04 volumio volumio[1389]: info: sendMpdCommand clear took 1 milliseconds Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99592548" Dec 24 21:27:04 volumio volumio[1389]: error: updateQueue error: null Dec 24 21:27:04 volumio volumio[1389]: info: ------------------------------ 10ms Dec 24 21:27:04 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 24 21:27:04 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:04 volumio volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 24 21:27:04 volumio volumio[1389]: info: ------------------------------ 13ms Dec 24 21:27:05 volumio volumio[1389]: info: Dec 24 21:27:05 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:05 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99592548" took 145 milliseconds Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Dec 24 21:27:05 volumio volumio[1389]: info: ------------------------------ 4ms Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand play took 2 milliseconds Dec 24 21:27:05 volumio volumio[1389]: info: Dec 24 21:27:05 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:05 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - Effects disabled Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - Effects disabled Dec 24 21:27:05 volumio volumio[1389]: info: Dec 24 21:27:05 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand status took 15 milliseconds Dec 24 21:27:05 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand status took 1 milliseconds Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:05 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:05 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:05 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 257/1700) La Noventera","album":null,"uri":"http://161.132.53.12:8043/stream","trackType":""} Dec 24 21:27:05 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Dec 24 21:27:05 volumio volumio[1389]: info: ------------------------------ 24ms Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:05 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:05 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:05 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 257/1700) La Noventera","album":null,"uri":"http://161.132.53.12:8043/stream","trackType":""} Dec 24 21:27:05 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:27:05 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:05 volumio volumio[1389]: info: ------------------------------ 30ms Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 24 21:27:05 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:05 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:05 volumio volumio[1389]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 24 21:27:05 volumio volumio[1389]: info: Dec 24 21:27:05 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:05 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:05 volumio volumio[1389]: info: Dec 24 21:27:05 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:05 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:05 volumio volumio[1389]: info: ------------------------------ 3ms Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand status took 1 milliseconds Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:05 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 24 21:27:05 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:05 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:05 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:05 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1134,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"VALERIA - VICIOSA","artist":"La Noventera","album":null,"uri":"http://161.132.53.12:8043/stream","trackType":""} Dec 24 21:27:05 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:27:05 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:05 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:05 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:05 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:05 volumio volumio[1389]: info: ------------------------------ 29ms Dec 24 21:27:05 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:05 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:07 volumio volumio[1389]: info: Executing endpoint metavolumio Dec 24 21:27:07 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 21:27:07 volumio volumio[1389]: info: Executing endpoint metavolumio Dec 24 21:27:07 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 21:27:08 volumio volumio[1389]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/La%20Noventera/29c1014f-8b22-4aed-8147-ff36aa0fb7e0.jpg' Dec 24 21:27:38 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::ClearQueue Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::stPlaybackTimer Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:38 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:38 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::serviceStop Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::serviceStop Dec 24 21:27:38 volumio volumio[1389]: info: [1766600858226] ControllerWebradio::stop Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::clearPlayQueue Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::addQueueItems Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::addQueueItems Dec 24 21:27:38 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:38 volumio volumio[1389]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139 Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioPlay Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::play index 0 Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::play index undefined Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::startPlaybackTimer Dec 24 21:27:38 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 21:27:38 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 24 21:27:38 volumio volumio[1389]: info: [1766600858261] ControllerWebradio::clearAddPlayTrack Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:38 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:38 volumio volumio[1389]: info: Dec 24 21:27:38 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand stop took 51 milliseconds Dec 24 21:27:38 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand stop took 15 milliseconds Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Dec 24 21:27:38 volumio volumio[1389]: info: Dec 24 21:27:38 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:38 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand status took 3 milliseconds Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand clear took 2 milliseconds Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" Dec 24 21:27:38 volumio volumio[1389]: error: updateQueue error: null Dec 24 21:27:38 volumio volumio[1389]: info: ------------------------------ 3ms Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:38 volumio volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 24 21:27:38 volumio volumio[1389]: info: ------------------------------ 8ms Dec 24 21:27:38 volumio volumio[1389]: info: Dec 24 21:27:38 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:38 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1778139" took 141 milliseconds Dec 24 21:27:38 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 21:27:38 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Dec 24 21:27:38 volumio volumio[1389]: info: ------------------------------ 2ms Dec 24 21:27:38 volumio volumio[1389]: info: sendMpdCommand play took 1 milliseconds Dec 24 21:27:39 volumio volumio[1389]: info: Dec 24 21:27:39 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:39 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - Effects disabled Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - Effects disabled Dec 24 21:27:39 volumio volumio[1389]: info: Dec 24 21:27:39 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:39 volumio volumio[1389]: info: sendMpdCommand status took 13 milliseconds Dec 24 21:27:39 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:39 volumio volumio[1389]: info: sendMpdCommand status took 1 milliseconds Dec 24 21:27:39 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:39 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:39 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:39 volumio volumio[1389]: 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":"chilloutlounge_128","artist":"1.FM - Chillout Lounge (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Dec 24 21:27:39 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Dec 24 21:27:39 volumio volumio[1389]: info: ------------------------------ 31ms Dec 24 21:27:39 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:39 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:39 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:39 volumio volumio[1389]: 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":"chilloutlounge_128","artist":"1.FM - Chillout Lounge (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Dec 24 21:27:39 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:27:39 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:39 volumio volumio[1389]: info: ------------------------------ 51ms Dec 24 21:27:39 volumio volumio[1389]: info: Dec 24 21:27:39 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:39 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:39 volumio volumio[1389]: info: Dec 24 21:27:39 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:39 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 24 21:27:39 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:39 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:39 volumio volumio[1389]: info: ------------------------------ 32ms Dec 24 21:27:39 volumio volumio[1389]: info: sendMpdCommand status took 30 milliseconds Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:39 volumio volumio[1389]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 24 21:27:39 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 24 21:27:39 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:39 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:39 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:39 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Babble - Hold the Sky","artist":"1.FM - Chillout Lounge Radio","album":null,"uri":"http://185.33.21.112:80/chilloutlounge_128","trackType":""} Dec 24 21:27:39 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:27:39 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:39 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:39 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:39 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:39 volumio volumio[1389]: info: ------------------------------ 66ms Dec 24 21:27:39 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:39 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:41 volumio volumio[1389]: info: Executing endpoint metavolumio Dec 24 21:27:41 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 21:27:41 volumio volumio[1389]: info: Executing endpoint metavolumio Dec 24 21:27:41 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 21:27:44 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::ClearQueue Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::stPlaybackTimer Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:44 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:44 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::serviceStop Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::serviceStop Dec 24 21:27:44 volumio volumio[1389]: info: [1766600864076] ControllerWebradio::stop Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::clearPlayQueue Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::addQueueItems Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::addQueueItems Dec 24 21:27:44 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:44 volumio volumio[1389]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978 Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioPlay Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::play index 0 Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::play index undefined Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::startPlaybackTimer Dec 24 21:27:44 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 21:27:44 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 24 21:27:44 volumio volumio[1389]: info: [1766600864098] ControllerWebradio::clearAddPlayTrack Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:44 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:27:44 volumio volumio[1389]: info: Dec 24 21:27:44 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand stop took 48 milliseconds Dec 24 21:27:44 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand stop took 26 milliseconds Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Dec 24 21:27:44 volumio volumio[1389]: info: Dec 24 21:27:44 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:44 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand status took 3 milliseconds Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand clear took 3 milliseconds Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" Dec 24 21:27:44 volumio volumio[1389]: error: updateQueue error: null Dec 24 21:27:44 volumio volumio[1389]: info: Dec 24 21:27:44 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:44 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:44 volumio volumio[1389]: info: ------------------------------ 152ms Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 149 milliseconds Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" took 149 milliseconds Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:44 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 21:27:44 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Dec 24 21:27:44 volumio volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 24 21:27:44 volumio volumio[1389]: info: ------------------------------ 159ms Dec 24 21:27:44 volumio volumio[1389]: info: ------------------------------ 5ms Dec 24 21:27:44 volumio volumio[1389]: info: sendMpdCommand play took 2 milliseconds Dec 24 21:27:46 volumio volumio[1389]: info: Executing endpoint metavolumio Dec 24 21:27:46 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 21:27:46 volumio volumio[1389]: info: Executing endpoint metavolumio Dec 24 21:27:46 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 24 21:27:54 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::ClearQueue Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::clearPlayQueue Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::addQueueItems Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::addQueueItems Dec 24 21:27:54 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:54 volumio volumio[1389]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978 Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::volumioPlay Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::play index 0 Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::play index undefined Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::startPlaybackTimer Dec 24 21:27:54 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 21:27:54 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 24 21:27:54 volumio volumio[1389]: info: [1766600874294] ControllerWebradio::clearAddPlayTrack Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:54 volumio volumio[1389]: info: Dec 24 21:27:54 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:54 volumio volumio[1389]: info: sendMpdCommand stop took 3 milliseconds Dec 24 21:27:54 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Dec 24 21:27:54 volumio volumio[1389]: info: Dec 24 21:27:54 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:54 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:54 volumio volumio[1389]: info: sendMpdCommand status took 1 milliseconds Dec 24 21:27:54 volumio volumio[1389]: info: sendMpdCommand clear took 1 milliseconds Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" Dec 24 21:27:54 volumio volumio[1389]: error: updateQueue error: null Dec 24 21:27:54 volumio volumio[1389]: info: ------------------------------ 2ms Dec 24 21:27:54 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:54 volumio volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 24 21:27:54 volumio volumio[1389]: info: ------------------------------ 4ms Dec 24 21:27:54 volumio volumio[1389]: info: Dec 24 21:27:54 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:54 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:54 volumio volumio[1389]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" took 140 milliseconds Dec 24 21:27:54 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 21:27:54 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Dec 24 21:27:54 volumio volumio[1389]: info: ------------------------------ 2ms Dec 24 21:27:54 volumio volumio[1389]: info: sendMpdCommand play took 1 milliseconds Dec 24 21:27:58 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::ClearQueue Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::clearPlayQueue Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::addQueueItems Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::addQueueItems Dec 24 21:27:58 volumio volumio[1389]: info: Preload queue cleared Dec 24 21:27:58 volumio volumio[1389]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497968 Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::volumioPushQueue Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::saveQueue Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::updateTrackBlock Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::getTrackBlock Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::volumioPlay Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::play index 0 Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::stop Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::play index undefined Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::startPlaybackTimer Dec 24 21:27:58 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 21:27:58 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 24 21:27:58 volumio volumio[1389]: info: [1766600878336] ControllerWebradio::clearAddPlayTrack Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Dec 24 21:27:58 volumio volumio[1389]: info: Dec 24 21:27:58 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:58 volumio volumio[1389]: info: sendMpdCommand stop took 2 milliseconds Dec 24 21:27:58 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Dec 24 21:27:58 volumio volumio[1389]: info: Dec 24 21:27:58 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:58 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:58 volumio volumio[1389]: info: sendMpdCommand status took 2 milliseconds Dec 24 21:27:58 volumio volumio[1389]: info: sendMpdCommand clear took 1 milliseconds Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497968" Dec 24 21:27:58 volumio volumio[1389]: error: updateQueue error: null Dec 24 21:27:58 volumio volumio[1389]: info: ------------------------------ 2ms Dec 24 21:27:58 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:58 volumio volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 24 21:27:58 volumio volumio[1389]: info: ------------------------------ 4ms Dec 24 21:27:58 volumio volumio[1389]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497968" took 138 milliseconds Dec 24 21:27:58 volumio volumio[1389]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 24 21:27:58 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Dec 24 21:27:58 volumio volumio[1389]: info: Dec 24 21:27:58 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:27:58 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:27:58 volumio volumio[1389]: info: sendMpdCommand play took 1 milliseconds Dec 24 21:27:58 volumio volumio[1389]: info: ------------------------------ 1ms Dec 24 21:27:59 volumio volumio[1389]: info: Dec 24 21:27:59 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:59 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - Effects disabled Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 24 21:27:59 volumio volumio[1389]: info: FusionDsp - Effects disabled Dec 24 21:27:59 volumio volumio[1389]: info: Dec 24 21:27:59 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:27:59 volumio volumio[1389]: info: sendMpdCommand status took 14 milliseconds Dec 24 21:27:59 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:59 volumio volumio[1389]: info: sendMpdCommand status took 1 milliseconds Dec 24 21:27:59 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:59 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:59 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:59 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-hamburg","artist":"(#1 - 237/500000) ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} Dec 24 21:27:59 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:59 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:59 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Dec 24 21:27:59 volumio volumio[1389]: info: ------------------------------ 19ms Dec 24 21:27:59 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 24 21:27:59 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:27:59 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:27:59 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:59 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rockantenne-hamburg","artist":"(#1 - 237/500000) ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} Dec 24 21:27:59 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:27:59 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:27:59 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:27:59 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:27:59 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:59 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:59 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:59 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:27:59 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:27:59 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:27:59 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:27:59 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 28ms Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 24 21:28:00 volumio volumio[1389]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:28:00 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:28:00 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:28:00 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 23ms Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand status took 21 milliseconds Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 48ms Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand status took 47 milliseconds Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 48ms Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand status took 23 milliseconds Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:28:00 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:28:00 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1231,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Queen - Heaven For Everyone","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} Dec 24 21:28:00 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:28:00 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 90ms Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 57 milliseconds Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:28:00 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:28:00 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1356,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Queen - Heaven For Everyone","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} Dec 24 21:28:00 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:28:00 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:28:00 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:28:00 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1356,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Queen - Heaven For Everyone","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} Dec 24 21:28:00 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:28:00 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 150ms Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 150ms Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces system playlist update Dec 24 21:28:00 volumio volumio[1389]: info: Ignoring MPD Status Update Dec 24 21:28:00 volumio volumio[1389]: info: Dec 24 21:28:00 volumio volumio[1389]: ---------------------------- MPD announces state update: player Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::getState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 3ms Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand status took 1 milliseconds Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseState Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 24 21:28:00 volumio volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 24 21:28:00 volumio volumio[1389]: verbose: ControllerMpd::parseTrackInfo Dec 24 21:28:00 volumio volumio[1389]: info: ControllerMpd::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::servicePushState Dec 24 21:28:00 volumio volumio[1389]: info: CorePlayQueue::getTrack 0 Dec 24 21:28:00 volumio volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Queen - Heaven For Everyone","artist":"ROCK ANTENNE Hamburg","album":null,"uri":"http://stream.antenne.de:80/rockantenne-hamburg","trackType":""} Dec 24 21:28:00 volumio volumio[1389]: verbose: CURRENT POSITION 0 Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState stateService play Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::syncState currentStatus play Dec 24 21:28:00 volumio volumio[1389]: info: Received an update from plugin. extracting info from payload Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: CoreStateMachine::pushState Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioPushState Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output update for this device Dec 24 21:28:00 volumio volumio[1389]: info: MRS: Pushing multiroomSync output Dec 24 21:28:00 volumio volumio[1389]: info: CoreCommandRouter::volumioGetState Dec 24 21:28:00 volumio volumio[1389]: info: ------------------------------ 22ms Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:00 volumio volumio[1389]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 24 21:28:01 volumio volumio[1389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 21:28:01 volumio volumio[1389]: Error: connect EHOSTUNREACH 146.75.54.53:443 Dec 24 21:28:01 volumio volumio[1389]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 24 21:28:01 volumio volumio[1389]: errno: -113, Dec 24 21:28:01 volumio volumio[1389]: code: 'EHOSTUNREACH', Dec 24 21:28:01 volumio volumio[1389]: syscall: 'connect', Dec 24 21:28:01 volumio volumio[1389]: address: '146.75.54.53', Dec 24 21:28:01 volumio volumio[1389]: port: 443 Dec 24 21:28:01 volumio volumio[1389]: } Dec 24 21:28:01 volumio volumio[1389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 21:28:01 volumio sudo[13433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-24 21:27 Dec 24 21:28:01 volumio sudo[13433]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:03:54 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="35e446ff98e90abcfcab1bb2733dfd3a"