Dec 27 16:09:00 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 27 16:09:02 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:06 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:08 volumio-nano volumio[4174]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 27 16:09:08 volumio-nano volumio[4174]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 27 16:09:08 volumio-nano volumio[4174]: info: Received Get System Version Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 16:09:08 volumio-nano volumio[4174]: info: Received Get System Info Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 27 16:09:08 volumio-nano volumio[4174]: info: Discovery: Getting this device information Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:08 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 16:09:10 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:12 volumio-nano volumio[4174]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] Dec 27 16:09:12 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions Dec 27 16:09:14 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:18 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:22 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:26 volumio-nano volumio[4174]: info: CALLMETHOD: miscellanea my_music updateMusicLibraryBrowseSourcesVisibility [object Object] Dec 27 16:09:26 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibraryBrowseSourcesVisibility Dec 27 16:09:26 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioUpdateToBrowseSources Dec 27 16:09:26 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 16:09:26 volumio-nano volumio[4174]: Cannot find translation for source QOBUZ Dec 27 16:09:26 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:30 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 27 16:09:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:34 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:09:34 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:09:35 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetQueue Dec 27 16:09:35 volumio-nano volumio[4174]: info: CoreStateMachine::getQueue Dec 27 16:09:35 volumio-nano volumio[4174]: info: CorePlayQueue::getQueue Dec 27 16:09:39 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 16:09:39 volumio-nano volumio[4174]: info: CURURI: artists:// Dec 27 16:09:40 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:09:43 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 16:09:43 volumio-nano volumio[4174]: info: CURURI: artists://30%20Seconds%20To%20Mars Dec 27 16:09:43 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:09:43 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:09:43 volumio-nano volumio[4174]: info: Preloading song: music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac Dec 27 16:09:43 volumio-nano volumio[4174]: info: Preloading song: music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac Dec 27 16:09:43 volumio-nano volumio[4174]: info: Preloading song: music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/23 Love Is Madness.flac Dec 27 16:09:43 volumio-nano volumio[4174]: info: Exploding uri music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac in service mpd Dec 27 16:09:43 volumio-nano volumio[4174]: info: ALBUMART /albumart?cacheid=943&web=30%20Seconds%20To%20Mars/It%E2%80%99s%20The%20End%20Of%20The%20World%20But%20It%E2%80%99s%20A%20Beautiful%20Day/medium&path=%2Fmnt%2FNAS%2FArchiv_Audio%2FPlaylist%2F%231-MixQuer%2F25%20Stuck.flac&metadata=false Dec 27 16:09:43 volumio-nano volumio[4174]: info: URI /mnt/NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac Dec 27 16:09:43 volumio-nano volumio[4174]: info: Exploding uri music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac in service mpd Dec 27 16:09:43 volumio-nano volumio[4174]: info: ALBUMART /albumart?cacheid=943&web=30%20Seconds%20To%20Mars/It%E2%80%99s%20The%20End%20Of%20The%20World%20But%20It%E2%80%99s%20A%20Beautiful%20Day/medium&path=%2Fmnt%2FNAS%2FArchiv_Audio%2FPlaylist%2F%231-MixQuer%2F24%207_1.flac&metadata=false Dec 27 16:09:43 volumio-nano volumio[4174]: info: URI /mnt/NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac Dec 27 16:09:43 volumio-nano volumio[4174]: info: Exploding uri music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/23 Love Is Madness.flac in service mpd Dec 27 16:09:43 volumio-nano volumio[4174]: info: ALBUMART /albumart?cacheid=943&web=30%20Seconds%20To%20Mars/AMERICA/medium&path=%2Fmnt%2FNAS%2FArchiv_Audio%2FPlaylist%2F%231-MixQuer%2F23%20Love%20Is%20Madness.flac&metadata=false Dec 27 16:09:43 volumio-nano volumio[4174]: info: URI /mnt/NAS/Archiv_Audio/Playlist/#1-MixQuer/23 Love Is Madness.flac Dec 27 16:09:46 volumio-nano volumio[4174]: info: Executing endpoint getSimilarArtists Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 27 16:09:46 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::ClearQueue Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::serviceStop Dec 27 16:09:46 volumio-nano volumio[4174]: info: Received STOP, but no service to execute it Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::clearPlayQueue Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::saveQueue Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::addQueueItems Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::addQueueItems Dec 27 16:09:46 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:09:46 volumio-nano volumio[4174]: info: Adding Item to queue: artists://30%20Seconds%20To%20Mars/It%E2%80%99s%20The%20End%20Of%20The%20World%20But%20It%E2%80%99s%20A%20Beautiful%20Day Dec 27 16:09:46 volumio-nano volumio[4174]: info: Exploding uri artists://30%20Seconds%20To%20Mars/It%E2%80%99s%20The%20End%20Of%20The%20World%20But%20It%E2%80%99s%20A%20Beautiful%20Day in service mpd Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushQueue Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::saveQueue Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPlay Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: UNSET VOLATILE: Service: undefined Dec 27 16:09:46 volumio-nano volumio[4174]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 27 16:09:46 volumio-nano volumio[4174]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 27 16:09:46 volumio-nano volumio[4174]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::play index 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:09:46 volumio-nano volumio[4174]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::resetVolumioState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::getcurrentVolume Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioStop Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::clearAddPlayTracks NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand stop took 5 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 16:09:46 volumio-nano volumio[4174]: info: Dec 27 16:09:46 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:09:46 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand clear took 1 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac" Dec 27 16:09:46 volumio-nano volumio[4174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 27 16:09:46 volumio-nano volumio[4174]: error: updateQueue error: null Dec 27 16:09:46 volumio-nano volumio[4174]: info: Dec 27 16:09:46 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:09:46 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:09:46 volumio-nano volumio[4174]: info: ------------------------------ 12ms Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac" took 11 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:09:46 volumio-nano volumio[4174]: info: ------------------------------ 4ms Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand play took 2 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: info: VolumeController:: Volume=undefined Mute =false Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:09:46 volumio-nano volumio[4174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: VolumeController:: Volume=undefined Mute =false Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:09:46 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:09:46 volumio-nano volumio[4174]: info: Dec 27 16:09:46 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:09:46 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:09:46 volumio-nano volumio[4174]: info: Dec 27 16:09:46 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand status took 4 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:09:46 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand status took 10 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:09:46 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:09:46 volumio-nano volumio[4174]: info: ------------------------------ 19ms Dec 27 16:09:46 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:09:46 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:09:46 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:09:46 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:46 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:46 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:46 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:46 volumio-nano volumio[4174]: info: ------------------------------ 76ms Dec 27 16:09:47 volumio-nano volumio[4174]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/30%20Seconds%20To%20Mars/It%E2%80%99s%20The%20End%20Of%20The%20World%20But%20It%E2%80%99s%20A%20Beautiful%20Day/f067a350-b653-4e6d-b610-dbe8fac8c97c.jpg' Dec 27 16:09:47 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:09:47 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:09:47 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:09:47 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:09:47 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:09:48 volumio-nano volumio[4174]: info: Tunnel connection is inactive, restarting it Dec 27 16:09:48 volumio-nano volumio[4174]: info: Starting Tunnel 1 Dec 27 16:09:48 volumio-nano volumio[4174]: info: Starting Tunnel Connection Checker Dec 27 16:09:49 volumio-nano sudo[5355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 27 16:09:49 volumio-nano sudo[5355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 16:09:49 volumio-nano systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 27 16:09:49 volumio-nano sudo[5355]: pam_unix(sudo:session): session closed for user root Dec 27 16:09:49 volumio-nano volumio[4174]: info: Remote SSH Started Dec 27 16:09:49 volumio-nano autossh[5358]: port set to 0, monitoring disabled Dec 27 16:09:49 volumio-nano autossh[5358]: starting ssh (count 1) Dec 27 16:09:49 volumio-nano autossh[5358]: ssh child pid is 5361 Dec 27 16:09:49 volumio-nano volumio[4174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 27 16:09:49 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:49 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:49 volumio-nano volumiossh-tunnel[5361]: Warning: Permanently added '[eu7.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 27 16:09:51 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PLAYPAUSE TRIGGERED Dec 27 16:09:51 volumio-nano volumio[4174]: info: gpio-buttons - GPIO-Buttons: playPause triggered Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:51 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPause Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::pause Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::servicePause Dec 27 16:09:51 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePause Dec 27 16:09:51 volumio-nano volumio[4174]: info: ControllerMpd::pause Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand pause Dec 27 16:09:51 volumio-nano volumio[4174]: info: Dec 27 16:09:51 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:09:51 volumio-nano volumio[4174]: info: sendMpdCommand pause took 3 milliseconds Dec 27 16:09:51 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:09:51 volumio-nano volumio[4174]: info: sendMpdCommand status took 6 milliseconds Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:09:51 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:09:51 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:09:51 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":7111,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1068 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:09:51 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService pause Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus pause Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:51 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:51 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:51 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:51 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:51 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:09:51 volumio-nano volumio[4174]: info: ------------------------------ 38ms Dec 27 16:09:51 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:09:51 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:09:53 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PLAYPAUSE TRIGGERED Dec 27 16:09:53 volumio-nano volumio[4174]: info: gpio-buttons - GPIO-Buttons: playPause triggered Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPlay Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: ControllerMpd::resume Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:09:53 volumio-nano volumio[4174]: info: Dec 27 16:09:53 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:09:53 volumio-nano volumio[4174]: info: sendMpdCommand play took 11 milliseconds Dec 27 16:09:53 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:09:53 volumio-nano volumio[4174]: info: sendMpdCommand status took 6 milliseconds Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:09:53 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:09:53 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":8359,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1598 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:09:53 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus pause Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:53 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:53 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:53 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:53 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:53 volumio-nano volumio[4174]: info: ------------------------------ 37ms Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:09:53 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:09:53 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:09:57 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PREVIOUS TRIGGERED Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPrevious Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreStateMachine::previous Dec 27 16:09:57 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:57 volumio-nano volumio[4174]: info: ControllerMpd::seek Dec 27 16:09:57 volumio-nano volumio[4174]: error: null Dec 27 16:09:57 volumio-nano volumio[4174]: info: Dec 27 16:09:57 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:09:57 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:09:57 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:09:57 volumio-nano volumio[4174]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:09:57 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:09:57 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:09:57 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:09:57 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:09:57 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:09:57 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:57 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1133 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:09:57 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:09:57 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:57 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:57 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:57 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:57 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:09:57 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:09:57 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:09:57 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:09:57 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:09:57 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:09:57 volumio-nano volumio[4174]: info: ------------------------------ 60ms Dec 27 16:09:57 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:09:57 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:01 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::serviceStop Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::serviceStop Dec 27 16:10:01 volumio-nano volumio[4174]: info: ControllerMpd::stop Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:01 volumio-nano volumio[4174]: info: Dec 27 16:10:01 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand stop took 30 milliseconds Dec 27 16:10:01 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::clearAddPlayTracks NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand stop took 1 milliseconds Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 16:10:01 volumio-nano volumio[4174]: info: Dec 27 16:10:01 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:01 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand clear took 2 milliseconds Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" Dec 27 16:10:01 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService stop Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: No code Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:01 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:01 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:01 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:01 volumio-nano volumio[4174]: info: ------------------------------ 59ms Dec 27 16:10:01 volumio-nano volumio[4174]: error: updateQueue error: null Dec 27 16:10:01 volumio-nano volumio[4174]: info: Dec 27 16:10:01 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:01 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:01 volumio-nano volumio[4174]: info: ------------------------------ 55ms Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" took 54 milliseconds Dec 27 16:10:01 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:01 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:01 volumio-nano volumio[4174]: info: ------------------------------ 34ms Dec 27 16:10:01 volumio-nano volumio[4174]: info: sendMpdCommand play took 33 milliseconds Dec 27 16:10:02 volumio-nano volumio[4174]: info: Dec 27 16:10:02 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:02 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:10:02 volumio-nano volumio[4174]: info: Dec 27 16:10:02 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:02 volumio-nano volumio[4174]: info: sendMpdCommand status took 11 milliseconds Dec 27 16:10:02 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:02 volumio-nano volumio[4174]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:10:02 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:02 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:02 volumio-nano volumio[4174]: info: ------------------------------ 17ms Dec 27 16:10:02 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:02 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1368 Kbps","isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:02 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:02 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:02 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:02 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:02 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:02 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:02 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:02 volumio-nano volumio[4174]: info: ------------------------------ 49ms Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:02 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:02 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:04 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::serviceStop Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::serviceStop Dec 27 16:10:04 volumio-nano volumio[4174]: info: ControllerMpd::stop Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:04 volumio-nano volumio[4174]: info: Dec 27 16:10:04 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:04 volumio-nano volumio[4174]: info: sendMpdCommand stop took 32 milliseconds Dec 27 16:10:04 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 2 Dec 27 16:10:04 volumio-nano volumio[4174]: info: sendMpdCommand status took 5 milliseconds Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:04 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:04 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:04 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService stop Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: No code Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:04 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:04 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:04 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:04 volumio-nano volumio[4174]: info: ------------------------------ 72ms Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:04 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:09 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PREVIOUS TRIGGERED Dec 27 16:10:09 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPrevious Dec 27 16:10:09 volumio-nano volumio[4174]: info: CoreStateMachine::previous Dec 27 16:10:11 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::clearAddPlayTracks NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand stop took 1 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 16:10:11 volumio-nano volumio[4174]: info: Dec 27 16:10:11 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:11 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand clear took 1 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" Dec 27 16:10:11 volumio-nano volumio[4174]: error: updateQueue error: null Dec 27 16:10:11 volumio-nano volumio[4174]: info: Dec 27 16:10:11 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:11 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:11 volumio-nano volumio[4174]: info: ------------------------------ 2ms Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" took 1 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:10:11 volumio-nano volumio[4174]: info: ------------------------------ 1ms Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand play took 1 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: info: Dec 27 16:10:11 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:11 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:10:11 volumio-nano volumio[4174]: info: Dec 27 16:10:11 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand status took 10 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand status took 2 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:11 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:11 volumio-nano volumio[4174]: info: ------------------------------ 17ms Dec 27 16:10:11 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:11 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1368 Kbps","isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:11 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:11 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:11 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:11 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:11 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:11 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:11 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:11 volumio-nano volumio[4174]: info: ------------------------------ 56ms Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:11 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:11 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:17 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PREVIOUS TRIGGERED Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPrevious Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreStateMachine::previous Dec 27 16:10:17 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:17 volumio-nano volumio[4174]: info: ControllerMpd::seek Dec 27 16:10:17 volumio-nano volumio[4174]: error: null Dec 27 16:10:17 volumio-nano volumio[4174]: info: Dec 27 16:10:17 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:17 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:17 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:17 volumio-nano volumio[4174]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:10:17 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:17 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:17 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:10:17 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:17 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:17 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:17 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1429 Kbps","isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:17 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:17 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:17 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:17 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:17 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:17 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:17 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:17 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:17 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:17 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:17 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:17 volumio-nano volumio[4174]: info: ------------------------------ 56ms Dec 27 16:10:17 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:17 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:22 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::serviceStop Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::serviceStop Dec 27 16:10:22 volumio-nano volumio[4174]: info: ControllerMpd::stop Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:22 volumio-nano volumio[4174]: info: Dec 27 16:10:22 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:22 volumio-nano volumio[4174]: info: sendMpdCommand stop took 31 milliseconds Dec 27 16:10:22 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 2 Dec 27 16:10:22 volumio-nano volumio[4174]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:22 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:22 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:22 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService stop Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: No code Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:22 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:22 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:22 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:22 volumio-nano volumio[4174]: info: ------------------------------ 67ms Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:22 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:27 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::clearAddPlayTracks NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand stop took 2 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 16:10:27 volumio-nano volumio[4174]: info: Dec 27 16:10:27 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:27 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand clear took 1 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" Dec 27 16:10:27 volumio-nano volumio[4174]: error: updateQueue error: null Dec 27 16:10:27 volumio-nano volumio[4174]: info: Dec 27 16:10:27 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:27 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:27 volumio-nano volumio[4174]: info: ------------------------------ 2ms Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" took 1 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:10:27 volumio-nano volumio[4174]: info: ------------------------------ 1ms Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand play took 1 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: info: Dec 27 16:10:27 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:27 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:10:27 volumio-nano volumio[4174]: info: Dec 27 16:10:27 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand status took 11 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:27 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:27 volumio-nano volumio[4174]: info: ------------------------------ 18ms Dec 27 16:10:27 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:27 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1368 Kbps","isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:27 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:27 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:27 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:27 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:27 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:27 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:27 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:27 volumio-nano volumio[4174]: info: ------------------------------ 53ms Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:27 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:27 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:30 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::serviceStop Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::serviceStop Dec 27 16:10:30 volumio-nano volumio[4174]: info: ControllerMpd::stop Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:30 volumio-nano volumio[4174]: info: Dec 27 16:10:30 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:30 volumio-nano volumio[4174]: info: sendMpdCommand stop took 29 milliseconds Dec 27 16:10:30 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 2 Dec 27 16:10:30 volumio-nano volumio[4174]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:30 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:30 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:30 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService stop Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: No code Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:30 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:30 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:30 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:30 volumio-nano volumio[4174]: info: ------------------------------ 61ms Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:30 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:32 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PLAYPAUSE TRIGGERED Dec 27 16:10:32 volumio-nano volumio[4174]: info: gpio-buttons - GPIO-Buttons: playPause triggered Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPlay Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::clearAddPlayTracks NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand stop took 0 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 16:10:32 volumio-nano volumio[4174]: info: Dec 27 16:10:32 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:32 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand clear took 0 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac" Dec 27 16:10:32 volumio-nano volumio[4174]: error: updateQueue error: null Dec 27 16:10:32 volumio-nano volumio[4174]: info: Dec 27 16:10:32 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:32 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:32 volumio-nano volumio[4174]: info: ------------------------------ 2ms Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac" took 1 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:10:32 volumio-nano volumio[4174]: info: ------------------------------ 2ms Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand play took 1 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: info: Dec 27 16:10:32 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:32 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:32 volumio-nano volumio[4174]: info: Dec 27 16:10:32 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:32 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand status took 8 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:32 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1103 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:32 volumio-nano volumio[4174]: info: ------------------------------ 27ms Dec 27 16:10:32 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:32 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1103 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:10:32 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:32 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:32 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:32 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:32 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:32 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:32 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:32 volumio-nano volumio[4174]: info: ------------------------------ 64ms Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:32 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:10:32 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:33 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:33 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:36 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PREVIOUS TRIGGERED Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPrevious Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreStateMachine::previous Dec 27 16:10:36 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:36 volumio-nano volumio[4174]: info: ControllerMpd::seek Dec 27 16:10:36 volumio-nano volumio[4174]: error: null Dec 27 16:10:36 volumio-nano volumio[4174]: info: Dec 27 16:10:36 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:36 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:36 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:36 volumio-nano volumio[4174]: info: sendMpdCommand status took 1 milliseconds Dec 27 16:10:36 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:36 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:36 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:36 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:36 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:36 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:36 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"912 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:10:36 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:36 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:36 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:36 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:36 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:36 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:36 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:36 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:36 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:36 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:36 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:36 volumio-nano volumio[4174]: info: ------------------------------ 65ms Dec 27 16:10:36 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:36 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:39 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 PREVIOUS TRIGGERED Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPrevious Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreStateMachine::previous Dec 27 16:10:39 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:39 volumio-nano volumio[4174]: info: ControllerMpd::seek Dec 27 16:10:39 volumio-nano volumio[4174]: error: null Dec 27 16:10:39 volumio-nano volumio[4174]: info: Dec 27 16:10:39 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:39 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:39 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:39 volumio-nano volumio[4174]: info: sendMpdCommand status took 0 milliseconds Dec 27 16:10:39 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:39 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:39 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 27 16:10:39 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:39 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:39 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:39 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":183,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"908 Kbps","isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:10:39 volumio-nano volumio[4174]: verbose: CURRENT POSITION 0 Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:39 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:39 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:39 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:39 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:39 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:39 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:39 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:40 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:40 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:40 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:40 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:40 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:40 volumio-nano volumio[4174]: info: ------------------------------ 54ms Dec 27 16:10:40 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:40 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:41 volumio-nano volumio[4174]: info: gpio-buttons - 🔥 NEXT TRIGGERED Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioNext Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::next Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::stop Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::stPlaybackTimer Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::serviceStop Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 0 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::serviceStop Dec 27 16:10:41 volumio-nano volumio[4174]: info: ControllerMpd::stop Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:41 volumio-nano volumio[4174]: info: Dec 27 16:10:41 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand stop took 27 milliseconds Dec 27 16:10:41 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::play index undefined Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::startPlaybackTimer Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetVisibleSources Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::clearAddPlayTracks NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::updateTrackBlock Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrackBlock Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand stop took 2 milliseconds Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 16:10:41 volumio-nano volumio[4174]: info: Dec 27 16:10:41 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:41 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand clear took 2 milliseconds Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" Dec 27 16:10:41 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stuck","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/25 Stuck.flac","trackType":"flac"} Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService stop Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: No code Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:41 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:41 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:41 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:41 volumio-nano volumio[4174]: info: ------------------------------ 66ms Dec 27 16:10:41 volumio-nano volumio[4174]: error: updateQueue error: null Dec 27 16:10:41 volumio-nano volumio[4174]: info: Dec 27 16:10:41 volumio-nano volumio[4174]: ---------------------------- MPD announces system playlist update Dec 27 16:10:41 volumio-nano volumio[4174]: info: Ignoring MPD Status Update Dec 27 16:10:41 volumio-nano volumio[4174]: info: ------------------------------ 61ms Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand add "NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac" took 60 milliseconds Dec 27 16:10:41 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand play Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Volumio is not playing Dec 27 16:10:41 volumio-nano volumio[4174]: info: FusionDsp - Clipped samples monitor stopped Dec 27 16:10:41 volumio-nano volumio[4174]: info: ------------------------------ 27ms Dec 27 16:10:41 volumio-nano volumio[4174]: info: sendMpdCommand play took 26 milliseconds Dec 27 16:10:42 volumio-nano volumio[4174]: info: Dec 27 16:10:42 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:42 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - Crossfeed selected : chumoy Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - Nulleq applied Dec 27 16:10:42 volumio-nano volumio[4174]: info: Dec 27 16:10:42 volumio-nano volumio[4174]: ---------------------------- MPD announces state update: player Dec 27 16:10:42 volumio-nano volumio[4174]: info: sendMpdCommand status took 6 milliseconds Dec 27 16:10:42 volumio-nano volumio[4174]: info: ControllerMpd::getState Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand status Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:42 volumio-nano volumio[4174]: info: sendMpdCommand status took 3 milliseconds Dec 27 16:10:42 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::parseState Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:42 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus stop Dec 27 16:10:42 volumio-nano volumio[4174]: info: ------------------------------ 12ms Dec 27 16:10:42 volumio-nano volumio[4174]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: ControllerMpd::parseTrackInfo Dec 27 16:10:42 volumio-nano volumio[4174]: info: ControllerMpd::pushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::servicePushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":165,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1319 Kbps","isStreaming":false,"title":"7:1","artist":"30 Seconds To Mars","album":"It’s The End Of The World But It’s A Beautiful Day","uri":"NAS/Archiv_Audio/Playlist/#1-MixQuer/24 7_1.flac","trackType":"flac"} Dec 27 16:10:42 volumio-nano volumio[4174]: verbose: CURRENT POSITION 1 Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreStateMachine::syncState stateService play Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreStateMachine::syncState currentStatus play Dec 27 16:10:42 volumio-nano volumio[4174]: info: Received an update from plugin. extracting info from payload Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:42 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreStateMachine::pushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioPushState Dec 27 16:10:42 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output update for this device Dec 27 16:10:42 volumio-nano volumio[4174]: info: MRS: Pushing multiroomSync output Dec 27 16:10:42 volumio-nano volumio[4174]: info: CoreCommandRouter::volumioGetState Dec 27 16:10:42 volumio-nano volumio[4174]: info: CorePlayQueue::getTrack 1 Dec 27 16:10:42 volumio-nano volumio[4174]: info: ------------------------------ 47ms Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:42 volumio-nano volumio[4174]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - Volumio is playing Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:42 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:44 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:44 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:44 volumio-nano volumio[4174]: info: FusionDsp - Clipping Monitor started Dec 27 16:10:45 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 16:10:45 volumio-nano volumio[4174]: info: CURURI: artists://30%20Seconds%20To%20Mars/AMERICA Dec 27 16:10:45 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:10:45 volumio-nano volumio[4174]: info: Executing endpoint getSimilarAlbums Dec 27 16:10:45 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 27 16:10:46 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:10:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:10:46 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:10:46 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:10:54 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 16:10:54 volumio-nano volumio[4174]: info: CURURI: artists://Ayron%20Jones Dec 27 16:10:55 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:10:55 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:10:55 volumio-nano volumio[4174]: info: Preloading song: music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/04 Boys From The Puget Sound.flac Dec 27 16:10:55 volumio-nano volumio[4174]: info: Preloading song: music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/05 Strawman.flac Dec 27 16:10:55 volumio-nano volumio[4174]: info: Executing endpoint getSimilarArtists Dec 27 16:10:55 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 27 16:10:55 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:10:55 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:10:55 volumio-nano volumio[4174]: info: Exploding uri music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/04 Boys From The Puget Sound.flac in service mpd Dec 27 16:10:55 volumio-nano volumio[4174]: info: ALBUMART /albumart?cacheid=943&web=Ayron%20Jones/Child%20Of%20The%20State/medium&path=%2Fmnt%2FNAS%2FArchiv_Audio%2FPlaylist%2F%231-MixQuer%2F04%20Boys%20From%20The%20Puget%20Sound.flac&metadata=false Dec 27 16:10:55 volumio-nano volumio[4174]: info: URI /mnt/NAS/Archiv_Audio/Playlist/#1-MixQuer/04 Boys From The Puget Sound.flac Dec 27 16:10:55 volumio-nano volumio[4174]: info: Exploding uri music-library/NAS/Archiv_Audio/Playlist/#1-MixQuer/05 Strawman.flac in service mpd Dec 27 16:10:55 volumio-nano volumio[4174]: info: ALBUMART /albumart?cacheid=943&web=Ayron%20Jones/Chronicles%20Of%20The%20Kid/medium&path=%2Fmnt%2FNAS%2FArchiv_Audio%2FPlaylist%2F%231-MixQuer%2F05%20Strawman.flac&metadata=false Dec 27 16:10:55 volumio-nano volumio[4174]: info: URI /mnt/NAS/Archiv_Audio/Playlist/#1-MixQuer/05 Strawman.flac Dec 27 16:10:57 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 27 16:10:57 volumio-nano volumio[4174]: info: CURURI: artists://Ayron%20Jones/Child%20Of%20The%20State Dec 27 16:10:57 volumio-nano volumio[4174]: info: Preload queue cleared Dec 27 16:10:57 volumio-nano volumio[4174]: info: Executing endpoint getSimilarAlbums Dec 27 16:10:57 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Dec 27 16:10:57 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:10:57 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:10:57 volumio-nano volumio[4174]: info: Executing endpoint metavolumio Dec 27 16:10:57 volumio-nano volumio[4174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 27 16:10:58 volumio-nano volumio[4174]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 16:10:58 volumio-nano volumio[4174]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Dec 27 16:10:58 volumio-nano volumio[4174]: at GetAddrInfoReqWrap.onlookupall [as oncomplete] (node:dns:118:26) { Dec 27 16:10:58 volumio-nano volumio[4174]: errno: -3007, Dec 27 16:10:58 volumio-nano volumio[4174]: code: 'ENOTFOUND', Dec 27 16:10:58 volumio-nano volumio[4174]: syscall: 'getaddrinfo', Dec 27 16:10:58 volumio-nano volumio[4174]: hostname: 'cdn-images.dzcdn.net' Dec 27 16:10:58 volumio-nano volumio[4174]: } Dec 27 16:10:58 volumio-nano volumio[4174]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 16:10:58 volumio-nano sudo[5724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 16:09' Dec 27 16:10:58 volumio-nano sudo[5724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"