-- Logs begin at Sat 2026-08-29 13:26:26 SAST, end at Sun 2026-08-30 20:38:34 SAST. -- Aug 30 20:37:02 volumio volumio[966]: info: Preload queue cleared Aug 30 20:37:02 volumio volumio[966]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::ClearQueue Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::stop Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::clearPlayQueue Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::saveQueue Aug 30 20:37:02 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::addQueueItems Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::addQueueItems Aug 30 20:37:02 volumio volumio[966]: info: Preload queue cleared Aug 30 20:37:02 volumio volumio[966]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25727 Aug 30 20:37:02 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 20:37:02 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::saveQueue Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Aug 30 20:37:02 volumio volumio[966]: info: CoreCommandRouter::volumioPlay Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::play index 0 Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::stop Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::play index undefined Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:02 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Aug 30 20:37:02 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:02 volumio volumio[966]: info: [1788115022059] ControllerWebradio::clearAddPlayTrack Aug 30 20:37:02 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 20:37:03 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:03 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:03 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:03 volumio volumio[966]: info: Aug 30 20:37:03 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:03 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 5033ms Aug 30 20:37:03 volumio volumio[966]: info: sendMpdCommand status took 5033 milliseconds Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 5032ms Aug 30 20:37:03 volumio volumio[966]: info: sendMpdCommand status took 5031 milliseconds Aug 30 20:37:03 volumio volumio[966]: info: sendMpdCommand stop took 1571 milliseconds Aug 30 20:37:03 volumio volumio[966]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" took 4 milliseconds Aug 30 20:37:03 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:03 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:03 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 20:37:03 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Aug 30 20:37:03 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:03 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:03 volumio volumio[966]: 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} Aug 30 20:37:03 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::syncState stateService stop Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:03 volumio volumio[966]: info: No code Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:03 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:03 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:03 volumio volumio[966]: 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} Aug 30 20:37:03 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::syncState stateService stop Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:03 volumio volumio[966]: info: No code Aug 30 20:37:03 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:03 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.654+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=1502 volume=18 Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.655+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=1502 volume=18 Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.655+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.656+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=1502 volume=18 Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.657+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.657+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=1502 volume=18 Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.658+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.658+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=1502 volume=18 Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.659+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:03 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:03.659+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 5070ms Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 5070ms Aug 30 20:37:03 volumio volumio[966]: Upnp client error: Error: This socket has been ended by the other party Aug 30 20:37:03 volumio volumio[966]: info: Aug 30 20:37:03 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:03 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:03 volumio volumio[966]: info: Aug 30 20:37:03 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:03 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:03 volumio volumio[966]: info: Aug 30 20:37:03 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:03 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:03 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:03 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 45ms Aug 30 20:37:03 volumio volumio[966]: info: sendMpdCommand clear took 43 milliseconds Aug 30 20:37:03 volumio volumio[966]: info: sendMpdCommand play took 43 milliseconds Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 5ms Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 4ms Aug 30 20:37:03 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:03 volumio volumio[966]: info: Aug 30 20:37:03 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:03 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:03 volumio volumio[966]: info: Aug 30 20:37:03 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:03 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:03 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:03 volumio volumio[966]: info: ------------------------------ 12ms Aug 30 20:37:07 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:07 volumio volumio[966]: info: Listing playlists Aug 30 20:37:07 volumio volumio[966]: info: Listing playlists Aug 30 20:37:07 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:07 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:07 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:07 volumio volumio[966]: info: Aug 30 20:37:07 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:07 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:07 volumio volumio[966]: info: ------------------------------ 3871ms Aug 30 20:37:07 volumio volumio[966]: info: ------------------------------ 3869ms Aug 30 20:37:07 volumio volumio[966]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" took 3 milliseconds Aug 30 20:37:07 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 20:37:07 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Aug 30 20:37:07 volumio volumio[966]: Upnp client error: Error: This socket has been ended by the other party Aug 30 20:37:07 volumio volumio[966]: info: Aug 30 20:37:07 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:07 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:07 volumio volumio[966]: info: Aug 30 20:37:07 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:07 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:07 volumio volumio[966]: info: ------------------------------ 7ms Aug 30 20:37:07 volumio volumio[966]: info: sendMpdCommand play took 6 milliseconds Aug 30 20:37:07 volumio volumio[966]: info: ------------------------------ 4ms Aug 30 20:37:07 volumio volumio[966]: info: ------------------------------ 2ms Aug 30 20:37:08 volumio volumio[966]: info: Preload queue cleared Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::ClearQueue Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::stop Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::clearPlayQueue Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::saveQueue Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::addQueueItems Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::addQueueItems Aug 30 20:37:08 volumio volumio[966]: info: Preload queue cleared Aug 30 20:37:08 volumio volumio[966]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25727 Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::volumioPushQueue Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::saveQueue Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::volumioPlay Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::play index 0 Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::stop Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::play index undefined Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:08 volumio volumio[966]: info: [1788115028541] ControllerWebradio::clearAddPlayTrack Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 20:37:08 volumio volumio[966]: info: sendMpdCommand stop took 12 milliseconds Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 20:37:08 volumio volumio[966]: info: Aug 30 20:37:08 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:08 volumio volumio[966]: info: Aug 30 20:37:08 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:08 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:08 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:08 volumio volumio[966]: info: sendMpdCommand clear took 3 milliseconds Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:08 volumio volumio[966]: info: Aug 30 20:37:08 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:08 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:08 volumio volumio[966]: info: Aug 30 20:37:08 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:08 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:08 volumio volumio[966]: info: sendMpdCommand status took 6 milliseconds Aug 30 20:37:08 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:08 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:08 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:08 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:08 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:08 volumio volumio[966]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 20:37:08 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:08.571+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_STOPPED positionMs=0 volume=18 Aug 30 20:37:08 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:08.572+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:08 volumio volumio[966]: info: ------------------------------ 19ms Aug 30 20:37:11 volumio volumio[966]: info: CoreCommandRouter::volumioPlay Aug 30 20:37:11 volumio volumio[966]: info: CoreStateMachine::play index undefined Aug 30 20:37:11 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:11 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:11 volumio volumio[966]: info: CoreStateMachine::startPlaybackTimer Aug 30 20:37:11 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:11 volumio volumio[966]: info: [1788115031595] ControllerWebradio::clearAddPlayTrack Aug 30 20:37:11 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 20:37:12 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 3789ms Aug 30 20:37:12 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:12 volumio volumio[966]: info: Aug 30 20:37:12 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:12 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:12 volumio volumio[966]: info: sendMpdCommand status took 3786 milliseconds Aug 30 20:37:12 volumio volumio[966]: info: sendMpdCommand status took 3784 milliseconds Aug 30 20:37:12 volumio volumio[966]: info: sendMpdCommand stop took 752 milliseconds Aug 30 20:37:12 volumio volumio[966]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" took 3 milliseconds Aug 30 20:37:12 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:12 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:12 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 20:37:12 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Aug 30 20:37:12 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:12 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:12 volumio volumio[966]: 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} Aug 30 20:37:12 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::syncState stateService stop Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:12 volumio volumio[966]: info: No code Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:12 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:12 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:12 volumio volumio[966]: 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} Aug 30 20:37:12 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::syncState stateService stop Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:12 volumio volumio[966]: info: No code Aug 30 20:37:12 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:12 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.373+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=750 volume=18 Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.374+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.375+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=750 volume=18 Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.375+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.376+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=750 volume=18 Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.377+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.377+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=750 volume=18 Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.379+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=750 volume=18 Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.379+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.378+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=750 volume=18 Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.381+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:12 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:12.382+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id=http://stream.live.vc.bbcmedia.co.uk/bbc_world_service title="BBC World Service Online" Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 3824ms Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 3823ms Aug 30 20:37:12 volumio volumio[966]: Upnp client error: Error: This socket has been ended by the other party Aug 30 20:37:12 volumio volumio[966]: info: Aug 30 20:37:12 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:12 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:12 volumio volumio[966]: info: Aug 30 20:37:12 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:12 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:12 volumio volumio[966]: info: Aug 30 20:37:12 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:12 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:12 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:12 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 53ms Aug 30 20:37:12 volumio volumio[966]: info: sendMpdCommand clear took 50 milliseconds Aug 30 20:37:12 volumio volumio[966]: info: sendMpdCommand play took 50 milliseconds Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 6ms Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 4ms Aug 30 20:37:12 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:12 volumio volumio[966]: info: Aug 30 20:37:12 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:12 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:12 volumio volumio[966]: info: Aug 30 20:37:12 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:12 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:12 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:12 volumio volumio[966]: info: ------------------------------ 11ms Aug 30 20:37:16 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" Aug 30 20:37:16 volumio volumio[966]: error: updateQueue error: null Aug 30 20:37:16 volumio volumio[966]: info: Aug 30 20:37:16 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:16 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:16 volumio volumio[966]: info: ------------------------------ 3843ms Aug 30 20:37:16 volumio volumio[966]: info: ------------------------------ 3841ms Aug 30 20:37:16 volumio volumio[966]: info: sendMpdCommand add "https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" took 3 milliseconds Aug 30 20:37:16 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 20:37:16 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand play Aug 30 20:37:16 volumio volumio[966]: Upnp client error: Error: This socket has been ended by the other party Aug 30 20:37:16 volumio volumio[966]: info: Aug 30 20:37:16 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:16 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:16 volumio volumio[966]: info: Aug 30 20:37:16 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:16 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:16 volumio volumio[966]: info: ------------------------------ 7ms Aug 30 20:37:16 volumio volumio[966]: info: sendMpdCommand play took 5 milliseconds Aug 30 20:37:16 volumio volumio[966]: info: ------------------------------ 3ms Aug 30 20:37:16 volumio volumio[966]: info: ------------------------------ 2ms Aug 30 20:37:17 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.189+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.10.137:52658 @ 0x2746990" latency=26.142843ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.205+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.218+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=12.647645ms Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.315+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://www.googleapis.com duration=109.754401ms Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.395+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=http://pushupdates.volumio.org duration=188.400883ms Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.471+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://functions.volumio.cloud duration=266.108933ms Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.473+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://functions.volumio.cloud duration=266.770857ms Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.510+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://securetoken.googleapis.com duration=304.771345ms Aug 30 20:37:18 volumio sudo[22216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 20:37:18 volumio sudo[22216]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:18 volumio sudo[22216]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:18 volumio sudo[22218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 20:37:18 volumio sudo[22218]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:18 volumio sudo[22218]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.591+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=385.693493ms Aug 30 20:37:18 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.10.172 from 192.168.10.137 UA: Mozilla/5.0 (Linux; Android 16; SM-A346E Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.716+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://database.volumio.cloud duration=509.733916ms Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 20:37:18 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.793+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=586.783219ms Aug 30 20:37:18 volumio sudo[22222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 20:37:18 volumio sudo[22222]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:18 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:18.874+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=669.11911ms Aug 30 20:37:18 volumio sudo[22222]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:18 volumio sudo[22225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 20:37:18 volumio sudo[22225]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:18 volumio sudo[22225]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:18 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.10.172 from 192.168.10.137 UA: Mozilla/5.0 (Linux; Android 16; SM-A346E Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 20:37:19 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 20:37:19 volumio volumio[966]: info: Listing playlists Aug 30 20:37:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 20:37:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 20:37:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 20:37:19 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 20:37:19 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:19.126+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=https://google.com duration=921.151291ms Aug 30 20:37:19 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:19.218+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=http://plugins.volumio.org duration=1.011407091s Aug 30 20:37:19 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:19.269+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=16.05197ms timeout=10s endpoint=http://cddb.volumio.org duration=1.06255694s Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:20 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:20 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces system playlist update Aug 30 20:37:20 volumio volumio[966]: info: Ignoring MPD Status Update Aug 30 20:37:20 volumio volumio[966]: info: Aug 30 20:37:20 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand status took 31 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand status took 30 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand status took 29 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 20ms Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand status took 18 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 17ms Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand status took 22 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 22ms Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand status took 9 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","artist":"FM702","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","trackType":""} Aug 30 20:37:20 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus stop Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","artist":"FM702","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","trackType":""} Aug 30 20:37:20 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Aug 30 20:37:20 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","artist":"FM702","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","trackType":""} Aug 30 20:37:20 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Aug 30 20:37:20 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","artist":"FM702","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","trackType":""} Aug 30 20:37:20 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Aug 30 20:37:20 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 68ms Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.403+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=68 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.403+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.404+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.405+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=68 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.405+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.406+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=68 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.406+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.407+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=68 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.408+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 83ms Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 82ms Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 71ms Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 30 20:37:20 volumio volumio[966]: info: sendMpdCommand playlistinfo took 45 milliseconds Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:20 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":594,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","artist":"FM702","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","trackType":""} Aug 30 20:37:20 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Aug 30 20:37:20 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","artist":"FM702","album":null,"uri":"https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w","trackType":""} Aug 30 20:37:20 volumio volumio[966]: verbose: CURRENT POSITION 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState stateService play Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::syncState currentStatus play Aug 30 20:37:20 volumio volumio[966]: info: Received an update from plugin. extracting info from payload Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:20 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:20 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.436+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=188 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.436+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.439+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=188 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.440+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=188 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.440+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 91ms Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.442+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:20 volumio volumio[966]: info: ------------------------------ 89ms Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.443+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=188 volume=18 Aug 30 20:37:20 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:20.444+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:24 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:24.900+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.137:52658 @ 0x2746990" latency=26.18889ms timeout=3s volume=17 Aug 30 20:37:24 volumio volumio[966]: info: VolumeController::SetAlsaVolume17 Aug 30 20:37:24 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:24 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:24 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:24 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:24 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:24.932+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=4571 volume=17 Aug 30 20:37:24 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:24.934+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:25 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:25.240+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.137:52658 @ 0x2746990" latency=19.097716ms timeout=3s volume=16 Aug 30 20:37:25 volumio volumio[966]: info: VolumeController::SetAlsaVolume16 Aug 30 20:37:25 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:25 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:25 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:25 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:25 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:25.272+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=4983 volume=16 Aug 30 20:37:25 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:25.274+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:26 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:26.736+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.137:52658 @ 0x2746990" latency=19.10364ms timeout=3s volume=15 Aug 30 20:37:26 volumio volumio[966]: info: VolumeController::SetAlsaVolume15 Aug 30 20:37:26 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:26 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:26 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:26 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:26 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:26.768+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=6484 volume=15 Aug 30 20:37:26 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:26.771+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:27 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:27 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:27 volumio volumio[966]: info: Listing playlists Aug 30 20:37:27 volumio volumio[966]: info: Listing playlists Aug 30 20:37:27 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:27.555+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.137:52658 @ 0x2746990" latency=27.087524ms timeout=3s volume=14 Aug 30 20:37:27 volumio volumio[966]: info: VolumeController::SetAlsaVolume14 Aug 30 20:37:27 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:27 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:27 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:27 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:27 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:27.589+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=7234 volume=14 Aug 30 20:37:27 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:27.590+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:28 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:28.043+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.137:52658 @ 0x2746990" latency=18.02207ms timeout=3s volume=13 Aug 30 20:37:28 volumio volumio[966]: info: VolumeController::SetAlsaVolume13 Aug 30 20:37:28 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:28 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:28 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:28 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:28 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:28.083+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=7734 volume=13 Aug 30 20:37:28 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:28.084+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:28 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:28.569+02:00 level=INFO msg="set volume" component=server type=REQUEST_TYPE_SET_VOLUME peer="192.168.10.137:52658 @ 0x2746990" latency=15.356424ms timeout=3s volume=12 Aug 30 20:37:28 volumio volumio[966]: info: VolumeController::SetAlsaVolume12 Aug 30 20:37:28 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:28 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:28 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:28 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:28 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:28.601+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_PLAYING positionMs=8234 volume=12 Aug 30 20:37:28 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:28.602+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::volumioStop Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::stop Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::stPlaybackTimer Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::updateTrackBlock Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrackBlock Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::serviceStop Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::serviceStop Aug 30 20:37:36 volumio volumio[966]: info: [1788115056349] ControllerWebradio::stop Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.350+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_STOPPED positionMs=0 volume=12 Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.351+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:36 volumio volumio[966]: info: Aug 30 20:37:36 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand stop took 24 milliseconds Aug 30 20:37:36 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:36 volumio volumio[966]: info: Aug 30 20:37:36 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:36 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:36 volumio volumio[966]: info: Aug 30 20:37:36 volumio volumio[966]: ---------------------------- MPD announces state update: player Aug 30 20:37:36 volumio volumio[966]: info: ControllerMpd::getState Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand status Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand status took 4 milliseconds Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand status took 2 milliseconds Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::parseState Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 30 20:37:36 volumio volumio[966]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:36 volumio volumio[966]: verbose: ControllerMpd::parseTrackInfo Aug 30 20:37:36 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 20:37:36 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 20:37:36 volumio volumio[966]: info: ControllerMpd::pushState Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::servicePushState Aug 30 20:37:36 volumio volumio[966]: info: CoreStateMachine::pushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: CoreCommandRouter::volumioPushState Aug 30 20:37:36 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:36 volumio volumio[966]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.400+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_STOPPED positionMs=141 volume=12 Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.401+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.402+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_STOPPED positionMs=141 volume=12 Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.402+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.403+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" state=STATUS_STOPPED positionMs=141 volume=12 Aug 30 20:37:36 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:36.403+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.137:52658 @ 0x2746990" id="https://playerservices.streamtheworld.com/api/livestream-redirect/FM702.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc4ODExNTAwMSwiaXNzIjoidGlzcnYifQ.LmEyxW5tAeZaxgpYQ9nobapO6vHOnm5s8TNyzv--A9w" title=702 Aug 30 20:37:36 volumio volumio[966]: info: ------------------------------ 34ms Aug 30 20:37:36 volumio volumio[966]: info: ------------------------------ 32ms Aug 30 20:37:36 volumio volumio[966]: info: ------------------------------ 32ms Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 20:37:37 volumio volumio[966]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 20:37:37 volumio volumio[966]: info: Received Get System Version Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 20:37:37 volumio volumio[966]: info: Received Get System Info Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 20:37:37 volumio volumio[966]: info: Discovery: Getting this device information Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:37 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 20:37:37 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:37 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:44 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:44.849+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.10.137:52658 @ 0x2746990" latency=20.179127ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 20:37:44 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:44.863+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s Aug 30 20:37:44 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:44.875+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=11.850982ms Aug 30 20:37:44 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:44.972+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://www.googleapis.com duration=108.57326ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.049+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=http://pushupdates.volumio.org duration=185.087462ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.049+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=184.959338ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.063+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=199.414109ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.120+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=255.541277ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.129+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://database.volumio.cloud duration=263.99436ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.130+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://functions.volumio.cloud duration=265.425291ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.133+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://functions.volumio.cloud duration=268.552983ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.152+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://securetoken.googleapis.com duration=288.505384ms Aug 30 20:37:45 volumio sudo[22340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 20:37:45 volumio sudo[22340]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:45 volumio sudo[22340]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:45 volumio sudo[22343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 20:37:45 volumio sudo[22343]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:45 volumio sudo[22343]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:45 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.10.172 from 192.168.10.137 UA: Mozilla/5.0 (Linux; Android 16; SM-A346E Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:45 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 20:37:45 volumio sudo[22346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 20:37:45 volumio sudo[22346]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:45 volumio sudo[22346]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:45 volumio sudo[22349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 20:37:45 volumio sudo[22349]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 20:37:45 volumio sudo[22349]: pam_unix(sudo:session): session closed for user root Aug 30 20:37:45 volumio volumio[966]: verbose: New Socket.io Connection to 192.168.10.172 from 192.168.10.137 UA: Mozilla/5.0 (Linux; Android 16; SM-A346E Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.705+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=http://cddb.volumio.org duration=841.859759ms Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:45 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 20:37:45 volumio volumio[966]: info: Listing playlists Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 20:37:45 volumio volumio[966]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.779+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=https://google.com duration=915.038092ms Aug 30 20:37:45 volumio volumio5-onboarding[1108]: time=2026-08-30T20:37:45.808+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.10.137:52658 @ 0x2746990" latency=15.664218ms timeout=10s endpoint=http://plugins.volumio.org duration=943.389982ms Aug 30 20:37:47 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:47 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:37:47 volumio volumio[966]: info: Listing playlists Aug 30 20:37:47 volumio volumio[966]: info: Listing playlists Aug 30 20:37:57 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:37:57 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:38:07 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:38:07 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:38:07 volumio volumio[966]: info: Listing playlists Aug 30 20:38:07 volumio volumio[966]: info: Listing playlists Aug 30 20:38:17 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:38:17 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:38:27 volumio volumio[966]: info: CoreCommandRouter::volumioGetState Aug 30 20:38:27 volumio volumio[966]: info: CorePlayQueue::getTrack 0 Aug 30 20:38:27 volumio volumio[966]: info: Listing playlists Aug 30 20:38:27 volumio volumio[966]: info: Listing playlists Aug 30 20:38:33 volumio volumio[966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 20:38:33 volumio volumio[966]: Error: connect ETIMEDOUT 151.101.130.53:443 Aug 30 20:38:33 volumio volumio[966]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 30 20:38:33 volumio volumio[966]: errno: -110, Aug 30 20:38:33 volumio volumio[966]: code: 'ETIMEDOUT', Aug 30 20:38:33 volumio volumio[966]: syscall: 'connect', Aug 30 20:38:33 volumio volumio[966]: address: '151.101.130.53', Aug 30 20:38:33 volumio volumio[966]: port: 443 Aug 30 20:38:33 volumio volumio[966]: } Aug 30 20:38:33 volumio volumio[966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 20:38:34 volumio sudo[22526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 20:37 Aug 30 20:38:34 volumio sudo[22526]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"