-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2026-05-27 21:22:52 CEST. -- May 27 21:21:22 volumio go-librespot[1209]: time="2026-05-27T21:21:22+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 496" May 27 21:21:22 volumio go-librespot[1209]: time="2026-05-27T21:21:22+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 27 21:21:22 volumio go-librespot[1209]: time="2026-05-27T21:21:22+02:00" level=debug msg="put connect state inactive" May 27 21:21:22 volumio go-librespot[1209]: time="2026-05-27T21:21:22+02:00" level=trace msg="emitting websocket event: inactive" May 27 21:21:22 volumio volumio[986]: SPOTIFY: received: {"type":"inactive","data":null} May 27 21:21:22 volumio volumio[986]: error: Failed to decode event: inactive May 27 21:21:22 volumio go-librespot[1209]: time="2026-05-27T21:21:22+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1846" May 27 21:21:22 volumio go-librespot[1209]: time="2026-05-27T21:21:22+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 27 21:21:23 volumio volumio[986]: info: Airplay started streaming, receiving metadatas May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioStop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::stop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::stPlaybackTimer May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::updateTrackBlock May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrackBlock May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::serviceStop May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::serviceStop May 27 21:21:23 volumio volumio[986]: info: [1779909683041] ControllerWebradio::stop May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:21:23 volumio volumio[986]: info: Airplay started streaming, receiving metadatas May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioStop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::stop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:21:23 volumio volumio[986]: info: Airplay started streaming, receiving metadatas May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioStop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::stop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:21:23 volumio volumio[986]: info: Airplay started streaming, receiving metadatas May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioStop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::stop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:21:23 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 May 27 21:21:23 volumio volumio[986]: info: May 27 21:21:23 volumio volumio[986]: ---------------------------- MPD announces state update: player May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand stop took 32 milliseconds May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::getState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand status May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand status took 2 milliseconds May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:21:23 volumio volumio[986]: info: May 27 21:21:23 volumio volumio[986]: ---------------------------- MPD announces state update: player May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::getState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand status May 27 21:21:23 volumio volumio[986]: info: May 27 21:21:23 volumio volumio[986]: ---------------------------- MPD announces state update: player May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::getState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand status May 27 21:21:23 volumio volumio[986]: info: May 27 21:21:23 volumio volumio[986]: ---------------------------- MPD announces state update: player May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::getState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand status May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand playlistinfo took 10 milliseconds May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand status took 9 milliseconds May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand status took 6 milliseconds May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand status took 5 milliseconds May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseTrackInfo May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseState May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::pushState May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 21:21:23 volumio volumio[986]: info: ------------------------------ 59ms May 27 21:21:23 volumio volumio[986]: info: Updating volume from AirPlay: -20; 33% May 27 21:21:23 volumio volumio[986]: info: VolumeController::SetAlsaVolume33 May 27 21:21:23 volumio shairport-sync[1287]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 21:21:23 volumio shairport-sync[1287]: Dload Upload Total Spent Left Speed May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand playlistinfo took 121 milliseconds May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand playlistinfo took 121 milliseconds May 27 21:21:23 volumio volumio[986]: info: sendMpdCommand playlistinfo took 121 milliseconds May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseTrackInfo May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseTrackInfo May 27 21:21:23 volumio volumio[986]: verbose: ControllerMpd::parseTrackInfo May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::pushState May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::pushState May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 21:21:23 volumio volumio[986]: info: ControllerMpd::pushState May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd May 27 21:21:23 volumio volumio[986]: info: ------------------------------ 208ms May 27 21:21:23 volumio volumio[986]: info: ------------------------------ 207ms May 27 21:21:23 volumio volumio[986]: info: ------------------------------ 205ms May 27 21:21:23 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 May 27 21:21:23 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:23 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 61 May 27 21:21:23 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 33 May 27 21:21:23 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:23 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 33 May 27 21:21:23 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:23 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:23 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:23 volumio volumio[986]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 21:21:23 volumio volumio[986]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 21:21:23 volumio volumio[986]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 21:21:23 volumio go-librespot[1209]: time="2026-05-27T21:21:23+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1449" May 27 21:21:23 volumio go-librespot[1209]: time="2026-05-27T21:21:23+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 27 21:21:23 volumio volumio[986]: info: May 27 21:21:23 volumio volumio[986]: ---------------------------- Client requests Start Airplay PlaybackRoutine May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback May 27 21:21:23 volumio volumio[986]: info: Airplay playback start May 27 21:21:23 volumio volumio[986]: info: CorePlayQueue::getTrack 0 May 27 21:21:23 volumio volumio[986]: info: CoreCommandRouter::volumioStop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::stop May 27 21:21:23 volumio volumio[986]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:21:23 volumio shairport-sync[1287]: [158B blob data] May 27 21:21:23 volumio volumio[986]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:24 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:24 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:24 volumio volumio[986]: SPOTIFY: SETTING SPOTIFY VOLUME 33 May 27 21:21:24 volumio volumio[986]: info: Sending Spotify command with payload to local API: /player/volume May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 27 21:21:24 volumio go-librespot[1209]: time="2026-05-27T21:21:24+02:00" level=debug msg="update volume to 21626/65535" May 27 21:21:25 volumio go-librespot[1209]: time="2026-05-27T21:21:25+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 27 21:21:25 volumio go-librespot[1209]: time="2026-05-27T21:21:25+02:00" level=trace msg="emitting websocket event: volume" May 27 21:21:25 volumio volumio[986]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} May 27 21:21:25 volumio volumio[986]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 May 27 21:21:28 volumio kernel: hwmon hwmon1: Voltage normalised May 27 21:21:32 volumio kernel: hwmon hwmon1: Undervoltage detected! May 27 21:21:33 volumio volumio[986]: info: Updating volume from AirPlay: -18.12; 40% May 27 21:21:33 volumio volumio[986]: info: VolumeController::SetAlsaVolume40 May 27 21:21:33 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:33 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:33 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:33 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 May 27 21:21:33 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 33 May 27 21:21:33 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 40 May 27 21:21:33 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:33 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 40 May 27 21:21:34 volumio volumio[986]: info: Updating volume from AirPlay: -16.25; 46% May 27 21:21:34 volumio volumio[986]: info: VolumeController::SetAlsaVolume46 May 27 21:21:34 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:34 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:34 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:34 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 May 27 21:21:34 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 40 May 27 21:21:34 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 46 May 27 21:21:34 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:34 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 46 May 27 21:21:34 volumio volumio[986]: info: Updating volume from AirPlay: -14.37; 52% May 27 21:21:34 volumio volumio[986]: info: VolumeController::SetAlsaVolume52 May 27 21:21:34 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:34 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:34 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:34 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 May 27 21:21:34 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 46 May 27 21:21:34 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 52 May 27 21:21:34 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:34 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 52 May 27 21:21:34 volumio volumio[986]: info: Updating volume from AirPlay: -10.62; 65% May 27 21:21:34 volumio volumio[986]: info: VolumeController::SetAlsaVolume65 May 27 21:21:34 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:34 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:35 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:35 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 May 27 21:21:35 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 52 May 27 21:21:35 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 65 May 27 21:21:35 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:35 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 65 May 27 21:21:35 volumio volumio[986]: info: Updating volume from AirPlay: -8.75; 71% May 27 21:21:35 volumio volumio[986]: info: VolumeController::SetAlsaVolume71 May 27 21:21:35 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:35 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:35 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:35 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 71 May 27 21:21:35 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 65 May 27 21:21:35 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 71 May 27 21:21:35 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:35 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 71 May 27 21:21:35 volumio volumio[986]: info: Updating volume from AirPlay: -5; 83% May 27 21:21:35 volumio volumio[986]: info: VolumeController::SetAlsaVolume83 May 27 21:21:35 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:35 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:35 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:35 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:35 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 71 May 27 21:21:35 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 83 May 27 21:21:35 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:35 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 83 May 27 21:21:36 volumio kernel: hwmon hwmon1: Voltage normalised May 27 21:21:37 volumio volumio[986]: SPOTIFY: SETTING SPOTIFY VOLUME 83 May 27 21:21:37 volumio volumio[986]: info: Sending Spotify command with payload to local API: /player/volume May 27 21:21:37 volumio go-librespot[1209]: time="2026-05-27T21:21:37+02:00" level=debug msg="update volume to 54394/65535" May 27 21:21:37 volumio go-librespot[1209]: time="2026-05-27T21:21:37+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 27 21:21:37 volumio go-librespot[1209]: time="2026-05-27T21:21:37+02:00" level=trace msg="emitting websocket event: volume" May 27 21:21:37 volumio volumio[986]: SPOTIFY: received: {"type":"volume","data":{"value":83,"max":100}} May 27 21:21:37 volumio volumio[986]: SPOTIFY: RECEIVED SPOTIFY VOLUME 83 May 27 21:21:38 volumio kernel: hwmon hwmon1: Undervoltage detected! May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:42 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:42 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:42 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:21:43 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:43 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:43 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 83 May 27 21:21:48 volumio kernel: hwmon hwmon1: Voltage normalised May 27 21:21:51 volumio kernel: hwmon hwmon1: Undervoltage detected! May 27 21:21:56 volumio volumio[986]: info: Updating volume from AirPlay: -3.12; 90% May 27 21:21:56 volumio volumio[986]: info: VolumeController::SetAlsaVolume90 May 27 21:21:57 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:57 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:57 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:57 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 May 27 21:21:57 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 83 May 27 21:21:57 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 90 May 27 21:21:57 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:57 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 90 May 27 21:21:58 volumio volumio[986]: info: Updating volume from AirPlay: -1.25; 96% May 27 21:21:58 volumio volumio[986]: info: VolumeController::SetAlsaVolume96 May 27 21:21:58 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:21:58 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:21:58 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:21:58 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:21:58 volumio volumio[986]: SPOTIFY: SPOTIFY VOLUME 90 May 27 21:21:58 volumio volumio[986]: SPOTIFY: VOLUMIO VOLUME 96 May 27 21:21:58 volumio volumio[986]: SPOTIFY: DELTA VOLUME ENOUGH: true May 27 21:21:58 volumio volumio[986]: info: Setting Spotify Volume from Volumio: 96 May 27 21:21:59 volumio volumio[986]: SPOTIFY: SETTING SPOTIFY VOLUME 96 May 27 21:21:59 volumio volumio[986]: info: Sending Spotify command with payload to local API: /player/volume May 27 21:21:59 volumio go-librespot[1209]: time="2026-05-27T21:21:59+02:00" level=debug msg="update volume to 62913/65535" May 27 21:21:59 volumio go-librespot[1209]: time="2026-05-27T21:21:59+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 27 21:21:59 volumio go-librespot[1209]: time="2026-05-27T21:21:59+02:00" level=trace msg="emitting websocket event: volume" May 27 21:21:59 volumio volumio[986]: SPOTIFY: received: {"type":"volume","data":{"value":96,"max":100}} May 27 21:21:59 volumio volumio[986]: SPOTIFY: RECEIVED SPOTIFY VOLUME 96 May 27 21:22:22 volumio kernel: hwmon hwmon1: Voltage normalised May 27 21:22:26 volumio kernel: hwmon hwmon1: Undervoltage detected! May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::servicePushState May 27 21:22:49 volumio volumio[986]: info: CoreStateMachine::pushState May 27 21:22:49 volumio volumio[986]: info: CoreCommandRouter::volumioPushState May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:49 volumio volumio[986]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 May 27 21:22:51 volumio volumio[986]: info: [1779909771795] [80s80s] Pushing the next song state Kenny Loggins - Danger Zone and getting next track. May 27 21:22:51 volumio volumio[986]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 21:22:51 volumio volumio[986]: TypeError: Cannot set property 'name' of undefined May 27 21:22:51 volumio volumio[986]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) May 27 21:22:51 volumio volumio[986]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) May 27 21:22:51 volumio volumio[986]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) May 27 21:22:51 volumio volumio[986]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) May 27 21:22:51 volumio volumio[986]: at processImmediate (internal/timers.js:461:21) May 27 21:22:51 volumio volumio[986]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 21:22:52 volumio sudo[3324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-27 21:21 May 27 21:22:52 volumio sudo[3324]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"