Aug 26 19:25:34 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:34 volumio volumio[19746]: info: CorePlayQueue::getTrack 4 Aug 26 19:25:34 volumio volumio[19746]: info: Prefetching next song Aug 26 19:25:34 volumio volumio[19746]: info: DOING PREFETCH IN MPD Aug 26 19:25:34 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand add "USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3" Aug 26 19:25:34 volumio volumio[19746]: info: Aug 26 19:25:34 volumio volumio[19746]: ---------------------------- MPD announces system playlist update Aug 26 19:25:34 volumio volumio[19746]: info: Ignoring MPD Status Update Aug 26 19:25:34 volumio volumio[19746]: info: sendMpdCommand add "USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3" took 1 milliseconds Aug 26 19:25:34 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 26 19:25:34 volumio volumio[19746]: info: Aug 26 19:25:34 volumio volumio[19746]: ---------------------------- MPD announces system playlist update Aug 26 19:25:34 volumio volumio[19746]: info: Ignoring MPD Status Update Aug 26 19:25:34 volumio volumio[19746]: info: Aug 26 19:25:34 volumio volumio[19746]: ---------------------------- MPD announces system playlist update Aug 26 19:25:34 volumio volumio[19746]: info: Ignoring MPD Status Update Aug 26 19:25:34 volumio volumio[19746]: info: ------------------------------ 2ms Aug 26 19:25:34 volumio volumio[19746]: info: sendMpdCommand consume 1 took 1 milliseconds Aug 26 19:25:34 volumio volumio[19746]: info: ------------------------------ 1ms Aug 26 19:25:34 volumio volumio[19746]: info: ------------------------------ 1ms Aug 26 19:25:36 volumio volumio[19746]: info: Aug 26 19:25:36 volumio volumio[19746]: ---------------------------- MPD announces system playlist update Aug 26 19:25:36 volumio volumio[19746]: info: Ignoring MPD Status Update Aug 26 19:25:36 volumio volumio[19746]: info: Aug 26 19:25:36 volumio volumio[19746]: ---------------------------- MPD announces state update: player Aug 26 19:25:36 volumio volumio[19746]: info: ControllerMpd::getState Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:25:36 volumio volumio[19746]: info: Aug 26 19:25:36 volumio volumio[19746]: ---------------------------- MPD announces system playlist update Aug 26 19:25:36 volumio volumio[19746]: info: Ignoring MPD Status Update Aug 26 19:25:36 volumio volumio[19746]: info: Aug 26 19:25:36 volumio volumio[19746]: ---------------------------- MPD announces state update: player Aug 26 19:25:36 volumio volumio[19746]: info: ControllerMpd::getState Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:25:36 volumio volumio[19746]: info: Aug 26 19:25:36 volumio volumio[19746]: ---------------------------- MPD announces system playlist update Aug 26 19:25:36 volumio volumio[19746]: info: Ignoring MPD Status Update Aug 26 19:25:36 volumio volumio[19746]: info: Aug 26 19:25:36 volumio volumio[19746]: ---------------------------- MPD announces state update: player Aug 26 19:25:36 volumio volumio[19746]: info: ControllerMpd::getState Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:25:36 volumio volumio[19746]: info: ------------------------------ 1ms Aug 26 19:25:36 volumio volumio[19746]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:25:36 volumio volumio[19746]: info: ------------------------------ 1ms Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::parseState Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:25:36 volumio volumio[19746]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:25:36 volumio volumio[19746]: info: ------------------------------ 1ms Aug 26 19:25:36 volumio volumio[19746]: info: sendMpdCommand status took 0 milliseconds Aug 26 19:25:36 volumio volumio[19746]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::parseState Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::parseState Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:25:36 volumio volumio[19746]: info: ControllerMpd::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::servicePushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"What Can I Do With This Broken Heart?","artist":"England Dan & John Ford Coley","album":"The Best of England Dan & John Ford Coley","uri":"USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3","trackType":"mp3"} Aug 26 19:25:36 volumio volumio[19746]: verbose: CURRENT POSITION 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::syncState stateService play Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:25:36 volumio volumio[19746]: info: Received an update from plugin. extracting info from payload Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.502+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio[19746]: info: ------------------------------ 7ms Aug 26 19:25:36 volumio volumio[19746]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 19:25:36 volumio volumio[19746]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:25:36 volumio volumio[19746]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:25:36 volumio volumio[19746]: info: ControllerMpd::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::servicePushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"What Can I Do With This Broken Heart?","artist":"England Dan & John Ford Coley","album":"The Best of England Dan & John Ford Coley","uri":"USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3","trackType":"mp3"} Aug 26 19:25:36 volumio volumio[19746]: verbose: CURRENT POSITION 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::syncState stateService play Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:25:36 volumio volumio[19746]: info: Received an update from plugin. extracting info from payload Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:36 volumio volumio[19746]: info: ControllerMpd::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::servicePushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"What Can I Do With This Broken Heart?","artist":"England Dan & John Ford Coley","album":"The Best of England Dan & John Ford Coley","uri":"USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3","trackType":"mp3"} Aug 26 19:25:36 volumio volumio[19746]: verbose: CURRENT POSITION 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::syncState stateService play Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::syncState currentStatus play Aug 26 19:25:36 volumio volumio[19746]: info: Received an update from plugin. extracting info from payload Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:36 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:36 volumio volumio[19746]: info: CorePlayQueue::getTrack 3 Aug 26 19:25:36 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.509+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=166967 volume=100 Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:36.510+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 04 - It's Sad To Belong.mp3" title="It's Sad To Belong" Aug 26 19:25:36 volumio volumio[19746]: info: ------------------------------ 16ms Aug 26 19:25:36 volumio volumio[19746]: info: ------------------------------ 16ms Aug 26 19:25:39 volumio volumio[19746]: info: CoreStateMachine::startPlaybackTimer Aug 26 19:25:39 volumio volumio[19746]: info: CorePlayQueue::getTrack 4 Aug 26 19:25:39 volumio volumio[19746]: info: CoreStateMachine::pushState Aug 26 19:25:39 volumio volumio[19746]: info: CorePlayQueue::getTrack 4 Aug 26 19:25:39 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:25:39 volumio volumio[19746]: info: CoreCommandRouter::volumioPushState Aug 26 19:25:39 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:39.556+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" state=STATUS_PLAYING positionMs=250 volume=100 Aug 26 19:25:39 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:39.556+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" state=STATUS_PLAYING positionMs=250 volume=100 Aug 26 19:25:39 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:39.557+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406 @ 0xc000602630" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3" title="What Can I Do With This Broken Heart?" Aug 26 19:25:39 volumio volumio5-onboarding[1713]: time=2026-08-26T19:25:39.557+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" id="mnt/USB/BA04F85304F813DB/music/England Dan & John Ford Coley - The Best of (1979)/The Best of - 05 - What Can I Do With This Broken Heart_.mp3" title="What Can I Do With This Broken Heart?" Aug 26 19:26:39 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:39.807+05:30 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s Aug 26 19:26:39 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:39.899+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=91.72784ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.030+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=223.084162ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.037+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://www.googleapis.com duration=228.84774ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.141+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=333.815759ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.195+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://securetoken.googleapis.com duration=388.234107ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.228+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://google.com duration=420.37267ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.247+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=http://pushupdates.volumio.org duration=439.96775ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.536+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=728.432211ms Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.814+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://database.volumio.cloud duration=1.006843018s Aug 26 19:26:40 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:40.929+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=http://cddb.volumio.org duration=1.12120124s Aug 26 19:26:41 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:41.038+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=http://plugins.volumio.org duration=1.230180865s Aug 26 19:26:41 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:41.903+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.095544958s Aug 26 19:26:41 volumio volumio5-onboarding[1713]: time=2026-08-26T19:26:41.926+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.106:59406,00:00:00:00:00:00%03 @ 0xc0004953b0" latency=202.338784ms timeout=10s endpoint=https://functions.volumio.cloud duration=2.118269143s Aug 26 19:26:46 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:26:46 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:26:46 volumio volumio[19746]: info: Discovery: Getting this device information Aug 26 19:26:46 volumio volumio[19746]: info: CoreCommandRouter::volumioGetState Aug 26 19:26:46 volumio volumio[19746]: info: CorePlayQueue::getTrack 4 Aug 26 19:26:46 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:26:46 volumio volumio[19746]: verbose: New Socket.io Connection to 192.168.0.101:3000 from 192.168.0.106 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 26 19:26:46 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 19:26:46 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:26:50 volumio volumio[19746]: info: Retrieving Cloud Streaming UI Aug 26 19:26:50 volumio volumio[19746]: info: Getting Tidal Cloud Configuration Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:26:50 volumio volumio[19746]: info: Getting Qobuz Cloud Configuration Aug 26 19:26:50 volumio volumio[19746]: info: Asking plugin for UI Config Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:26:50 volumio volumio[19746]: info: Getting Spotify Cloud Configuration Aug 26 19:26:50 volumio volumio[19746]: info: Asking plugin for UI Config Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:26:50 volumio volumio[19746]: info: Saving Spotify Acccount Aug 26 19:26:50 volumio volumio[19746]: info: Got Tidal Cloud Configuration Aug 26 19:26:50 volumio volumio[19746]: info: Got it Aug 26 19:26:50 volumio volumio[19746]: info: Got it Aug 26 19:26:50 volumio volumio[19746]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:26:50 volumio volumio[19746]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:26:50 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 26 19:26:54 volumio volumio[19746]: info: Disabling MyMusic plugin upnp Aug 26 19:26:54 volumio sudo[22542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 26 19:26:54 volumio sudo[22542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 19:26:54 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Aug 26 19:26:54 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:26:55 volumio volumio[19746]: info: Enabling MyMusic plugin upnp Aug 26 19:26:55 volumio volumio[19746]: info: Enabling plugin upnp Aug 26 19:26:55 volumio volumio[19746]: info: Loading plugin "upnp"... Aug 26 19:26:55 volumio volumio[19746]: info: [1787752615473] Starting Upmpd Daemon Aug 26 19:26:55 volumio volumio[19746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 19:26:55 volumio volumio[19746]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 19:26:55 volumio volumio[19746]: Error: listen EADDRINUSE: address already in use :::6599 Aug 26 19:26:55 volumio volumio[19746]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Aug 26 19:26:55 volumio volumio[19746]: at listenInCluster (node:net:1920:12) Aug 26 19:26:55 volumio volumio[19746]: at Server.listen (node:net:2008:7) Aug 26 19:26:55 volumio volumio[19746]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 26 19:26:55 volumio volumio[19746]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 26 19:26:55 volumio volumio[19746]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 26 19:26:55 volumio volumio[19746]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 26 19:26:55 volumio volumio[19746]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Aug 26 19:26:55 volumio volumio[19746]: code: 'EADDRINUSE', Aug 26 19:26:55 volumio volumio[19746]: errno: -98, Aug 26 19:26:55 volumio volumio[19746]: syscall: 'listen', Aug 26 19:26:55 volumio volumio[19746]: address: '::', Aug 26 19:26:55 volumio volumio[19746]: port: 6599 Aug 26 19:26:55 volumio volumio[19746]: } Aug 26 19:26:55 volumio volumio[19746]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 19:26:55 volumio sudo[22559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 19:25' Aug 26 19:26:55 volumio sudo[22559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"