Mar 04 19:36:06 loe volumio[8359]: info: Mar 04 19:36:06 loe volumio[8359]: ---------------------------- MPD announces system playlist update Mar 04 19:36:06 loe volumio[8359]: info: Ignoring MPD Status Update Mar 04 19:36:06 loe volumio[8359]: info: Mar 04 19:36:06 loe volumio[8359]: ---------------------------- MPD announces state update: player Mar 04 19:36:06 loe volumio[8359]: info: ControllerMpd::getState Mar 04 19:36:06 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:36:06 loe volumio[8359]: info: ------------------------------ 3ms Mar 04 19:36:06 loe volumio[8359]: info: sendMpdCommand status took 2 milliseconds Mar 04 19:36:06 loe volumio[8359]: verbose: ControllerMpd::parseState Mar 04 19:36:06 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:36:06 loe volumio[8359]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 04 19:36:06 loe volumio[8359]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:36:06 loe volumio[8359]: info: ControllerMpd::pushState Mar 04 19:36:06 loe volumio[8359]: info: CoreCommandRouter::servicePushState Mar 04 19:36:06 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:06 loe volumio[8359]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"744 Kbps","isStreaming":false,"title":"0.flac?token=1772652943~NGY3NWI1M2EyYmFlODVkNmQyNmZhMDViNDdhMDBlOGNkOWExNTVkMQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiOWIzZDk1NzFhZTQ3YWVlOGYyNmNlZmFiYmFiYWViZV82MS5tcDQ/0.flac?token=1772652943~NGY3NWI1M2EyYmFlODVkNmQyNmZhMDViNDdhMDBlOGNkOWExNTVkMQ==","trackType":"tidal"} Mar 04 19:36:06 loe volumio[8359]: verbose: CURRENT POSITION 4 Mar 04 19:36:06 loe volumio[8359]: info: CoreStateMachine::syncState stateService play Mar 04 19:36:06 loe volumio[8359]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:36:06 loe volumio[8359]: info: Received an update from plugin. extracting info from payload Mar 04 19:36:06 loe volumio[8359]: info: CoreStateMachine::pushState Mar 04 19:36:06 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:06 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:36:06 loe volumio[8359]: info: CoreCommandRouter::volumioPushState Mar 04 19:36:06 loe volumio[8359]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:36:06 loe volumio[8359]: info: MRS: Pushing multiroomSync output Mar 04 19:36:06 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:06 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:06 loe volumio[8359]: info: CoreStateMachine::pushState Mar 04 19:36:06 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:06 loe volumio[8359]: info: CoreCommandRouter::volumioPushState Mar 04 19:36:06 loe volumio[8359]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:36:06 loe volumio[8359]: info: MRS: Pushing multiroomSync output Mar 04 19:36:06 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:06 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:06 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:06.189+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" state=STATUS_PLAYING positionMs=0 volume=5 Mar 04 19:36:06 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:06.189+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" id=tidal://song/283538064 title="Davidde penitente, K. 469: Duet. Sorgi, o Signore, e spargi (Soprano 1 and 2)" Mar 04 19:36:06 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:06.195+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" state=STATUS_PLAYING positionMs=0 volume=5 Mar 04 19:36:06 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:06.195+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" id=tidal://song/283538064 title="Davidde penitente, K. 469: Duet. Sorgi, o Signore, e spargi (Soprano 1 and 2)" Mar 04 19:36:06 loe volumio[8359]: info: ------------------------------ 68ms Mar 04 19:36:06 loe volumio[8359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:36:06 loe volumio[8359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:36:31 loe kernel: hwmon hwmon1: Voltage normalised Mar 04 19:36:33 loe kernel: hwmon hwmon1: Undervoltage detected! Mar 04 19:36:37 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:37 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:37 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:37 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:48 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Mar 04 19:36:49 loe volumio[8359]: info: browseTIDALUri took 438 milliseconds Mar 04 19:36:49 loe volumio[8359]: info: Preload queue cleared Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:49 loe volumio[8359]: info: Executing endpoint metavolumio Mar 04 19:36:49 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 04 19:36:53 loe volumio[8359]: info: Preload queue cleared Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::ClearQueue Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::stop Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::stPlaybackTimer Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::updateTrackBlock Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrackBlock Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::pushState Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioPushState Mar 04 19:36:53 loe volumio[8359]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:36:53 loe volumio[8359]: info: MRS: Pushing multiroomSync output Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::serviceStop Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 4 Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::serviceStop Mar 04 19:36:53 loe volumio[8359]: info: [1772649413767] ControllerTidal::stop Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 19:36:53 loe volumio[8359]: info: ControllerMpd::stop Mar 04 19:36:53 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::clearPlayQueue Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::saveQueue Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::addQueueItems Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::addQueueItems Mar 04 19:36:53 loe volumio[8359]: info: Preload queue cleared Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699278 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699278 Mar 04 19:36:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:53.774+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" state=STATUS_STOPPED positionMs=0 volume=5 Mar 04 19:36:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:53.775+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" id=tidal://song/283538064 title="Davidde penitente, K. 469: Duet. Sorgi, o Signore, e spargi (Soprano 1 and 2)" Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::saveQueue Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::updateTrackBlock Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrackBlock Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioPlay Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::play index 0 Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::addQueueItems Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::addQueueItems Mar 04 19:36:53 loe volumio[8359]: info: Preload queue cleared Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699279 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699279 Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699280 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699280 Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699281 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699281 Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699282 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699282 Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699283 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699283 Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699284 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699284 Mar 04 19:36:53 loe volumio[8359]: info: Adding Item to queue: tidal://song/77699285 Mar 04 19:36:53 loe volumio[8359]: info: Using cached record of: tidal://song/77699285 Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::stop Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioPushQueue Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::saveQueue Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::play index undefined Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::updateTrackBlock Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrackBlock Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::startPlaybackTimer Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:53 loe volumio[8359]: info: [1772649413867] ControllerTidal::clearAddPlayTrack Mar 04 19:36:53 loe volumio[8359]: info: Getting stream with soundQuality LOSSLESS Mar 04 19:36:53 loe volumio[8359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:36:53 loe volumio[8359]: info: Mar 04 19:36:53 loe volumio[8359]: ---------------------------- MPD announces state update: player Mar 04 19:36:53 loe volumio[8359]: info: sendMpdCommand stop took 155 milliseconds Mar 04 19:36:53 loe volumio[8359]: info: ControllerMpd::getState Mar 04 19:36:53 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:36:53 loe volumio[8359]: info: sendMpdCommand status took 4 milliseconds Mar 04 19:36:53 loe volumio[8359]: verbose: ControllerMpd::parseState Mar 04 19:36:53 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:36:53 loe volumio[8359]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 19:36:53 loe volumio[8359]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:36:53 loe volumio[8359]: info: ControllerMpd::pushState Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::servicePushState Mar 04 19:36:53 loe volumio[8359]: info: CoreStateMachine::pushState Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioPushState Mar 04 19:36:53 loe volumio[8359]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:36:53 loe volumio[8359]: info: MRS: Pushing multiroomSync output Mar 04 19:36:53 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:53 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:53 loe volumio[8359]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Mar 04 19:36:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:53.974+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" state=STATUS_STOPPED positionMs=7 volume=5 Mar 04 19:36:53 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:53.975+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" id=tidal://song/77699278 title="The Lover Of Beirut" Mar 04 19:36:54 loe volumio[8359]: info: ------------------------------ 99ms Mar 04 19:36:54 loe volumio[8359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:36:54 loe volumio[8359]: info: getStreamUrl took 181 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand stop took 2 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 19:36:54 loe volumio[8359]: info: Mar 04 19:36:54 loe volumio[8359]: ---------------------------- MPD announces system playlist update Mar 04 19:36:54 loe volumio[8359]: info: Ignoring MPD Status Update Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand clear took 3 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1NTdjZmY3OTg5NDI4OGViYjM2OTU2OWI5NGMyZGY5Ny5tcDQ/0.flac?token=1772653013~ZGQzZjA2ODg2YTliNGQ0ZDFmZTVlNzJjZTE2YTY3NDVmNjIwMTA4MQ==" Mar 04 19:36:54 loe volumio[8359]: error: updateQueue error: null Mar 04 19:36:54 loe volumio[8359]: info: Mar 04 19:36:54 loe volumio[8359]: ---------------------------- MPD announces system playlist update Mar 04 19:36:54 loe volumio[8359]: info: Ignoring MPD Status Update Mar 04 19:36:54 loe volumio[8359]: info: ------------------------------ 8ms Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1NTdjZmY3OTg5NDI4OGViYjM2OTU2OWI5NGMyZGY5Ny5tcDQ/0.flac?token=1772653013~ZGQzZjA2ODg2YTliNGQ0ZDFmZTVlNzJjZTE2YTY3NDVmNjIwMTA4MQ==" took 6 milliseconds Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand play Mar 04 19:36:54 loe volumio[8359]: info: ------------------------------ 5ms Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand play took 3 milliseconds Mar 04 19:36:54 loe volumio[8359]: info: Mar 04 19:36:54 loe volumio[8359]: ---------------------------- MPD announces state update: player Mar 04 19:36:54 loe volumio[8359]: info: ControllerMpd::getState Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand status took 51 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::parseState Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:36:54 loe volumio[8359]: info: Mar 04 19:36:54 loe volumio[8359]: ---------------------------- MPD announces state update: player Mar 04 19:36:54 loe volumio[8359]: info: ControllerMpd::getState Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand status Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:36:54 loe volumio[8359]: info: ControllerMpd::pushState Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::servicePushState Mar 04 19:36:54 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:54 loe volumio[8359]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1772653013~ZGQzZjA2ODg2YTliNGQ0ZDFmZTVlNzJjZTE2YTY3NDVmNjIwMTA4MQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1NTdjZmY3OTg5NDI4OGViYjM2OTU2OWI5NGMyZGY5Ny5tcDQ/0.flac?token=1772653013~ZGQzZjA2ODg2YTliNGQ0ZDFmZTVlNzJjZTE2YTY3NDVmNjIwMTA4MQ==","trackType":"tidal"} Mar 04 19:36:54 loe volumio[8359]: verbose: CURRENT POSITION 0 Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::syncState stateService play Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::syncState currentStatus stop Mar 04 19:36:54 loe volumio[8359]: info: ------------------------------ 74ms Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand status took 12 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::parseState Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 19:36:54 loe volumio[8359]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 04 19:36:54 loe volumio[8359]: verbose: ControllerMpd::parseTrackInfo Mar 04 19:36:54 loe volumio[8359]: info: ControllerMpd::pushState Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::servicePushState Mar 04 19:36:54 loe volumio[8359]: info: CorePlayQueue::getTrack 0 Mar 04 19:36:54 loe volumio[8359]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":457,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"308 Kbps","isStreaming":false,"title":"0.flac?token=1772653013~ZGQzZjA2ODg2YTliNGQ0ZDFmZTVlNzJjZTE2YTY3NDVmNjIwMTA4MQ==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ1NTdjZmY3OTg5NDI4OGViYjM2OTU2OWI5NGMyZGY5Ny5tcDQ/0.flac?token=1772653013~ZGQzZjA2ODg2YTliNGQ0ZDFmZTVlNzJjZTE2YTY3NDVmNjIwMTA4MQ==","trackType":"tidal"} Mar 04 19:36:54 loe volumio[8359]: verbose: CURRENT POSITION 0 Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::syncState stateService play Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::syncState currentStatus play Mar 04 19:36:54 loe volumio[8359]: info: Received an update from plugin. extracting info from payload Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::pushState Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::volumioPushState Mar 04 19:36:54 loe volumio[8359]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:36:54 loe volumio[8359]: info: MRS: Pushing multiroomSync output Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:54 loe volumio[8359]: info: CoreStateMachine::pushState Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::volumioPushState Mar 04 19:36:54 loe volumio[8359]: info: MRS: Pushing multiroomSync output update for this device Mar 04 19:36:54 loe volumio[8359]: info: MRS: Pushing multiroomSync output Mar 04 19:36:54 loe volumio[8359]: info: CoreCommandRouter::volumioGetState Mar 04 19:36:54 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:54.238+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" state=STATUS_PLAYING positionMs=0 volume=5 Mar 04 19:36:54 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:54.239+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" id=tidal://song/77699278 title="The Lover Of Beirut" Mar 04 19:36:54 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:54.252+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" state=STATUS_PLAYING positionMs=0 volume=5 Mar 04 19:36:54 loe volumio5-onboarding[2437]: time=2026-03-04T19:36:54.253+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.147:58416 @ 0x279ef60" id=tidal://song/77699278 title="The Lover Of Beirut" Mar 04 19:36:54 loe volumio[8359]: info: ------------------------------ 225ms Mar 04 19:36:54 loe volumio[8359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:36:54 loe volumio[8359]: SPOTIFY: RECEIVED VOLUMIO VOLUME 5 Mar 04 19:37:23 loe go-librespot[8609]: time="2026-03-04T19:37:23+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.178.145:59414->34.158.1.133:4070: read: connection reset by peer" Mar 04 19:37:23 loe systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 04 19:37:23 loe systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 04 19:37:23 loe volumio[8359]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Mar 04 19:37:23 loe volumio[8359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:37:23 loe volumio[8359]: Error: socket hang up Mar 04 19:37:23 loe volumio[8359]: at connResetException (node:internal/errors:720:14) Mar 04 19:37:23 loe volumio[8359]: at Socket.socketOnEnd (node:_http_client:519:23) Mar 04 19:37:23 loe volumio[8359]: at Socket.emit (node:events:526:35) Mar 04 19:37:23 loe volumio[8359]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 04 19:37:23 loe volumio[8359]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 04 19:37:23 loe volumio[8359]: code: 'ECONNRESET', Mar 04 19:37:23 loe volumio[8359]: response: undefined Mar 04 19:37:23 loe volumio[8359]: } Mar 04 19:37:23 loe volumio[8359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 19:37:24 loe sudo[9809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 19:36' Mar 04 19:37:24 loe sudo[9809]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"