Oct 02 23:44:00 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:00 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:00 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:00 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:00 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:00 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:00 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:00 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:00 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:00 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:00 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:00 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 45 Oct 02 23:44:00 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 47 Oct 02 23:44:00 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 45 Oct 02 23:44:00 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:00 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 45 Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:01 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 45 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 44 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:01 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 45 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 43 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:01 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 43 Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:01 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 42 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 43 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 42 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:01 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:01 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:01 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:01 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:01 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 43 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 41 Oct 02 23:44:01 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:01 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 41 Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 41 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 40 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 39 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 41 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 39 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:02 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 39 Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 39 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 38 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 39 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 37 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:02 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 37 Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 36 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 37 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 36 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 37 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 35 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:02 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 35 Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:02 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:02 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:02 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:02 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:02 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 35 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 34 Oct 02 23:44:02 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 35 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 33 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:03 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 33 Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 32 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 33 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 32 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 33 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 31 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:03 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 31 Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 31 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 30 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 31 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 29 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:03 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 29 Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 29 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 28 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:03 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:03 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:03 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:03 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:03 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 27 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 29 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 27 Oct 02 23:44:03 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:03 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 27 Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 26 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 27 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 26 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 27 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 25 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:04 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 25 Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 25 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 24 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 25 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 23 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:04 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 23 Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 22 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 23 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 22 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 21 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 23 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 21 Oct 02 23:44:04 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:04 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 21 Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:04 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:04 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:04 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:04 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:04 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 21 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 20 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 21 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 19 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:05 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 19 Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 19 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 18 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 19 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 17 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:05 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 17 Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 16 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 17 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 16 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 17 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 15 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:05 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 15 Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:05 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 15 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 14 Oct 02 23:44:05 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:05 volumio go-librespot[1828]: time="2026-10-02T23:44:05+02:00" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:3UbCMiXR6d9zcfNRS0mTzG" Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 15 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 13 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:06 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 13 Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 13 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 12 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 11 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 13 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 11 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:06 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 11 Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 11 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 10 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 9 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 11 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 9 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:06 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 9 Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:06 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 8 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 9 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 8 Oct 02 23:44:06 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:07 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 9 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 7 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:07 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 7 Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:07 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 6 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 7 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 6 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:07 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 7 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 5 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:07 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 5 Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:07 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 5 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 4 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:07 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:07 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:07 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:07 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:07 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 5 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 3 Oct 02 23:44:07 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:07 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 3 Oct 02 23:44:08 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:08 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:08 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:08 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:08 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:08 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:08 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:08 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:08 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:08 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:08 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 2 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 3 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 2 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:44:08 volumio volumio[1335]: info: gpio-buttons: button1 triggered: volumeDown Oct 02 23:44:08 volumio volumio[1335]: info: gpio-buttons: Action: volumeDown Oct 02 23:44:08 volumio volumio[1335]: info: VolumeController::SetAlsaVolume- Oct 02 23:44:08 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:44:08 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:08 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:44:08 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:44:08 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:44:08 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:44:08 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:44:08 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 3 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 1 Oct 02 23:44:08 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:44:08 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 1 Oct 02 23:44:09 volumio volumio[1335]: SPOTIFY: SETTING SPOTIFY VOLUME 1 Oct 02 23:44:09 volumio volumio[1335]: info: Sending Spotify command with payload to local API: /player/volume Oct 02 23:44:09 volumio go-librespot[1828]: time="2026-10-02T23:44:09+02:00" level=debug msg="update volume requested to 655/65535" Oct 02 23:44:09 volumio go-librespot[1828]: time="2026-10-02T23:44:09+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 02 23:44:09 volumio go-librespot[1828]: time="2026-10-02T23:44:09+02:00" level=trace msg="emitting websocket event: volume" Oct 02 23:44:09 volumio volumio[1335]: SPOTIFY: received: {"type":"volume","data":{"max":100,"value":1}} Oct 02 23:44:09 volumio volumio[1335]: SPOTIFY: RECEIVED SPOTIFY VOLUME 1 Oct 02 23:44:18 volumio go-librespot[1828]: time="2026-10-02T23:44:18+02:00" level=debug msg="fetched chunk 12/16, size: 524288" uri="spotify:track:3UbCMiXR6d9zcfNRS0mTzG" Oct 02 23:44:18 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:18 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:19 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:19 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:19 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:20 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:21 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:21 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:22 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:23 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:23 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:24 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:28 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:28 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:29 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:30 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:30 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:30 volumio go-librespot[1828]: time="2026-10-02T23:44:30+02:00" level=debug msg="fetched chunk 13/16, size: 524288" uri="spotify:track:3UbCMiXR6d9zcfNRS0mTzG" Oct 02 23:44:31 volumio volumio[1335]: Searching plugin music_service/spop Oct 02 23:44:31 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 02 23:44:31 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:31 volumio volumio[1335]: info: All search sources collected, pushing search results Oct 02 23:44:42 volumio go-librespot[1828]: time="2026-10-02T23:44:42+02:00" level=debug msg="fetched chunk 14/16, size: 524288" uri="spotify:track:3UbCMiXR6d9zcfNRS0mTzG" Oct 02 23:44:55 volumio go-librespot[1828]: time="2026-10-02T23:44:55+02:00" level=debug msg="fetched chunk 15/16, size: 524288" uri="spotify:track:3UbCMiXR6d9zcfNRS0mTzG" Oct 02 23:44:57 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 02 23:44:57 volumio volumio[1335]: info: In handleBrowseUri, curUri=spotify:artist:12jQMTp7sgwtm0iPvrnCZa Oct 02 23:44:58 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:6ss8UCk6qEj8yZPEUTmYQR Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:0Uop2xyt3hHbYAxQgElrvi Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:6U5OM6W98LQlGCXrGOxlYs Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:5D8ESz2XhflyrZ80s0v2kt Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:3mpl9Ez4dQM5VbbzTNVjxy Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:3ic5bDfLpylYlbEDq8FFdT Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:3uREkeRiycIVLQngQTSCHV Oct 02 23:44:58 volumio volumio[1335]: info: Preloading song: spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab Oct 02 23:44:58 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:44:58 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:44:58 volumio volumio[1335]: info: Executing endpoint getSimilarArtists Oct 02 23:44:58 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 02 23:44:58 volumio volumio[1335]: info: Executing endpoint getSimilarArtists Oct 02 23:44:58 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 02 23:44:58 volumio volumio[1335]: info: Executing endpoint metavolumio Oct 02 23:44:58 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 23:44:58 volumio volumio[1335]: info: Executing endpoint metavolumio Oct 02 23:44:58 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 23:45:05 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::ClearQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::stop Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::stPlaybackTimer Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::serviceStop Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::serviceStop Oct 02 23:45:05 volumio volumio[1335]: info: Spotify Stop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: SPOTIFY STOP Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: {"status":"play","position":0,"title":"Keeping Your Head Up","artist":"Birdy","album":"Beautiful Lies","albumart":"https://i.scdn.co/image/ab67616d0000b27355ff8d164c51a7b3d71d9248","uri":"spotify:track:3UbCMiXR6d9zcfNRS0mTzG","trackType":"spotify","codec":"ogg","seek":104025,"duration":208,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":1,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Oct 02 23:45:05 volumio volumio[1335]: info: Sending Spotify command to local API: /player/pause Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::clearPlayQueue Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::saveQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:6ss8UCk6qEj8yZPEUTmYQR Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:6ss8UCk6qEj8yZPEUTmYQR in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:6ss8UCk6qEj8yZPEUTmYQR Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="pause track at 161344ms" Oct 02 23:45:05 volumio volumio[1335]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=trace msg="emitting websocket event: paused" Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:3UbCMiXR6d9zcfNRS0mTzG","uri":"spotify:track:3UbCMiXR6d9zcfNRS0mTzG","play_origin":"go-librespot"}} Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: {"status":"pause","service":"spop","title":"Keeping Your Head Up","artist":"Birdy","album":"Beautiful Lies","albumart":"https://i.scdn.co/image/ab67616d00001e0255ff8d164c51a7b3d71d9248","uri":"spotify:track:3UbCMiXR6d9zcfNRS0mTzG","trackType":"spotify","seek":161000,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Keeping Your Head Up","artist":"Birdy","album":"Beautiful Lies","albumart":"https://i.scdn.co/image/ab67616d00001e0255ff8d164c51a7b3d71d9248","uri":"spotify:track:3UbCMiXR6d9zcfNRS0mTzG","trackType":"spotify","seek":161000,"duration":208,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:05 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::syncState stateService pause Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","service":"spop","name":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","type":"song","duration":74,"albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushQueue Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::saveQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPlay Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::play index 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:0Uop2xyt3hHbYAxQgElrvi Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:0Uop2xyt3hHbYAxQgElrvi in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:0Uop2xyt3hHbYAxQgElrvi Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:6U5OM6W98LQlGCXrGOxlYs Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:6U5OM6W98LQlGCXrGOxlYs in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:6U5OM6W98LQlGCXrGOxlYs Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:5D8ESz2XhflyrZ80s0v2kt Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:5D8ESz2XhflyrZ80s0v2kt in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:5D8ESz2XhflyrZ80s0v2kt Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:3mpl9Ez4dQM5VbbzTNVjxy Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:3mpl9Ez4dQM5VbbzTNVjxy in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:3mpl9Ez4dQM5VbbzTNVjxy Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:3ic5bDfLpylYlbEDq8FFdT Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:3ic5bDfLpylYlbEDq8FFdT in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:3ic5bDfLpylYlbEDq8FFdT Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:3uREkeRiycIVLQngQTSCHV Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:3uREkeRiycIVLQngQTSCHV in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:3uREkeRiycIVLQngQTSCHV Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab Oct 02 23:45:05 volumio volumio[1335]: info: Exploding uri spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab in service spop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: EXPLODING URI:spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::stop Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::play index undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::startPlaybackTimer Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 23:45:05 volumio volumio[1335]: info: [1790977505510] ControllerSpotify::clearAddPlayTrack Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:05 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:05 volumio volumio[1335]: info: Sending Spotify command with payload to local API: /player/play Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="resolved context of track" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=trace msg="emitting websocket event: will_play" Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","play_origin":"go-librespot"}} Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Uop2xyt3hHbYAxQgElrvi","service":"spop","name":"waking up fucking early (big band jazz)","artist":"wake up alarms","album":"waking up fucking early (big band jazz)","type":"song","duration":104,"albumart":"https://i.scdn.co/image/ab67616d0000b27348d677c927ac64b15fb7ef8c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6U5OM6W98LQlGCXrGOxlYs","service":"spop","name":"waking up fucking early (country)","artist":"wake up alarms","album":"waking up fucking early (country)","type":"song","duration":116,"albumart":"https://i.scdn.co/image/ab67616d0000b27339bd8e6ab3eb20f1e174d143","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","service":"spop","name":"waking up fucking early (chiptune)","artist":"wake up alarms","album":"waking up fucking early (chiptune)","type":"song","duration":77,"albumart":"https://i.scdn.co/image/ab67616d0000b273be65f44bbc41e28e85baa30d","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5D8ESz2XhflyrZ80s0v2kt","service":"spop","name":"waking up fucking early (reggae)","artist":"wake up alarms","album":"waking up fucking early (reggae)","type":"song","duration":104,"albumart":"https://i.scdn.co/image/ab67616d0000b273f840648a2062853a673c0860","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy","service":"spop","name":"waking up fucking early (gospel)","artist":"wake up alarms","album":"waking up fucking early (gospel)","type":"song","duration":66,"albumart":"https://i.scdn.co/image/ab67616d0000b273c538d9fc8fde9a19a33c07f8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","service":"spop","name":"waking up fucking early (opera)","artist":"Diego Daly","album":"waking up fucking early (opera)","type":"song","duration":67,"albumart":"https://i.scdn.co/image/ab67616d0000b273e7d61a327eeab0934daaaab0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ic5bDfLpylYlbEDq8FFdT","service":"spop","name":"waking up fucking early (ethereal female voice)","artist":"wake up alarms","album":"waking up fucking early (ethereal female voice)","type":"song","duration":93,"albumart":"https://i.scdn.co/image/ab67616d0000b273377615cafb8d06988e6068d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="selected format OGG_VORBIS_320 (0857d0806cd2f86ffbe9b755c9677564e6eba5b2)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=debug msg="requested aes key for file 0857d0806cd2f86ffbe9b755c9677564e6eba5b2, gid: 6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3mpl9Ez4dQM5VbbzTNVjxy","service":"spop","name":"waking up fucking early (original)","artist":"wake up alarms","album":"waking up fucking early (original)","type":"song","duration":63,"albumart":"https://i.scdn.co/image/ab67616d0000b273d3c7bada10aa69ec8a115b81","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushQueue Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::saveQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:05 volumio go-librespot[1828]: time="2026-10-02T23:45:05+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:05 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::ClearQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::stop Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::stPlaybackTimer Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:05 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::serviceStop Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::serviceStop Oct 02 23:45:05 volumio volumio[1335]: info: Spotify Stop Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: SPOTIFY STOP Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":1,"dbVolume":null,"mute":false,"disableVolumeControl":false,"random":true,"repeat":false,"repeatSingle":false,"consume":false} Oct 02 23:45:05 volumio volumio[1335]: info: Sending Spotify command to local API: /player/pause Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::clearPlayQueue Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::saveQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:6ss8UCk6qEj8yZPEUTmYQR Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:6ss8UCk6qEj8yZPEUTmYQR Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushQueue Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::saveQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPlay Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::play index 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::addQueueItems Oct 02 23:45:05 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:0Uop2xyt3hHbYAxQgElrvi Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:0Uop2xyt3hHbYAxQgElrvi Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:6U5OM6W98LQlGCXrGOxlYs Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:6U5OM6W98LQlGCXrGOxlYs Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:5D8ESz2XhflyrZ80s0v2kt Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:5D8ESz2XhflyrZ80s0v2kt Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:2R4oVJQ2s3yqG1Bh9PCQZy Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:3mpl9Ez4dQM5VbbzTNVjxy Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:3mpl9Ez4dQM5VbbzTNVjxy Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:3ic5bDfLpylYlbEDq8FFdT Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:3ic5bDfLpylYlbEDq8FFdT Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:3uREkeRiycIVLQngQTSCHV Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:3uREkeRiycIVLQngQTSCHV Oct 02 23:45:05 volumio volumio[1335]: info: Adding Item to queue: spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab Oct 02 23:45:05 volumio volumio[1335]: info: Using cached record of: spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::stop Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreCommandRouter::volumioPushQueue Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::saveQueue Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::play index undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: CoreStateMachine::startPlaybackTimer Oct 02 23:45:05 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:05 volumio volumio[1335]: info: [1790977505991] ControllerSpotify::clearAddPlayTrack Oct 02 23:45:05 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched first chunk of 5, total size is 2390458 bytes" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="track has no narration" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=info msg="loaded track \"waking up fucking early (epic adventure)\" (paused: false, position: 0ms, duration: 74919ms, prefetched: false)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="scheduling prefetch in 45s" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="emitting websocket event: metadata" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: received: {"type":"metadata","data":{"album_cover_url":"https://i.scdn.co/image/ab67616d00001e0210366c635bb46a95b7e1b0d4","album_name":"waking up fucking early (epic adventure)","artist_names":["wake up alarms"],"bit_depth":null,"bitrate":320,"codec":"vorbis","disc_number":1,"duration":74919,"format":"OGG_VORBIS_320","name":"waking up fucking early (epic adventure)","position":0,"release_date":"year:2025 month:10 day:25","sample_rate":44100,"track_number":1,"uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR"}} Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="emitting websocket event: playing" Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:06 volumio volumio[1335]: info: Sending Spotify command with payload to local API: /player/play Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","resume":false,"play_origin":"go-librespot"}} Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:06 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched chunk 1/4, size: 524288" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="pause track at 100ms" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="emitting websocket event: paused" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","play_origin":"go-librespot"}} Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: {"status":"pause","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState stateService pause Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="resolved context of track" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="emitting websocket event: will_play" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","play_origin":"go-librespot"}} Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched chunk 2/4, size: 524288" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="selected format OGG_VORBIS_320 (0857d0806cd2f86ffbe9b755c9677564e6eba5b2)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="requested aes key for file 0857d0806cd2f86ffbe9b755c9677564e6eba5b2, gid: 6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched chunk 3/4, size: 524288" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: {"status":"pause","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState stateService pause Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched first chunk of 5, total size is 2390458 bytes" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="track has no narration" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=info msg="loaded track \"waking up fucking early (epic adventure)\" (paused: false, position: 0ms, duration: 74919ms, prefetched: false)" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="scheduling prefetch in 45s" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="emitting websocket event: metadata" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=trace msg="emitting websocket event: playing" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: received: {"type":"metadata","data":{"album_cover_url":"https://i.scdn.co/image/ab67616d00001e0210366c635bb46a95b7e1b0d4","album_name":"waking up fucking early (epic adventure)","artist_names":["wake up alarms"],"bit_depth":null,"bitrate":320,"codec":"vorbis","disc_number":1,"duration":74919,"format":"OGG_VORBIS_320","name":"waking up fucking early (epic adventure)","position":0,"release_date":"year:2025 month:10 day:25","sample_rate":44100,"track_number":1,"uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR"}} Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","resume":false,"play_origin":"go-librespot"}} Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d00001e0210366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d00001e0210366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:06 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched chunk 1/4, size: 524288" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched chunk 2/4, size: 524288" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio go-librespot[1828]: time="2026-10-02T23:45:06+02:00" level=debug msg="fetched chunk 3/4, size: 524288" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d00001e0210366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d00001e0210366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","seek":0,"duration":74,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:06 volumio volumio[1335]: verbose: CURRENT POSITION 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:06 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:06 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:06 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:06 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:06 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 1 Oct 02 23:45:20 volumio volumio[1335]: info: gpio-buttons: button2 triggered: volumeUp Oct 02 23:45:20 volumio volumio[1335]: info: gpio-buttons: Action: volumeUp Oct 02 23:45:20 volumio volumio[1335]: info: VolumeController::SetAlsaVolume+ Oct 02 23:45:20 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:20 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:20 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:20 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:20 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:20 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:20 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:20 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 2 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 1 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 2 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:45:20 volumio volumio[1335]: info: gpio-buttons: button2 triggered: volumeUp Oct 02 23:45:20 volumio volumio[1335]: info: gpio-buttons: Action: volumeUp Oct 02 23:45:20 volumio volumio[1335]: info: VolumeController::SetAlsaVolume+ Oct 02 23:45:20 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:20 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:20 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:20 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:20 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:20 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:20 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:20 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 3 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 1 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 3 Oct 02 23:45:20 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:45:20 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 3 Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: button2 triggered: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: Action: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: VolumeController::SetAlsaVolume+ Oct 02 23:45:21 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 4 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 3 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 4 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: button2 triggered: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: Action: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: VolumeController::SetAlsaVolume+ Oct 02 23:45:21 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 3 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 5 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:45:21 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 5 Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: button2 triggered: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: Action: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: VolumeController::SetAlsaVolume+ Oct 02 23:45:21 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 6 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 5 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 6 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: false Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: button2 triggered: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: gpio-buttons: Action: volumeUp Oct 02 23:45:21 volumio volumio[1335]: info: VolumeController::SetAlsaVolume+ Oct 02 23:45:21 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:21 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:21 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:21 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: SPOTIFY VOLUME 5 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: VOLUMIO VOLUME 7 Oct 02 23:45:21 volumio volumio[1335]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 02 23:45:21 volumio volumio[1335]: info: Setting Spotify Volume from Volumio: 7 Oct 02 23:45:22 volumio go-librespot[1828]: time="2026-10-02T23:45:22+02:00" level=debug msg="fetched chunk 4/4, size: 293306" uri="spotify:track:6ss8UCk6qEj8yZPEUTmYQR" Oct 02 23:45:23 volumio volumio[1335]: SPOTIFY: SETTING SPOTIFY VOLUME 7 Oct 02 23:45:23 volumio volumio[1335]: info: Sending Spotify command with payload to local API: /player/volume Oct 02 23:45:23 volumio go-librespot[1828]: time="2026-10-02T23:45:23+02:00" level=debug msg="update volume requested to 4587/65535" Oct 02 23:45:23 volumio go-librespot[1828]: time="2026-10-02T23:45:23+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 02 23:45:23 volumio go-librespot[1828]: time="2026-10-02T23:45:23+02:00" level=trace msg="emitting websocket event: volume" Oct 02 23:45:23 volumio volumio[1335]: SPOTIFY: received: {"type":"volume","data":{"max":100,"value":7}} Oct 02 23:45:23 volumio volumio[1335]: SPOTIFY: RECEIVED SPOTIFY VOLUME 7 Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::volumioNext Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::next Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::stop Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::stPlaybackTimer Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:30 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:30 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::serviceStop Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 0 Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::serviceStop Oct 02 23:45:30 volumio volumio[1335]: info: Spotify Stop Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: SPOTIFY STOP Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: {"status":"play","position":0,"title":"waking up fucking early (epic adventure)","artist":"wake up alarms","album":"waking up fucking early (epic adventure)","albumart":"https://i.scdn.co/image/ab67616d0000b27310366c635bb46a95b7e1b0d4","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","trackType":"spotify","codec":"ogg","seek":15006,"duration":74,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":7,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Oct 02 23:45:30 volumio volumio[1335]: info: Sending Spotify command to local API: /player/pause Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::play index undefined Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::startPlaybackTimer Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 02 23:45:30 volumio volumio[1335]: info: [1790977530843] ControllerSpotify::clearAddPlayTrack Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=debug msg="pause track at 24244ms" Oct 02 23:45:30 volumio volumio[1335]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=trace msg="emitting websocket event: paused" Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","uri":"spotify:track:6ss8UCk6qEj8yZPEUTmYQR","play_origin":"go-librespot"}} Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:30 volumio volumio[1335]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:30 volumio volumio[1335]: verbose: CURRENT POSITION 8 Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::syncState stateService pause Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:30 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:30 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:30 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:30 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:30 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (opera)","artist":"Diego Daly","album":"waking up fucking early (opera)","albumart":"https://i.scdn.co/image/ab67616d0000b273e7d61a327eeab0934daaaab0","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","trackType":"spotify","seek":0,"duration":67,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:30 volumio volumio[1335]: verbose: CURRENT POSITION 8 Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:30 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:30 volumio volumio[1335]: info: Sending Spotify command with payload to local API: /player/play Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=debug msg="resolved context of track" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:30 volumio go-librespot[1828]: time="2026-10-02T23:45:30+02:00" level=trace msg="emitting websocket event: will_play" Oct 02 23:45:30 volumio volumio[1335]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","play_origin":"go-librespot"}} Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="selected format OGG_VORBIS_320 (0a3489de84af69135ce2ca0dee07574cd206fe82)" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="requested aes key for file 0a3489de84af69135ce2ca0dee07574cd206fe82, gid: 2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="fetched first chunk of 5, total size is 2279722 bytes" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="track has no narration" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=info msg="loaded track \"waking up fucking early (opera)\" (paused: false, position: 0ms, duration: 67320ms, prefetched: false)" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=trace msg="scheduling prefetch in 37s" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=trace msg="emitting websocket event: metadata" Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: received: {"type":"metadata","data":{"album_cover_url":"https://i.scdn.co/image/ab67616d00001e02e7d61a327eeab0934daaaab0","album_name":"waking up fucking early (opera)","artist_names":["Diego Daly","Diego Eduardo Daly","GenerativeAI","wake up alarms"],"bit_depth":null,"bitrate":320,"codec":"vorbis","disc_number":1,"duration":67320,"format":"OGG_VORBIS_320","name":"waking up fucking early (opera)","position":0,"release_date":"year:2026 month:9 day:1","sample_rate":44100,"track_number":1,"uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab"}} Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="fetched chunk 3/4, size: 524288" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=trace msg="emitting websocket event: playing" Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","resume":false,"play_origin":"go-librespot"}} Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (opera)","artist":"Diego Daly, Diego Eduardo Daly, GenerativeAI, wake up alarms","album":"waking up fucking early (opera)","albumart":"https://i.scdn.co/image/ab67616d00001e02e7d61a327eeab0934daaaab0","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","trackType":"spotify","seek":0,"duration":67,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (opera)","artist":"Diego Daly, Diego Eduardo Daly, GenerativeAI, wake up alarms","album":"waking up fucking early (opera)","albumart":"https://i.scdn.co/image/ab67616d00001e02e7d61a327eeab0934daaaab0","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","trackType":"spotify","seek":0,"duration":67,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:31 volumio volumio[1335]: verbose: CURRENT POSITION 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:31 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="fetched chunk 2/4, size: 524288" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="fetched chunk 1/4, size: 524288" uri="spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab" Oct 02 23:45:31 volumio go-librespot[1828]: time="2026-10-02T23:45:31+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (opera)","artist":"Diego Daly, Diego Eduardo Daly, GenerativeAI, wake up alarms","album":"waking up fucking early (opera)","albumart":"https://i.scdn.co/image/ab67616d00001e02e7d61a327eeab0934daaaab0","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","trackType":"spotify","seek":0,"duration":67,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (opera)","artist":"Diego Daly, Diego Eduardo Daly, GenerativeAI, wake up alarms","album":"waking up fucking early (opera)","albumart":"https://i.scdn.co/image/ab67616d00001e02e7d61a327eeab0934daaaab0","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","trackType":"spotify","seek":0,"duration":67,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:31 volumio volumio[1335]: verbose: CURRENT POSITION 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:31 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:31 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:31 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:31 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:31 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::volumioNext Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::next Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::stop Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::stPlaybackTimer Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:35 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:35 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::serviceStop Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 8 Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::serviceStop Oct 02 23:45:35 volumio volumio[1335]: info: Spotify Stop Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: SPOTIFY STOP Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: {"status":"play","position":8,"title":"waking up fucking early (opera)","artist":"Diego Daly","album":"waking up fucking early (opera)","albumart":"https://i.scdn.co/image/ab67616d0000b273e7d61a327eeab0934daaaab0","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","trackType":"spotify","codec":"ogg","seek":0,"duration":67,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":7,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Oct 02 23:45:35 volumio volumio[1335]: info: Sending Spotify command to local API: /player/pause Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::play index undefined Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::startPlaybackTimer Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:35 volumio volumio[1335]: info: [1790977535217] ControllerSpotify::clearAddPlayTrack Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::updateTrackBlock Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrackBlock Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="pause track at 3610ms" Oct 02 23:45:35 volumio volumio[1335]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=trace msg="emitting websocket event: paused" Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","uri":"spotify:track:2Q5Ce9UCa7vmXnIzz1J3ab","play_origin":"go-librespot"}} Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:35 volumio volumio[1335]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:35 volumio volumio[1335]: verbose: CURRENT POSITION 7 Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::syncState stateService pause Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:35 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:35 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:35 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:35 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:35 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (chiptune)","artist":"wake up alarms","album":"waking up fucking early (chiptune)","albumart":"https://i.scdn.co/image/ab67616d0000b273be65f44bbc41e28e85baa30d","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","trackType":"spotify","seek":0,"duration":77,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:35 volumio volumio[1335]: verbose: CURRENT POSITION 7 Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:35 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus stop Oct 02 23:45:35 volumio volumio[1335]: info: Sending Spotify command with payload to local API: /player/play Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="resolved context of track" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=trace msg="emitting websocket event: will_play" Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","play_origin":"go-librespot"}} Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="selected format OGG_VORBIS_320 (75a5112eba54ed87b65c25c27c74b377c6f5c75b)" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="requested aes key for file 75a5112eba54ed87b65c25c27c74b377c6f5c75b, gid: 3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="fetched first chunk of 6, total size is 2717098 bytes" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="track has no narration" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=info msg="loaded track \"waking up fucking early (chiptune)\" (paused: false, position: 0ms, duration: 77039ms, prefetched: false)" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=trace msg="scheduling prefetch in 47s" Oct 02 23:45:35 volumio go-librespot[1828]: time="2026-10-02T23:45:35+02:00" level=trace msg="emitting websocket event: metadata" Oct 02 23:45:35 volumio volumio[1335]: SPOTIFY: received: {"type":"metadata","data":{"album_cover_url":"https://i.scdn.co/image/ab67616d00001e02be65f44bbc41e28e85baa30d","album_name":"waking up fucking early (chiptune)","artist_names":["wake up alarms"],"bit_depth":null,"bitrate":320,"codec":"vorbis","disc_number":1,"duration":77039,"format":"OGG_VORBIS_320","name":"waking up fucking early (chiptune)","position":0,"release_date":"year:2025 month:11 day:7","sample_rate":44100,"track_number":1,"uri":"spotify:track:3uREkeRiycIVLQngQTSCHV"}} Oct 02 23:45:36 volumio go-librespot[1828]: time="2026-10-02T23:45:36+02:00" level=debug msg="fetched chunk 2/5, size: 524288" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:36 volumio go-librespot[1828]: time="2026-10-02T23:45:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:36 volumio go-librespot[1828]: time="2026-10-02T23:45:36+02:00" level=trace msg="emitting websocket event: playing" Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","resume":false,"play_origin":"go-librespot"}} Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (chiptune)","artist":"wake up alarms","album":"waking up fucking early (chiptune)","albumart":"https://i.scdn.co/image/ab67616d00001e02be65f44bbc41e28e85baa30d","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","trackType":"spotify","seek":0,"duration":77,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (chiptune)","artist":"wake up alarms","album":"waking up fucking early (chiptune)","albumart":"https://i.scdn.co/image/ab67616d00001e02be65f44bbc41e28e85baa30d","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","trackType":"spotify","seek":0,"duration":77,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:36 volumio volumio[1335]: verbose: CURRENT POSITION 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:36 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio go-librespot[1828]: time="2026-10-02T23:45:36+02:00" level=debug msg="fetched chunk 1/5, size: 524288" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:36 volumio go-librespot[1828]: time="2026-10-02T23:45:36+02:00" level=debug msg="fetched chunk 3/5, size: 524288" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:36 volumio go-librespot[1828]: time="2026-10-02T23:45:36+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: PUSH STATE SPOTIFY Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: {"status":"play","service":"spop","title":"waking up fucking early (chiptune)","artist":"wake up alarms","album":"waking up fucking early (chiptune)","albumart":"https://i.scdn.co/image/ab67616d00001e02be65f44bbc41e28e85baa30d","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","trackType":"spotify","seek":0,"duration":77,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::servicePushState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"waking up fucking early (chiptune)","artist":"wake up alarms","album":"waking up fucking early (chiptune)","albumart":"https://i.scdn.co/image/ab67616d00001e02be65f44bbc41e28e85baa30d","uri":"spotify:track:3uREkeRiycIVLQngQTSCHV","trackType":"spotify","seek":0,"duration":77,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 02 23:45:36 volumio volumio[1335]: verbose: CURRENT POSITION 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::syncState stateService play Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::syncState currentStatus play Oct 02 23:45:36 volumio volumio[1335]: info: Received an update from plugin. extracting info from payload Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreStateMachine::pushState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioPushState Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output update for this device Oct 02 23:45:36 volumio volumio[1335]: info: MRS: Pushing multiroomSync output Oct 02 23:45:36 volumio volumio[1335]: info: CoreCommandRouter::volumioGetState Oct 02 23:45:36 volumio volumio[1335]: info: CorePlayQueue::getTrack 7 Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:36 volumio volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 7 Oct 02 23:45:41 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 02 23:45:41 volumio volumio[1335]: info: In handleBrowseUri, curUri=spotify:artist:1HoWFIcxuejNUZJmzVulh6 Oct 02 23:45:42 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:42wCn8hfJCdP8AjSqH2Ftd Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:3antOT1ExQKLpbdq1KIsL9 Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:0zzN699yXNHLMnJVwqrvqN Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:7gPoyXeNOrwPLYaALJbUGM Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:5umNfxEecl4LDGtjJavPKd Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:3WQAbWa0KfcrgLz0PoDftr Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:35AY4SRNSa3DB9lOuJU3FB Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:6FZjX5mOt11WxaZnwhVpNL Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:4Z9zhjlX5OVeBTD3tHlVSv Oct 02 23:45:42 volumio volumio[1335]: info: Preloading song: spotify:track:3b3MVE2meKkol9U08Ull0n Oct 02 23:45:42 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:42 volumio volumio[1335]: info: Preload queue cleared Oct 02 23:45:43 volumio volumio[1335]: info: Executing endpoint getSimilarArtists Oct 02 23:45:43 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 02 23:45:43 volumio volumio[1335]: info: Executing endpoint metavolumio Oct 02 23:45:43 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 23:45:43 volumio volumio[1335]: info: Executing endpoint metavolumio Oct 02 23:45:43 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 02 23:45:43 volumio volumio[1335]: info: Executing endpoint getSimilarArtists Oct 02 23:45:43 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Oct 02 23:45:51 volumio go-librespot[1828]: time="2026-10-02T23:45:51+02:00" level=debug msg="fetched chunk 4/5, size: 524288" uri="spotify:track:3uREkeRiycIVLQngQTSCHV" Oct 02 23:45:55 volumio volumio[1335]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 02 23:45:55 volumio volumio[1335]: info: In handleBrowseUri, curUri=spotify:artist:2rrclywCzv5WiHXjcGvPcI Oct 02 23:45:55 volumio volumio[1335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 23:45:55 volumio volumio[1335]: TypeError: Cannot read properties of undefined (reading 'url') Oct 02 23:45:55 volumio volumio[1335]: at /data/plugins/music_service/spop/index.js:3360:60 Oct 02 23:45:55 volumio volumio[1335]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 02 23:45:55 volumio volumio[1335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 23:45:55 volumio sudo[3910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 23:44' Oct 02 23:45:55 volumio sudo[3910]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"