-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Tue 2025-04-29 17:50:11 BST. -- Apr 29 17:49:04 denon volumio[834]: info: CoreCommandRouter::volumioGetState Apr 29 17:49:06 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:06 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 29 17:49:06 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 29 17:49:06 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 29 17:49:06 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 29 17:49:06 denon volumio[834]: info: Received Get System Version Apr 29 17:49:06 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 17:49:13 denon volumio[834]: info: CoreCommandRouter::volumioPlay Apr 29 17:49:13 denon volumio[834]: info: CoreStateMachine::play index undefined Apr 29 17:49:13 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:13 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:13 denon volumio[834]: info: CoreStateMachine::startPlaybackTimer Apr 29 17:49:13 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:13 denon volumio[834]: info: [1745945353436] ControllerWebradio::clearAddPlayTrack Apr 29 17:49:13 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 17:49:13 denon volumio[834]: info: sendMpdCommand stop took 1 milliseconds Apr 29 17:49:13 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand clear Apr 29 17:49:13 denon volumio[834]: info: Apr 29 17:49:13 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:49:13 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:49:13 denon volumio[834]: info: sendMpdCommand clear took 1 milliseconds Apr 29 17:49:13 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:49:13 denon volumio[834]: error: updateQueue error: null Apr 29 17:49:13 denon volumio[834]: info: ------------------------------ 3ms Apr 29 17:49:14 denon volumio[834]: info: CoreCommandRouter::volumioPlay Apr 29 17:49:14 denon volumio[834]: info: CoreStateMachine::play index undefined Apr 29 17:49:14 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:14 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:14 denon volumio[834]: info: CoreStateMachine::startPlaybackTimer Apr 29 17:49:14 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:14 denon volumio[834]: info: [1745945354721] ControllerWebradio::clearAddPlayTrack Apr 29 17:49:14 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 17:49:16 denon volumio[834]: info: CoreCommandRouter::volumioGetState Apr 29 17:49:16 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:18 denon volumio[834]: info: CoreCommandRouter::volumioPlay Apr 29 17:49:18 denon volumio[834]: info: CoreStateMachine::play index undefined Apr 29 17:49:18 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:18 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:18 denon volumio[834]: info: CoreStateMachine::startPlaybackTimer Apr 29 17:49:18 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:18 denon volumio[834]: info: [1745945358513] ControllerWebradio::clearAddPlayTrack Apr 29 17:49:18 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 17:49:23 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:49:23 denon volumio[834]: info: Apr 29 17:49:23 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:49:23 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:49:23 denon volumio[834]: info: sendMpdCommand stop took 8978 milliseconds Apr 29 17:49:23 denon volumio[834]: info: sendMpdCommand stop took 5185 milliseconds Apr 29 17:49:23 denon volumio[834]: info: sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" took 4 milliseconds Apr 29 17:49:23 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand clear Apr 29 17:49:23 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand clear Apr 29 17:49:23 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 17:49:23 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand play Apr 29 17:49:23 denon volumio[834]: info: Apr 29 17:49:23 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:49:23 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:49:23 denon volumio[834]: info: Apr 29 17:49:23 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:49:23 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:49:23 denon volumio[834]: info: ------------------------------ 7ms Apr 29 17:49:23 denon volumio[834]: info: sendMpdCommand clear took 4 milliseconds Apr 29 17:49:23 denon volumio[834]: info: sendMpdCommand clear took 4 milliseconds Apr 29 17:49:23 denon volumio[834]: info: sendMpdCommand play took 4 milliseconds Apr 29 17:49:23 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:49:23 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:49:23 denon volumio[834]: error: updateQueue error: null Apr 29 17:49:23 denon volumio[834]: error: updateQueue error: null Apr 29 17:49:23 denon volumio[834]: info: ------------------------------ 5ms Apr 29 17:49:23 denon volumio[834]: info: ------------------------------ 5ms Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:28 denon volumio[834]: info: Retrieving Cloud Streaming UI Apr 29 17:49:28 denon volumio[834]: info: Getting Tidal Cloud Configuration Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:28 denon volumio[834]: info: Getting Qobuz Cloud Configuration Apr 29 17:49:28 denon volumio[834]: info: Asking plugin for UI Config Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:28 denon volumio[834]: info: Getting Spotify Cloud Configuration Apr 29 17:49:28 denon volumio[834]: info: Asking plugin for UI Config Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:28 denon volumio[834]: info: Saving Spotify Acccount Apr 29 17:49:28 denon volumio[834]: info: Got Tidal Cloud Configuration Apr 29 17:49:28 denon volumio[834]: info: Got it Apr 29 17:49:28 denon volumio[834]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:28 denon volumio[834]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 29 17:49:28 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 29 17:49:30 denon volumio[834]: info: CoreCommandRouter::volumioGetState Apr 29 17:49:34 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:49:35 denon volumio[834]: info: Airplay started streaming, receiving metadatas Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::volumioStop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::stop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: Airplay started streaming, receiving metadatas Apr 29 17:49:35 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::volumioStop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::stop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: Airplay started streaming, receiving metadatas Apr 29 17:49:35 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::volumioStop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::stop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: Airplay started streaming, receiving metadatas Apr 29 17:49:35 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::volumioStop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::stop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon shairport-sync[2188]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 29 17:49:35 denon shairport-sync[2188]: Dload Upload Total Spent Left Speed Apr 29 17:49:35 denon volumio[834]: info: Apr 29 17:49:35 denon volumio[834]: ---------------------------- Client requests Start Airplay PlaybackRoutine Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Apr 29 17:49:35 denon volumio[834]: info: Airplay playback start Apr 29 17:49:35 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::volumioStop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::stop Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:49:35 denon shairport-sync[2188]: [158B blob data] Apr 29 17:49:35 denon volumio[834]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 29 17:49:35 denon volumio[834]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 29 17:49:35 denon volumio[834]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 29 17:49:35 denon volumio[834]: info: Updating volume from AirPlay: -20; 33% Apr 29 17:49:35 denon volumio[834]: info: VolumeController::SetAlsaVolume33 Apr 29 17:49:35 denon shairport-sync[2188]: {"time":1745945375423,"response":"startAirplayPlayback Success"}ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:101:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook failed to run command echo '44100,S32_LE,2,32' >/tmp/fusiondsp_stream_params.log Apr 29 17:49:35 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:35 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:49:35 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:35 denon volumio[834]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 29 17:49:35 denon volumio[834]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 29 17:49:35 denon volumio[834]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 29 17:49:35 denon volumio[834]: info: [ASDebug] CurState: stop PrevState: undefined Apr 29 17:49:35 denon volumio[834]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 29 17:49:35 denon volumio[834]: error: FusionDsp - WebSocket error: [object Object] Apr 29 17:49:35 denon volumio[834]: error: FusionDsp - WebSocket error: [object Object] Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] Togle GPIO: OFF Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:36 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] CurState: play PrevState: stop Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] Togle GPIO: ON Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:36 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:36 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:36 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:36 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:36 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:36 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:37 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:37 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:37 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:39 denon volumio[834]: info: Updating volume from AirPlay: -21.88; 27% Apr 29 17:49:39 denon volumio[834]: info: VolumeController::SetAlsaVolume27 Apr 29 17:49:39 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:39 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:49:39 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:39 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:49:42 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:49:42 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:42 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:49:45 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:49:45 denon volumio[834]: info: Apr 29 17:49:45 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:49:45 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:49:45 denon volumio[834]: info: Apr 29 17:49:45 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:49:45 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:49:45 denon volumio[834]: info: sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" took 11002 milliseconds Apr 29 17:49:45 denon volumio[834]: info: sendMpdCommand add "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" took 5 milliseconds Apr 29 17:49:45 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 17:49:45 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand play Apr 29 17:49:45 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 17:49:45 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand play Apr 29 17:49:45 denon volumio[834]: info: ------------------------------ 7ms Apr 29 17:49:45 denon volumio[834]: info: ------------------------------ 8ms Apr 29 17:49:45 denon volumio[834]: info: sendMpdCommand play took 5 milliseconds Apr 29 17:49:45 denon volumio[834]: info: sendMpdCommand play took 5 milliseconds Apr 29 17:49:56 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:49:56 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 29 17:49:57 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 29 17:49:57 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 29 17:49:57 denon volumio[834]: info: Received Get System Version Apr 29 17:49:57 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 17:50:00 denon volumio[834]: info: CoreCommandRouter::volumioGetState Apr 29 17:50:00 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 17:50:00 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 29 17:50:00 denon volumio[834]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 17:50:00 denon volumio[834]: info: FusionDsp - no preset used Apr 29 17:50:01 denon volumio[834]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 29 17:50:04 denon volumio[834]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 29 17:50:04 denon volumio[834]: info: Uninstalling plugin ampswitch Apr 29 17:50:04 denon volumio[834]: info: [ASDebug] Port: 24 Apr 29 17:50:04 denon volumio[834]: info: [ASDebug] Inverted: false Apr 29 17:50:04 denon volumio[834]: info: [ASDebug] Delay: 720 Apr 29 17:50:07 denon volumio[834]: info: Apr 29 17:50:07 denon volumio[834]: ---------------------------- MPD announces state update: player Apr 29 17:50:07 denon volumio[834]: info: ControllerMpd::getState Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand status Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 29 17:50:07 denon volumio[834]: info: sendMpdCommand status took 8 milliseconds Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::parseState Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 17:50:07 denon volumio[834]: info: sendMpdCommand clearerror took 3 milliseconds Apr 29 17:50:07 denon volumio[834]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::parseTrackInfo Apr 29 17:50:07 denon volumio[834]: info: ControllerMpd::pushState Apr 29 17:50:07 denon volumio[834]: info: CoreCommandRouter::servicePushState Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:50:07 denon volumio[834]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 17:50:07 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:50:07 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:50:07 denon volumio[834]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3","trackType":""} Apr 29 17:50:07 denon volumio[834]: verbose: CURRENT POSITION 0 Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::syncState stateService stop Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::syncState currentStatus play Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::play index undefined Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:50:07 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:50:07 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::startPlaybackTimer Apr 29 17:50:07 denon volumio[834]: info: CorePlayQueue::getTrack 0 Apr 29 17:50:07 denon volumio[834]: info: [1745945407740] ControllerWebradio::clearAddPlayTrack Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 17:50:07 denon volumio[834]: info: ------------------------------ 46ms Apr 29 17:50:07 denon volumio[834]: info: CoreStateMachine::pushState Apr 29 17:50:07 denon volumio[834]: info: CoreCommandRouter::volumioPushState Apr 29 17:50:07 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:50:07 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:50:07 denon volumio[834]: info: [ASDebug] CurState: play PrevState: play Apr 29 17:50:07 denon volumio[834]: info: sendMpdCommand stop took 27 milliseconds Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand clear Apr 29 17:50:07 denon volumio[834]: info: Apr 29 17:50:07 denon volumio[834]: ---------------------------- MPD announces system playlist update Apr 29 17:50:07 denon volumio[834]: info: Ignoring MPD Status Update Apr 29 17:50:07 denon volumio[834]: info: sendMpdCommand clear took 2 milliseconds Apr 29 17:50:07 denon volumio[834]: verbose: ControllerMpd::sendMpdCommand load "https://edge56.live-sm.absolutradio.de/absolut-relax/stream/mp3" Apr 29 17:50:07 denon volumio[834]: error: updateQueue error: null Apr 29 17:50:07 denon volumio[834]: info: ------------------------------ 3ms Apr 29 17:50:10 denon volumio[834]: info: [ASDebug] Port: 24 Apr 29 17:50:10 denon volumio[834]: info: [ASDebug] Inverted: false Apr 29 17:50:10 denon volumio[834]: info: [ASDebug] Delay: 720 Apr 29 17:50:10 denon volumio[834]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 17:50:10 denon volumio[834]: Error: EBADF: bad file descriptor, close Apr 29 17:50:10 denon volumio[834]: at Object.closeSync (fs.js:439:3) Apr 29 17:50:10 denon volumio[834]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Apr 29 17:50:10 denon volumio[834]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Apr 29 17:50:10 denon volumio[834]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Apr 29 17:50:10 denon volumio[834]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Apr 29 17:50:10 denon volumio[834]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Apr 29 17:50:10 denon volumio[834]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 29 17:50:10 denon volumio[834]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 29 17:50:10 denon volumio[834]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 29 17:50:10 denon volumio[834]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) { Apr 29 17:50:10 denon volumio[834]: errno: -9, Apr 29 17:50:10 denon volumio[834]: syscall: 'close', Apr 29 17:50:10 denon volumio[834]: code: 'EBADF' Apr 29 17:50:10 denon volumio[834]: } Apr 29 17:50:10 denon volumio[834]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 17:50:11 denon sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-29 17:49 Apr 29 17:50:11 denon sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 09:33:57 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="354f95cf0a6c2cbcd57b1aca3179c81c"