Oct 03 13:39:00 volumio bluealsa[912]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_73_A6_46_1B_C0_FB, ...) Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::volumioPause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::servicePause Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::servicePause Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::pause Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand pause Oct 03 13:39:00 volumio volumio[1207]: info: Oct 03 13:39:00 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand pause took 1 milliseconds Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand status took 0 milliseconds Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:00 volumio volumio[1207]: info: Oct 03 13:39:00 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:00 volumio volumio[1207]: info: Oct 03 13:39:00 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":43580,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"616 Kbps","isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:00 volumio volumio[1207]: verbose: CURRENT POSITION 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.202+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=43458 volume=100 Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.202+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PAUSED positionMs=43458 volume=100 Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.202+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.202+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:00 volumio volumio[1207]: info: ------------------------------ 10ms Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 03 13:39:00 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":43580,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"616 Kbps","isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:00 volumio volumio[1207]: verbose: CURRENT POSITION 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":43580,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"616 Kbps","isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:00 volumio volumio[1207]: verbose: CURRENT POSITION 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.212+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=43458 volume=100 Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.212+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PAUSED positionMs=43458 volume=100 Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.213+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=43458 volume=100 Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.213+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PAUSED positionMs=43458 volume=100 Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:00 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:00.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:00 volumio volumio[1207]: info: ------------------------------ 21ms Oct 03 13:39:00 volumio volumio[1207]: info: ------------------------------ 21ms Oct 03 13:39:00 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:00 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:00 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:00 volumio go-librespot[1637]: time="2026-10-03T13:39:00+01:00" level=error msg="did not receive last pong from dealer, 240s passed" Oct 03 13:39:00 volumio volumio[1207]: info: CoreCommandRouter::volumioPlay Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::play index undefined Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: info: CoreStateMachine::startPlaybackTimer Oct 03 13:39:00 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:00 volumio volumio[1207]: info: ControllerMpd::resume Oct 03 13:39:00 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand play Oct 03 13:39:01 volumio go-librespot[1637]: time="2026-10-03T13:39:01+01:00" level=trace msg="received accesspoint ping" Oct 03 13:39:01 volumio volumio[1207]: info: Oct 03 13:39:01 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand play took 316 milliseconds Oct 03 13:39:01 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:01 volumio volumio[1207]: info: Oct 03 13:39:01 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:01 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:01 volumio volumio[1207]: info: Oct 03 13:39:01 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:01 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:01 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44451,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:01 volumio volumio[1207]: verbose: CURRENT POSITION 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.147+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=43958 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.147+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PLAYING positionMs=43958 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.148+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.148+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio[1207]: info: ------------------------------ 13ms Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand status took 10 milliseconds Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:01 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44451,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:01 volumio volumio[1207]: verbose: CURRENT POSITION 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:01 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.159+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio[1207]: info: ------------------------------ 22ms Oct 03 13:39:01 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:01 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:01 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:01 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 03 13:39:01 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:01 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44451,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"527 Kbps","isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:01 volumio volumio[1207]: verbose: CURRENT POSITION 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:01 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:01 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:01 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:01 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.177+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.177+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.177+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.177+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" state=STATUS_PLAYING positionMs=44451 volume=100 Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.177+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.178+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.178+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.178+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:01 volumio volumio[1207]: info: ------------------------------ 39ms Oct 03 13:39:01 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:01 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:01 volumio go-librespot[1637]: time="2026-10-03T13:39:01+01:00" level=trace msg="received accesspoint pong ack" Oct 03 13:39:01 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:01.761+01:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Oct 03 13:39:01 volumio dbus-daemon[678]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.15" (uid=0 pid=1575 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=840 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioNext Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::next Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::serviceStop Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 14 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::serviceStop Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::stop Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.446+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.446+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.447+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.448+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac" title="Sisters of Mercy" Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand stop took 24 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::play index undefined Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::startPlaybackTimer Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::updateTrackBlock Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrackBlock Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand status took 3 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand stop took 1 milliseconds Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 03 13:39:02 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand clear took 2 milliseconds Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sisters of Mercy","artist":"Leonard Cohen","album":"Songs of Leonard Cohen","uri":"NAS/Synology/Leonard Cohen/Songs of Leonard Cohen/05 Sisters of Mercy.flac","trackType":"flac"} Oct 03 13:39:02 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: No code Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.482+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.482+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.483+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.484+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.484+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 18ms Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 03 13:39:02 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 03 13:39:02 volumio volumio[1207]: error: updateQueue error: null Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 03 13:39:02 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand status took 15 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand status took 15 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 14ms Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand add "NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" took 14 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 2ms Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:02 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand play Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 13:39:02 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: No code Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 13:39:02 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio[1207]: info: No code Oct 03 13:39:02 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:02 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:02 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.500+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.500+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.500+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.500+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.502+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_STOPPED positionMs=0 volume=100 Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.504+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:02.504+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 35ms Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 35ms Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 03 13:39:02 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 03 13:39:02 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 34ms Oct 03 13:39:02 volumio volumio[1207]: info: sendMpdCommand play took 34 milliseconds Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 15ms Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 14ms Oct 03 13:39:02 volumio volumio[1207]: info: Oct 03 13:39:02 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Oct 03 13:39:02 volumio volumio[1207]: info: Ignoring MPD Status Update Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:02 volumio volumio[1207]: info: ------------------------------ 5ms Oct 03 13:39:03 volumio volumio[1207]: info: Oct 03 13:39:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:03 volumio volumio[1207]: info: Oct 03 13:39:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:03 volumio volumio[1207]: info: Oct 03 13:39:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:03 volumio volumio[1207]: info: Oct 03 13:39:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:03 volumio volumio[1207]: info: Oct 03 13:39:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:03 volumio volumio[1207]: info: Oct 03 13:39:03 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand status took 5 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand status took 5 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand status took 5 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:03 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Oct 03 13:39:03 volumio volumio[1207]: info: ------------------------------ 9ms Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand status took 4 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:03 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:03 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:03 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:03 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:03 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:03 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:03 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:03 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.493+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.493+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.493+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.493+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.493+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.494+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio[1207]: info: ------------------------------ 40ms Oct 03 13:39:03 volumio volumio[1207]: info: ------------------------------ 41ms Oct 03 13:39:03 volumio volumio[1207]: info: ------------------------------ 40ms Oct 03 13:39:03 volumio volumio[1207]: info: ------------------------------ 37ms Oct 03 13:39:03 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 32 milliseconds Oct 03 13:39:03 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:03 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1215,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:03 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:39:03 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:03 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:03 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.511+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.511+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.512+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.512+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=1215 volume=100 Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.512+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.512+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.513+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:03.513+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:03 volumio volumio[1207]: info: ------------------------------ 50ms Oct 03 13:39:03 volumio volumio[1207]: info: [LastFM] Current track has sufficient metadata: title (Pistol Grip Pump) and artist (Rage Against the Machine) passed on explicitly Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:03 volumio bluealsa[912]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_48_3D_0E_82_F4_8C, ...) Oct 03 13:39:03 volumio volumio[1207]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Pistol Grip Pump","mbid":"0b97fba4-6595-4f5e-891a-3f3a7e31fc00","url":"https://www.last.fm/music/Rage+Against+the+Machine/_/Pistol+Grip+Pump","duration":"198000","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"343199","playcount":"1633318","artist":{"name":"Rage Against the Machine","mbid":"3798b104-01cb-484c-a3b0-56adc6399b80","url":"https://www.last.fm/music/Rage+Against+the+Machine"},"album":{"artist":"Rage Against the Machine","title":"Renegades","url":"https://www.last.fm/music/Rage+Against+the+Machine/Renegades","image":[{"#":"https://lastfm-img.freetls.fastly.net/i/u/34s/a2eaca0cd6684941c97b8e9c354a2840.png","@":{"size":"small"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/64s/a2eaca0cd6684941c97b8e9c354a2840.png","@":{"size":"medium"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/174s/a2eaca0cd6684941c97b8e9c354a2840.png","@":{"size":"large"}},{"#":"https://lastfm-img.freetls.fastly.net/i/u/300x300/a2eaca0cd6684941c97b8e9c354a2840.png","@":{"size":"extralarge"}}]},"userplaycount":"14","userloved":"0","toptags":""}} Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::servicePause Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::servicePause Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::pause Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand pause Oct 03 13:39:05 volumio volumio[1207]: info: Oct 03 13:39:05 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand pause took 1 milliseconds Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:05 volumio volumio[1207]: info: Oct 03 13:39:05 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:05 volumio volumio[1207]: info: Oct 03 13:39:05 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand status took 1 milliseconds Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 13:39:05 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:05 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3461,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:05 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3461,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:05 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:05 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":3461,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:39:05 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:39:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:39:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:39:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.614+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=3218 volume=100 Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.614+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=3218 volume=100 Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.615+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.615+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.615+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=3218 volume=100 Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.616+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=3218 volume=100 Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.616+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=3218 volume=100 Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.616+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=3218 volume=100 Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.617+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.617+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.617+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:05.617+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:39:05 volumio volumio[1207]: info: ------------------------------ 21ms Oct 03 13:39:05 volumio volumio[1207]: info: ------------------------------ 20ms Oct 03 13:39:05 volumio volumio[1207]: info: ------------------------------ 20ms Oct 03 13:39:05 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:05 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:05 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://functions.volumio.cloud duration=9.999970238s error="Head \"https://functions.volumio.cloud\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://database.volumio.cloud duration=9.999527151s error="Head \"https://database.volumio.cloud\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://functions.volumio.cloud duration=9.999259488s error="Head \"https://functions.volumio.cloud\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://www.googleapis.com duration=10.000288715s error="Head \"https://www.googleapis.com\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://google.com duration=10.00025903s error="Head \"https://google.com\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=10.000346343s error="Head \"https://radio-directory.firebaseapp.com\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://securetoken.googleapis.com duration=10.000285529s error="Head \"https://securetoken.googleapis.com\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.376+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716 @ 0x2000120" latency=-134.766308ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=10.00042838s error="Head \"https://myvolumio.firebaseio.com\": context deadline exceeded" Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.731+01:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.763+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=http://pushupdates.volumio.org duration=31.635868ms Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.763+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=32.016289ms Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.766+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=34.189129ms Oct 03 13:39:07 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:07.994+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=http://cddb.volumio.org duration=262.567094ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 13:39:08 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:08.095+01:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=http://plugins.volumio.org duration=363.674021ms Oct 03 13:39:09 volumio bluealsa[912]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4F_16_DB_75_4E_B1, ...) Oct 03 13:39:10 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 13:39:10 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 13:39:14 volumio volumio[1207]: info: Starting Uninstall of plugin music_service - spop Oct 03 13:39:14 volumio volumio[1207]: info: Uninstalling plugin spop Oct 03 13:39:14 volumio go-librespot[1637]: time="2026-10-03T13:39:14+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Oct 03 13:39:14 volumio volumio[1207]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Oct 03 13:39:14 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 13:39:14 volumio volumio[1207]: Cannot find translation for source LastFM Oct 03 13:39:14 volumio volumio[1207]: Cannot find translation for source Randomizer Oct 03 13:39:14 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:14.890+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:14 volumio volumio[1207]: info: Disabling plugin spop Oct 03 13:39:14 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:14.893+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:14 volumio volumio[1207]: info: Checking if uninstall.sh is present Oct 03 13:39:14 volumio volumio[1207]: info: Connection to go-librespot Websocket closed Oct 03 13:39:14 volumio sudo[15696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Oct 03 13:39:14 volumio sudo[15696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:39:14 volumio volumio[1207]: info: Executing uninstall.sh Oct 03 13:39:14 volumio sudo[15700]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/uninstall.sh Oct 03 13:39:14 volumio sudo[15700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:39:14 volumio sudo[15700]: pam_unix(sudo:session): session closed for user root Oct 03 13:39:14 volumio volumio[1207]: info: Uninstall script completed Oct 03 13:39:14 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:14.982+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:14 volumio volumio[1207]: info: Removing plugin spop from configuration Oct 03 13:39:14 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 03 13:39:14 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Oct 03 13:39:14 volumio systemd[1]: go-librespot-daemon.service: Killing process 12438 (go-librespot) with signal SIGKILL. Oct 03 13:39:15 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Oct 03 13:39:15 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Oct 03 13:39:15 volumio sudo[15696]: pam_unix(sudo:session): session closed for user root Oct 03 13:39:15 volumio volumio[1207]: info: Successfully removed spop configuration files Oct 03 13:39:15 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:15.083+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:15 volumio volumio[1207]: info: Plugin folders cleanup Oct 03 13:39:15 volumio volumio[1207]: info: Scanning into folder /volumio/app/plugins/ Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category audio_interface Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category miscellanea Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category music_service Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category plugins.json Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category system_controller Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category user_interface Oct 03 13:39:15 volumio volumio[1207]: info: Scanning into folder /data/plugins/ Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category music_service Oct 03 13:39:15 volumio volumio[1207]: info: Cleaning folder for spop Oct 03 13:39:15 volumio volumio[1207]: info: Scanning category user_interface Oct 03 13:39:15 volumio volumio[1207]: info: Plugin folders cleanup completed Oct 03 13:39:15 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:15.748+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:15 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 13:39:15 volumio volumio[1207]: info: [{"prettyName":"LastFM Scrobbler","name":"lastfm","category":"user_interface","version":"2.5.6","icon":"fa-lastfm","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"4.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true}] Oct 03 13:39:15 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 13:39:15 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 03 13:39:15 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 03 13:39:17 volumio nmbd[1095]: [2026/10/03 13:39:17.201921, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 03 13:39:17 volumio nmbd[1095]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.51 for name WORKGROUP<1d>. Oct 03 13:39:17 volumio nmbd[1095]: This response was from IP 192.168.1.186, reporting an IP address of 192.168.1.186. Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.731+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://database.volumio.cloud duration=9.999660414s error="Head \"https://database.volumio.cloud\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.731+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://functions.volumio.cloud duration=9.999906189s error="Head \"https://functions.volumio.cloud\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.731+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://google.com duration=10.000214814s error="Head \"https://google.com\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.732+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://securetoken.googleapis.com duration=10.000401053s error="Head \"https://securetoken.googleapis.com\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.732+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://www.googleapis.com duration=10.000478292s error="Head \"https://www.googleapis.com\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.732+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://functions.volumio.cloud duration=10.000326775s error="Head \"https://functions.volumio.cloud\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.732+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=10.000551865s error="Head \"https://radio-directory.firebaseapp.com\": context deadline exceeded" Oct 03 13:39:17 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:17.732+01:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" latency=-62.596749ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=10.000583846s error="Head \"https://myvolumio.firebaseio.com\": context deadline exceeded" Oct 03 13:39:39 volumio volumio[1207]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Oct 03 13:39:39 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:39.033+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:42 volumio volumio[1207]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Oct 03 13:39:42 volumio volumio[1207]: info: Folder /tmp/plugins removed Oct 03 13:39:42 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:42.876+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:43 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:43.388+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:43 volumio volumio[1207]: info: Check plugin dependencies Oct 03 13:39:43 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 13:39:43 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:43.441+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:43 volumio volumio[1207]: info: Checking if plugin already exists Oct 03 13:39:43 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:43.443+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:43 volumio volumio[1207]: info: Rename folder Oct 03 13:39:43 volumio volumio[1207]: info: Folder /tmp/downloaded_plugin.zip removed Oct 03 13:39:43 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:43.458+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:43 volumio volumio[1207]: info: Move to category Oct 03 13:39:44 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:44.704+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:44 volumio volumio[1207]: info: Checking if install.sh is present Oct 03 13:39:44 volumio volumio[1207]: info: Executing install.sh Oct 03 13:39:44 volumio sudo[15801]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Oct 03 13:39:44 volumio sudo[15801]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:39:44 volumio volumio[1207]: info: Installing Go-librespot Oct 03 13:39:44 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:44.745+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:44 volumio volumio[1207]: info: Checking old vollibrespot installs Oct 03 13:39:44 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:44.749+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:44 volumio systemd[1]: Reloading. Oct 03 13:39:45 volumio volumio[1207]: info: Downloading daemon Oct 03 13:39:45 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:45.645+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:53 volumio volumio[1207]: info: Creating directories Oct 03 13:39:53 volumio volumio[1207]: info: Creating data directory Oct 03 13:39:53 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:53.169+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:53 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:53.170+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:53 volumio volumio[1207]: info: Creating Start Script Oct 03 13:39:53 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:53.175+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:53 volumio systemd[1]: Reloading. Oct 03 13:39:54 volumio sudo[15801]: pam_unix(sudo:session): session closed for user root Oct 03 13:39:54 volumio volumio[1207]: info: Install script completed Oct 03 13:39:54 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:54.038+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:54 volumio volumio[1207]: info: Adding reference to registry Oct 03 13:39:54 volumio volumio5-onboarding[1575]: time=2026-10-03T13:39:54.042+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 03 13:39:54 volumio volumio[1207]: info: Done installing plugin. Oct 03 13:39:54 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 13:39:54 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 13:39:54 volumio volumio[1207]: Plugin install end detected on script Oct 03 13:39:54 volumio volumio[1207]: info: Folder /tmp/plugins removed Oct 03 13:39:54 volumio volumio[1207]: info: Folder /tmp/downloaded_plugin.zip removed Oct 03 13:39:54 volumio volumio[1207]: info: Folder /data/temp removed Oct 03 13:39:55 volumio volumio[1207]: info: Enabling plugin spop Oct 03 13:39:55 volumio volumio[1207]: info: Loading plugin "spop"... Oct 03 13:39:55 volumio volumio[1207]: info: PLUGIN START: spop Oct 03 13:39:55 volumio volumio[1207]: info: Creating Spotify config file Oct 03 13:39:55 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:39:55 volumio volumio[1207]: info: Done. Oct 03 13:39:55 volumio volumio[1207]: info: Spotify config file written Oct 03 13:39:55 volumio volumio[1207]: info: No need to fix Spotify hosts Oct 03 13:39:55 volumio sudo[15873]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 03 13:39:55 volumio sudo[15873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:39:55 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 13:39:55 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Oct 03 13:39:55 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Oct 03 13:39:55 volumio go-librespot[15875]: go-librespot daemon starting... Oct 03 13:39:55 volumio sudo[15873]: pam_unix(sudo:session): session closed for user root Oct 03 13:39:55 volumio go-librespot[15876]: time="2026-10-03T13:39:55+01:00" level=info msg="running go-librespot 0.9.1" Oct 03 13:39:55 volumio go-librespot[15876]: time="2026-10-03T13:39:55+01:00" level=info msg="api server listening on 127.0.0.1:9879" Oct 03 13:39:55 volumio go-librespot[15876]: time="2026-10-03T13:39:55+01:00" level=debug msg="no app state found" Oct 03 13:39:55 volumio go-librespot[15876]: time="2026-10-03T13:39:55+01:00" level=debug msg="stored credentials not found" Oct 03 13:39:55 volumio go-librespot[15876]: time="2026-10-03T13:39:55+01:00" level=info msg="generated new device id: 884cd468f04daafd6bc9253e6b603a8b6f5fbc57" Oct 03 13:39:58 volumio volumio[1207]: info: go-librespot daemon successfully initialized Oct 03 13:40:01 volumio volumio[1207]: info: Initializing connection to go-librespot Websocket Oct 03 13:40:01 volumio go-librespot[15876]: time="2026-10-03T13:40:01+01:00" level=debug msg="new websocket client" Oct 03 13:40:01 volumio volumio[1207]: info: Connection to go-librespot Websocket established Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPlay Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::play index undefined Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::startPlaybackTimer Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::resume Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand play Oct 03 13:40:04 volumio volumio[1207]: info: Oct 03 13:40:04 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand play took 317 milliseconds Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:40:04 volumio volumio[1207]: info: Oct 03 13:40:04 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:40:04 volumio volumio[1207]: info: Oct 03 13:40:04 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand status took 3 milliseconds Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand status took 3 milliseconds Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4332,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"831 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:40:04 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.484+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=3969 volume=100 Oct 03 13:40:04 volumio volumio[1207]: info: ------------------------------ 15ms Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.486+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=3969 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.486+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.487+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 03 13:40:04 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:40:04 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4332,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"831 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:40:04 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:40:04 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:04 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4332,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"831 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:40:04 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::syncState stateService play Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Oct 03 13:40:04 volumio volumio[1207]: info: Received an update from plugin. extracting info from payload Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:04 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.501+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.502+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.503+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PLAYING positionMs=4332 volume=100 Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.503+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.503+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.504+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:04.504+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:04 volumio volumio[1207]: info: ------------------------------ 34ms Oct 03 13:40:04 volumio volumio[1207]: info: ------------------------------ 34ms Oct 03 13:40:04 volumio volumio[1207]: info: Getting Spotify volume Oct 03 13:40:04 volumio volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 03 13:40:04 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Oct 03 13:40:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:04 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::servicePause Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::servicePause Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::pause Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand pause Oct 03 13:40:05 volumio volumio[1207]: info: Oct 03 13:40:05 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand pause took 2 milliseconds Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:40:05 volumio volumio[1207]: info: Oct 03 13:40:05 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:40:05 volumio volumio[1207]: info: Oct 03 13:40:05 volumio volumio[1207]: ---------------------------- MPD announces state update: player Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::getState Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::parseState Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5570,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:40:05 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.640+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=5334 volume=100 Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.641+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=5334 volume=100 Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.641+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:05 volumio volumio[1207]: info: ------------------------------ 8ms Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.642+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 03 13:40:05 volumio volumio[1207]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:40:05 volumio volumio[1207]: verbose: ControllerMpd::parseTrackInfo Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5570,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:40:05 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:40:05 volumio volumio[1207]: info: ControllerMpd::pushState Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":5570,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Pistol Grip Pump","artist":"Rage Against the Machine","album":"Renegades","uri":"NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac","trackType":"flac"} Oct 03 13:40:05 volumio volumio[1207]: verbose: CURRENT POSITION 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::syncState stateService pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::pushState Oct 03 13:40:05 volumio volumio[1207]: info: CorePlayQueue::getTrack 15 Oct 03 13:40:05 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Oct 03 13:40:05 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.654+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=5334 volume=100 Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.654+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=5334 volume=100 Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.654+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.654+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:05 volumio volumio[1207]: info: ------------------------------ 22ms Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.656+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" state=STATUS_PAUSED positionMs=5334 volume=100 Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.656+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" state=STATUS_PAUSED positionMs=5334 volume=100 Oct 03 13:40:05 volumio volumio[1207]: info: ------------------------------ 22ms Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.656+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.84:46264 @ 0x2000090" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:05 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:05.656+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:33716,00:00:00:00:00:00%02 @ 0x2000120" id="mnt/NAS/Synology/Rage Against the Machine/Renegades/02 Rage Against the Machine - Pistol Grip PumpPistol Grip PumpRage Against the MachinePistol Grip PumpRenegadesAlternative.flac" title="Pistol Grip Pump" Oct 03 13:40:05 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:40:05 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:40:05 volumio volumio[1207]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 03 13:40:16 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:16.892+01:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.1.198:33716 error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 03 13:40:16 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:16.893+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.198:33716 Oct 03 13:40:16 volumio volumio5-onboarding[1575]: time=2026-10-03T13:40:16.893+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.198:33716 Oct 03 13:40:25 volumio go-librespot[15876]: time="2026-10-03T13:40:25+01:00" level=fatal msg="daemon exited with error" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Oct 03 13:40:25 volumio volumio[1207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:40:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Oct 03 13:40:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 03 13:40:25 volumio volumio[1207]: Error: socket hang up Oct 03 13:40:25 volumio volumio[1207]: at connResetException (node:internal/errors:720:14) Oct 03 13:40:25 volumio volumio[1207]: at Socket.socketOnEnd (node:_http_client:519:23) Oct 03 13:40:25 volumio volumio[1207]: at Socket.emit (node:events:526:35) Oct 03 13:40:25 volumio volumio[1207]: at endReadableNT (node:internal/streams/readable:1376:12) Oct 03 13:40:25 volumio volumio[1207]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Oct 03 13:40:25 volumio volumio[1207]: code: 'ECONNRESET', Oct 03 13:40:25 volumio volumio[1207]: response: undefined Oct 03 13:40:25 volumio volumio[1207]: } Oct 03 13:40:25 volumio volumio[1207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:40:26 volumio sudo[15971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 13:39' Oct 03 13:40:26 volumio sudo[15971]: 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"