-- Logs begin at Thu 2026-03-26 19:45:51 CET, end at Thu 2026-03-26 19:56:08 CET. -- Mar 26 19:55:06 volumio volumio[1119]: verbose: ----------------------------------- Mar 26 19:55:06 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 25) (YouTube Music) Incoming message: 'noop' Mar 26 19:55:06 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 25) (YouTube Music) Not handled: 'noop' Mar 26 19:55:16 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:16.378+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=375.988376ms timeout=3s volume=60 Mar 26 19:55:16 volumio volumio[1119]: info: VolumeController::SetAlsaVolume60 Mar 26 19:55:16 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:16 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:16 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:16 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:16 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:16 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:16.396+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=129963 volume=60 Mar 26 19:55:16 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:16.397+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:16 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:16.747+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=375.097914ms timeout=3s volume=59 Mar 26 19:55:16 volumio volumio[1119]: info: VolumeController::SetAlsaVolume59 Mar 26 19:55:16 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:16 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:16 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:16 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:16 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:16 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:16 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:16.763+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=130424 volume=59 Mar 26 19:55:16 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:16.763+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:16 volumio volumio[1119]: verbose: ----------------------------------- Mar 26 19:55:16 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 25) (YouTube) Incoming message: 'noop' Mar 26 19:55:16 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 25) (YouTube) Not handled: 'noop' Mar 26 19:55:17 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.113+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=377.830119ms timeout=3s volume=58 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume58 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.130+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=130713 volume=58 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.130+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.371+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=384.359179ms timeout=3s volume=57 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume57 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.387+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=130963 volume=57 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.387+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.421+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=373.983289ms timeout=3s volume=56 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume56 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.437+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=130963 volume=56 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.437+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.461+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=376.826962ms timeout=3s volume=55 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume55 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.478+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=130963 volume=55 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.479+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.504+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.372308ms timeout=3s volume=54 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume54 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.520+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131174 volume=54 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.520+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.555+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=373.777554ms timeout=3s volume=53 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume53 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.572+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131216 volume=53 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.572+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.605+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.208037ms timeout=3s volume=52 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume52 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.621+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131216 volume=52 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.622+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.658+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.17942ms timeout=3s volume=51 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume51 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.674+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131216 volume=51 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.674+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.708+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=375.158972ms timeout=3s volume=50 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume50 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.724+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131216 volume=50 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.724+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.765+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=375.562249ms timeout=3s volume=49 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume49 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.782+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131424 volume=49 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.782+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.809+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.138921ms timeout=3s volume=48 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume48 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.824+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131478 volume=48 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.824+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.863+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=376.254889ms timeout=3s volume=47 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume47 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.880+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131478 volume=47 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.880+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.920+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.522353ms timeout=3s volume=46 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume46 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.938+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131478 volume=46 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.938+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.974+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.012102ms timeout=3s volume=45 Mar 26 19:55:17 volumio volumio[1119]: info: VolumeController::SetAlsaVolume45 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:17 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:17 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.990+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131478 volume=45 Mar 26 19:55:17 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:17.990+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.022+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=375.94104ms timeout=3s volume=44 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume44 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.038+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131685 volume=44 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.038+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.140+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=398.853827ms timeout=3s volume=42 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.141+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=450.106455ms timeout=3s volume=43 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume42 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.156+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131732 volume=42 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume43 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.159+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.174+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131732 volume=43 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.174+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.181+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.220856ms timeout=3s volume=41 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume41 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.197+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131732 volume=41 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.197+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.223+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.847462ms timeout=3s volume=40 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume40 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.241+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131732 volume=40 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.241+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.267+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.156303ms timeout=3s volume=39 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume39 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.281+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=131939 volume=39 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.281+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.824+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=377.621077ms timeout=3s volume=38 Mar 26 19:55:18 volumio volumio[1119]: info: VolumeController::SetAlsaVolume38 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:18 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:18 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.840+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=132483 volume=38 Mar 26 19:55:18 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:18.840+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.076+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=378.619368ms timeout=3s volume=37 Mar 26 19:55:19 volumio volumio[1119]: info: VolumeController::SetAlsaVolume37 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.093+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=132734 volume=37 Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.094+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.116+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.652752ms timeout=3s volume=36 Mar 26 19:55:19 volumio volumio[1119]: info: VolumeController::SetAlsaVolume36 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.134+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=132734 volume=36 Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.135+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.170+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=373.303077ms timeout=3s volume=35 Mar 26 19:55:19 volumio volumio[1119]: info: VolumeController::SetAlsaVolume35 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.188+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=132734 volume=35 Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.189+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.215+01:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.8.100:36740 @ 0x220eb70" latency=374.123445ms timeout=3s volume=34 Mar 26 19:55:19 volumio volumio[1119]: info: VolumeController::SetAlsaVolume34 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreStateMachine::pushState Mar 26 19:55:19 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 26 19:55:19 volumio volumio[1119]: info: CoreCommandRouter::volumioPushState Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.232+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" state=STATUS_PLAYING positionMs=132734 volume=34 Mar 26 19:55:19 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:19.232+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.8.100:36740 @ 0x220eb70" id="mnt/USB/1TBczarny/Muzyka/Demo/02.Rebecca Pidgeon - Spanish Harlem.flac" title="Spanish Harlem" Mar 26 19:55:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 19:55:22 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 19:55:24 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:55:31 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:55:36 volumio volumio[1119]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... Mar 26 19:55:36 volumio volumio[1119]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=25&CI=0&RID=rpc&SID=5980AD307F4EDFFA&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=TQTu4CMHazapp2Ex5PcONn9Oko_x_y-E&id=9b0e152d-9b9d-4c82-ac25-e3a00acf3e96&loungeIdToken=AGdO5p9iSQr7zhGPE6GPD91do0xLjTimTAUZrckj27NkcxYD7ldB9APGnrKE_nKduzQzzPPMwN4aai_Affxv4NojbYOMr3V_02T6AusEU5dukJ1YLbTIU64&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=a404a73f5679 Mar 26 19:55:36 volumio volumio[1119]: verbose: [yt-cast-receiver] RPC connection established. Mar 26 19:55:36 volumio volumio[1119]: verbose: ----------------------------------- Mar 26 19:55:36 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 27) (YouTube Music) Incoming message: 'noop' Mar 26 19:55:36 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 27) (YouTube Music) Not handled: 'noop' Mar 26 19:55:38 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:55:43 volumio volumio[1119]: verbose: ----------------------------------- Mar 26 19:55:43 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 26) (YouTube) Incoming message: 'noop' Mar 26 19:55:43 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 26) (YouTube) Not handled: 'noop' Mar 26 19:55:45 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:55:52 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:55:54 volumio volumio[1119]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf Mar 26 19:55:54 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:54.866+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/spop/volumio/buster/armhf Mar 26 19:55:56 volumio volumio[1119]: info: Folder /tmp/plugins removed Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.566+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.741+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: Check plugin dependencies Mar 26 19:55:56 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.769+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: Checking if plugin already exists Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.771+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: Rename folder Mar 26 19:55:56 volumio volumio[1119]: info: Folder /tmp/downloaded_plugin.zip removed Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.792+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: Move to category Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.858+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: Checking if install.sh is present Mar 26 19:55:56 volumio volumio[1119]: info: Executing install.sh Mar 26 19:55:56 volumio sudo[4474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Mar 26 19:55:56 volumio sudo[4474]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 19:55:56 volumio volumio[1119]: info: Installing Go-librespot Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.893+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio volumio[1119]: info: Checking old vollibrespot installs Mar 26 19:55:56 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:56.896+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:56 volumio systemd[1]: Reloading. Mar 26 19:55:57 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 26 19:55:57 volumio volumio[1119]: info: Downloading daemon Mar 26 19:55:57 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:57.247+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:58 volumio volumio[1119]: info: Creating directories Mar 26 19:55:58 volumio volumio[1119]: info: Creating data directory Mar 26 19:55:58 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:58.156+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:58 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:58.157+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:58 volumio volumio[1119]: info: Creating Start Script Mar 26 19:55:58 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:58.160+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:58 volumio systemd[1]: Reloading. Mar 26 19:55:58 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 26 19:55:58 volumio sudo[4474]: pam_unix(sudo:session): session closed for user root Mar 26 19:55:58 volumio volumio[1119]: Plugin install end detected on script Mar 26 19:55:58 volumio volumio[1119]: info: Install script completed Mar 26 19:55:58 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:58.488+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:58 volumio volumio[1119]: info: Adding reference to registry Mar 26 19:55:58 volumio volumio5-onboarding[2044]: time=2026-03-26T19:55:58.490+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 26 19:55:58 volumio volumio[1119]: info: Done installing plugin. Mar 26 19:55:58 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 26 19:55:58 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 26 19:55:58 volumio volumio[1119]: info: Folder /tmp/plugins removed Mar 26 19:55:58 volumio volumio[1119]: info: Folder /tmp/downloaded_plugin.zip removed Mar 26 19:55:58 volumio volumio[1119]: info: Folder /data/temp removed Mar 26 19:55:59 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:56:01 volumio volumio[1119]: info: Enabling plugin spop Mar 26 19:56:01 volumio volumio[1119]: info: Loading plugin "spop"... Mar 26 19:56:01 volumio volumio[1119]: info: PLUGIN START: spop Mar 26 19:56:01 volumio volumio[1119]: info: Creating Spotify config file Mar 26 19:56:01 volumio volumio[1119]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 26 19:56:01 volumio volumio[1119]: info: Done. Mar 26 19:56:01 volumio volumio[1119]: info: Spotify config file written Mar 26 19:56:01 volumio volumio[1119]: info: No need to fix Spotify hosts Mar 26 19:56:01 volumio sudo[4547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 26 19:56:01 volumio sudo[4547]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 26 19:56:01 volumio systemd[1]: Started go-librespot Daemon. Mar 26 19:56:01 volumio go-librespot[4549]: go-librespot daemon starting... Mar 26 19:56:01 volumio sudo[4547]: pam_unix(sudo:session): session closed for user root Mar 26 19:56:01 volumio go-librespot[4549]: time="2026-03-26T19:56:01+01:00" level=info msg="running go-librespot 0.7.1" Mar 26 19:56:01 volumio go-librespot[4549]: time="2026-03-26T19:56:01+01:00" level=debug msg="no app state found" Mar 26 19:56:01 volumio go-librespot[4549]: time="2026-03-26T19:56:01+01:00" level=debug msg="stored credentials not found" Mar 26 19:56:01 volumio go-librespot[4549]: time="2026-03-26T19:56:01+01:00" level=info msg="generated new device id: b2f603142e2f4ec369637c31a6adba84fc98aa32" Mar 26 19:56:01 volumio go-librespot[4549]: time="2026-03-26T19:56:01+01:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 26 19:56:02 volumio go-librespot[4549]: time="2026-03-26T19:56:02+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 26 19:56:02 volumio go-librespot[4549]: time="2026-03-26T19:56:02+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 26 19:56:02 volumio go-librespot[4549]: time="2026-03-26T19:56:02+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 26 19:56:02 volumio go-librespot[4549]: time="2026-03-26T19:56:02+01:00" level=info msg="zeroconf server listening on port 37857" Mar 26 19:56:02 volumio go-librespot[4549]: time="2026-03-26T19:56:02+01:00" level=info msg="using avahi-daemon avahi 0.7 for mDNS service registration" Mar 26 19:56:04 volumio volumio[1119]: verbose: ----------------------------------- Mar 26 19:56:04 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 28) (YouTube Music) Incoming message: 'noop' Mar 26 19:56:04 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 28) (YouTube Music) Not handled: 'noop' Mar 26 19:56:04 volumio volumio[1119]: info: go-librespot daemon successfully initialized Mar 26 19:56:06 volumio wpa_supplicant[1006]: RRM: Ignoring radio measurement request: Not RRM network Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="obtained new client token: AACOOK7Hf9UKIgJb6xvXxy8N3BlB0U5ttcYP3/obBL3ZXdfDbZf8Y6rbB9UBrnBvIwSNjwd6+nd/H/6GljwYG2iIe1EqS5PE1xuWQVYVBcwPeG0OuzaWkKcLDets8/cAtjOGxhP9hk/TEVOdH39f9UC7fb6yk0r85ur8k+/gsGQIxant3sohZ+zzF3ORi3MkgUX2iaD3iiewMsm02cE1MWy4c0hoFE1MFD4Tg0/e6y3j3iGhu6CZ4akC" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="completed keyexchange" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="completed challenge" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=info msg="authenticated AP" username="ra***********ki" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=info msg="authenticated Login5" username="ra***********ki" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=info msg="accepted zeroconf from SM-S721B" username="ra***********ki" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="dealer connection opened" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=trace msg="starting accesspoint recv loop" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=trace msg="starting dealer recv loop" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=trace msg="received accesspoint ping" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 26 19:56:06 volumio go-librespot[4549]: time="2026-03-26T19:56:06+01:00" level=debug msg="received connection id: OTJmYTBmZjctMzY3...MEYyRDBBRkQ0Ng==" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="received accesspoint pong ack" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="handling transfer player command from 86984eb781b29cc12fea5a400b6f94260483e825" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="resolved context of track" uri="spotify:album:0nA01XOVBUoi1zDVVYKz4i" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="fetched new page 0 with 13 items (list: 13)" uri="spotify:album:0nA01XOVBUoi1zDVVYKz4i" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="loading track (paused: true, position: 2220ms)" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio volumio[1119]: verbose: [yt-cast-receiver] RPC connection disconnected. Reconnecting... Mar 26 19:56:07 volumio volumio[1119]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=26&CI=0&RID=rpc&SID=9B011B8C98D77506&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=DhEZl7HP4CFxyD4biMg7EXHxr2jTTVFb&id=fd37a3ca-ab2e-42ed-85ff-a6601e2455ff&loungeIdToken=AGdO5p-j6pYb2M4b8mKOKsFmmZjTR2BOVkrWpB077LD5U209kcGF6j_88fT2POGPBEuqeJujqI7LJFs0r6DFfBuFwlPDvN3yrlIR9sQN_Ygd-5NysPq9jdY&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=3aed1e3a38a2 Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="emitting websocket event: will_play" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1142" Mar 26 19:56:07 volumio volumio[1119]: verbose: [yt-cast-receiver] RPC connection established. Mar 26 19:56:07 volumio volumio[1119]: verbose: ----------------------------------- Mar 26 19:56:07 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 28) (YouTube) Incoming message: 'noop' Mar 26 19:56:07 volumio volumio[1119]: verbose: [yt-cast-receiver] (AID: 28) (YouTube) Not handled: 'noop' Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="selected format OGG_VORBIS_320 (9c03186be177e0833b799b68820b7fefe7c2d8ff)" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="requested aes key for file 9c03186be177e0833b799b68820b7fefe7c2d8ff, gid: 2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="fetched first chunk of 19, total size is 9638716 bytes" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="seek to 2220ms (diff: 55ms, samples: 97902, bytes: 84671)" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="created new output device" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=info msg="loaded track \"Ricky's Theme\" (paused: true, position: 2220ms, duration: 224733ms, prefetched: false)" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:07 volumio volumio[1119]: info: Initializing connection to go-librespot Websocket Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="new websocket client" Mar 26 19:56:07 volumio volumio[1119]: info: Connection to go-librespot Websocket established Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="emitting websocket event: metadata" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=trace msg="emitting websocket event: active" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="sending successful reply for dealer request" Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 26 19:56:07 volumio volumio[1119]: info: Aligning Spotify Volume to Volumio Volume Mar 26 19:56:07 volumio volumio[1119]: info: CoreCommandRouter::volumioGetState Mar 26 19:56:07 volumio volumio[1119]: info: CorePlayQueue::getTrack 5 Mar 26 19:56:07 volumio volumio[1119]: info: Setting Spotify Volume from Volumio: 34 Mar 26 19:56:07 volumio go-librespot[4549]: time="2026-03-26T19:56:07+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:08 volumio go-librespot[4549]: time="2026-03-26T19:56:08+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 26 19:56:08 volumio go-librespot[4549]: time="2026-03-26T19:56:08+01:00" level=trace msg="emitting websocket event: paused" Mar 26 19:56:08 volumio volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 19:56:08 volumio go-librespot[4549]: time="2026-03-26T19:56:08+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:08 volumio volumio[1119]: TypeError: Cannot read property 'service' of undefined Mar 26 19:56:08 volumio volumio[1119]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:345:50) Mar 26 19:56:08 volumio volumio[1119]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:279:18) Mar 26 19:56:08 volumio volumio[1119]: at WebSocket.message (/data/plugins/music_service/spop/index.js:198:14) Mar 26 19:56:08 volumio volumio[1119]: at WebSocket.emit (events.js:315:20) Mar 26 19:56:08 volumio volumio[1119]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1225:20) Mar 26 19:56:08 volumio volumio[1119]: at Receiver.emit (events.js:315:20) Mar 26 19:56:08 volumio volumio[1119]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 26 19:56:08 volumio volumio[1119]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 26 19:56:08 volumio volumio[1119]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 26 19:56:08 volumio volumio[1119]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 26 19:56:08 volumio volumio[1119]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 26 19:56:08 volumio volumio[1119]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 26 19:56:08 volumio volumio[1119]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1360:35) Mar 26 19:56:08 volumio volumio[1119]: at Socket.emit (events.js:315:20) Mar 26 19:56:08 volumio volumio[1119]: at addChunk (internal/streams/readable.js:309:12) Mar 26 19:56:08 volumio volumio[1119]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 26 19:56:08 volumio volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 26 19:56:08 volumio go-librespot[4549]: time="2026-03-26T19:56:08+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:2y5PBBac3bYilEkad4qbet" Mar 26 19:56:08 volumio sudo[4580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-26 19:55 Mar 26 19:56:08 volumio sudo[4580]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"