-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Mon 2026-10-12 22:38:24 MSK. -- Oct 12 22:37:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:05 volumio volumio[955]: info: Listing playlists Oct 12 22:37:05 volumio volumio[955]: info: Listing playlists Oct 12 22:37:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:16 volumio volumio5-onboarding[2710]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Oct 12 22:37:16 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 22:37:16 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 22:37:17 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 12 22:37:17 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 12. Oct 12 22:37:17 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Oct 12 22:37:17 volumio systemd[1]: Started Volumio5 Onboarding Server. Oct 12 22:37:17 volumio volumio5-onboarding[2867]: time=2026-10-12T22:37:17.326+03:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Oct 12 22:37:17 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 22:37:17 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 22:37:17 volumio volumio[955]: info: Received Get System Info Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:37:17 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:37:17 volumio volumio5-onboarding[2867]: time=2026-10-12T22:37:17.360+03:00 level=INFO msg="system info for 6efa6b9583b3ed32dc40f2e8f5ba7091" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Oct 12 22:37:17 volumio volumio[955]: info: Received Get System Info Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:37:17 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 22:37:17 volumio volumio5-onboarding[2867]: time=2026-10-12T22:37:17.378+03:00 level=INFO msg="enabling local network discovery" Oct 12 22:37:17 volumio volumio5-onboarding[2867]: time=2026-10-12T22:37:17.394+03:00 level=INFO msg="enabling BLE discovery" Oct 12 22:37:17 volumio volumio5-onboarding[2867]: time=2026-10-12T22:37:17.450+03:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 12 22:37:17 volumio volumio[955]: info: Received Get System Info Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:37:17 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:37:17 volumio volumio-remote-updater[617]: No test mode Oct 12 22:37:17 volumio volumio-remote-updater[617]: No alpha test mode Oct 12 22:37:17 volumio volumio[955]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 22:37:17 volumio volumio[955]: info: Preload queue cleared Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::ClearQueue Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::addQueueItems Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::addQueueItems Oct 12 22:37:17 volumio volumio[955]: info: Preload queue cleared Oct 12 22:37:17 volumio volumio[955]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99611193 Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 12 22:37:17 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::play index 0 Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:17 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 12 22:37:17 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:17 volumio volumio[955]: info: [1791833837867] ControllerWebradio::clearAddPlayTrack Oct 12 22:37:17 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 22:37:25 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:25 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:25 volumio volumio[955]: info: Listing playlists Oct 12 22:37:25 volumio volumio[955]: info: Listing playlists Oct 12 22:37:28 volumio volumio[955]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Oct 12 22:37:34 volumio volumio[955]: info: Preload queue cleared Oct 12 22:37:34 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::ClearQueue Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:37:34 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::addQueueItems Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::addQueueItems Oct 12 22:37:34 volumio volumio[955]: info: Preload queue cleared Oct 12 22:37:34 volumio volumio[955]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542934 Oct 12 22:37:34 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 12 22:37:34 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 12 22:37:34 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::play index 0 Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:34 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 12 22:37:34 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:34 volumio volumio[955]: info: [1791833854051] ControllerWebradio::clearAddPlayTrack Oct 12 22:37:34 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 22:37:35 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:35 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:41 volumio volumio[955]: info: Preload queue cleared Oct 12 22:37:41 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::ClearQueue Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:37:41 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::addQueueItems Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::addQueueItems Oct 12 22:37:41 volumio volumio[955]: info: Preload queue cleared Oct 12 22:37:41 volumio volumio[955]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542934 Oct 12 22:37:41 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 12 22:37:41 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 12 22:37:41 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::play index 0 Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:41 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 12 22:37:41 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:41 volumio volumio[955]: info: [1791833861807] ControllerWebradio::clearAddPlayTrack Oct 12 22:37:41 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 22:37:42 volumio ntpd[771]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 12 22:37:45 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:45 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:45 volumio volumio[955]: info: Listing playlists Oct 12 22:37:45 volumio volumio[955]: info: Listing playlists Oct 12 22:37:47 volumio volumio5-onboarding[2867]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Oct 12 22:37:47 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 22:37:47 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 22:37:47 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 12 22:37:47 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 13. Oct 12 22:37:47 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Oct 12 22:37:47 volumio systemd[1]: Started Volumio5 Onboarding Server. Oct 12 22:37:47 volumio volumio5-onboarding[2939]: time=2026-10-12T22:37:47.836+03:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Oct 12 22:37:47 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 22:37:47 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 22:37:47 volumio volumio[955]: info: Received Get System Info Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:37:47 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:47 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:37:47 volumio volumio5-onboarding[2939]: time=2026-10-12T22:37:47.870+03:00 level=INFO msg="system info for 6efa6b9583b3ed32dc40f2e8f5ba7091" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Oct 12 22:37:47 volumio volumio[955]: info: Received Get System Info Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:37:47 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:47 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 22:37:47 volumio volumio5-onboarding[2939]: time=2026-10-12T22:37:47.886+03:00 level=INFO msg="enabling local network discovery" Oct 12 22:37:47 volumio volumio5-onboarding[2939]: time=2026-10-12T22:37:47.902+03:00 level=INFO msg="enabling BLE discovery" Oct 12 22:37:47 volumio volumio5-onboarding[2939]: time=2026-10-12T22:37:47.958+03:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 12 22:37:47 volumio volumio[955]: info: Received Get System Info Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:37:47 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:47 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:47 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:37:47 volumio volumio-remote-updater[617]: No test mode Oct 12 22:37:47 volumio volumio-remote-updater[617]: No alpha test mode Oct 12 22:37:48 volumio volumio[955]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 12 22:37:48 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 12 22:37:48 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:37:48 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:37:48 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 22:37:52 volumio volumio[955]: info: CoreCommandRouter::volumioPause Oct 12 22:37:52 volumio volumio[955]: info: CoreStateMachine::pause Oct 12 22:37:55 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:37:55 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:37:59 volumio volumio[955]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Oct 12 22:38:05 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:38:05 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:05 volumio volumio[955]: info: Listing playlists Oct 12 22:38:05 volumio volumio[955]: info: Listing playlists Oct 12 22:38:09 volumio volumio[955]: info: CoreCommandRouter::volumioRemoveQueueItem Oct 12 22:38:09 volumio volumio[955]: info: CoreStateMachine::removeQueueItem Oct 12 22:38:09 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:38:09 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:38:09 volumio volumio[955]: info: CorePlayQueue::removeQueueItem 0 Oct 12 22:38:09 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:38:09 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:38:15 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:38:15 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:16 volumio volumio[955]: info: CoreCommandRouter::volumioClearQueue Oct 12 22:38:16 volumio volumio[955]: info: CoreStateMachine::ClearQueue Oct 12 22:38:16 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:38:16 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:38:16 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Oct 12 22:38:16 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:38:16 volumio volumio[955]: info: CoreCommandRouter::volumioPushState Oct 12 22:38:16 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:38:17 volumio volumio5-onboarding[2939]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Oct 12 22:38:17 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 12 22:38:17 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 12 22:38:18 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 12 22:38:18 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 14. Oct 12 22:38:18 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Oct 12 22:38:18 volumio systemd[1]: Started Volumio5 Onboarding Server. Oct 12 22:38:18 volumio volumio5-onboarding[3035]: time=2026-10-12T22:38:18.326+03:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z Oct 12 22:38:18 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 22:38:18 volumio volumio[955]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Oct 12 22:38:18 volumio volumio[955]: info: Received Get System Info Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:38:18 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:38:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:38:18 volumio volumio5-onboarding[3035]: time=2026-10-12T22:38:18.361+03:00 level=INFO msg="system info for 6efa6b9583b3ed32dc40f2e8f5ba7091" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.912 Oct 12 22:38:18 volumio volumio[955]: info: Received Get System Info Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:38:18 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:38:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 12 22:38:18 volumio volumio5-onboarding[3035]: time=2026-10-12T22:38:18.374+03:00 level=INFO msg="enabling local network discovery" Oct 12 22:38:18 volumio volumio5-onboarding[3035]: time=2026-10-12T22:38:18.389+03:00 level=INFO msg="enabling BLE discovery" Oct 12 22:38:18 volumio volumio5-onboarding[3035]: time=2026-10-12T22:38:18.449+03:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 12 22:38:18 volumio volumio[955]: info: Received Get System Info Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 12 22:38:18 volumio volumio[955]: info: Discovery: Getting this device information Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::volumioGetState Oct 12 22:38:18 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 12 22:38:18 volumio volumio-remote-updater[617]: No test mode Oct 12 22:38:18 volumio volumio-remote-updater[617]: No alpha test mode Oct 12 22:38:18 volumio volumio[955]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 12 22:38:18 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 12 22:38:24 volumio volumio[955]: info: Preload queue cleared Oct 12 22:38:24 volumio volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::ClearQueue Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::clearPlayQueue Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:38:24 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::addQueueItems Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::addQueueItems Oct 12 22:38:24 volumio volumio[955]: info: Preload queue cleared Oct 12 22:38:24 volumio volumio[955]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99542934 Oct 12 22:38:24 volumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 12 22:38:24 volumio volumio[955]: info: CoreCommandRouter::volumioPushQueue Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::saveQueue Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::updateTrackBlock Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::getTrackBlock Oct 12 22:38:24 volumio volumio[955]: info: CoreCommandRouter::volumioPlay Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::play index 0 Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::stop Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::play index undefined Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:24 volumio volumio[955]: info: CoreStateMachine::startPlaybackTimer Oct 12 22:38:24 volumio volumio[955]: info: CorePlayQueue::getTrack 0 Oct 12 22:38:24 volumio volumio[955]: info: [1791833904049] ControllerWebradio::clearAddPlayTrack Oct 12 22:38:24 volumio volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Oct 12 22:38:24 volumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 22:38:24 volumio volumio[955]: Error: connect ETIMEDOUT 139.45.207.162:443 Oct 12 22:38:24 volumio volumio[955]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 12 22:38:24 volumio volumio[955]: errno: -110, Oct 12 22:38:24 volumio volumio[955]: code: 'ETIMEDOUT', Oct 12 22:38:24 volumio volumio[955]: syscall: 'connect', Oct 12 22:38:24 volumio volumio[955]: address: '139.45.207.162', Oct 12 22:38:24 volumio volumio[955]: port: 443 Oct 12 22:38:24 volumio volumio[955]: } Oct 12 22:38:24 volumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 12 22:38:24 volumio sudo[3059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-12 22:37 Oct 12 22:38:24 volumio sudo[3059]: 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"