-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Sat 2026-10-03 07:13:10 KST. -- Oct 03 07:12:05 volumio volumio5-onboarding[2045]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Oct 03 07:12:05 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 07:12:05 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 07:12:05 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 03 07:12:05 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 03 07:12:05 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Oct 03 07:12:06 volumio systemd[1]: Started Volumio5 Onboarding Server. Oct 03 07:12:06 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:06.074+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 07:12:07 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 07:12:14 volumio volumio[1064]: 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: 7 Oct 03 07:12:14 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 03 07:12:14 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.13 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 03 07:12:14 volumio volumio[1064]: 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: 9 Oct 03 07:12:14 volumio volumio[1064]: info: Received Get System Info Oct 03 07:12:14 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 07:12:14 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 07:12:14 volumio volumio[1064]: info: Discovery: Getting this device information Oct 03 07:12:14 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Oct 03 07:12:14 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Oct 03 07:12:14 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 07:12:14 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:14.886+09:00 level=INFO msg="system info for 169d5188372c6d8aedf0b10fbe83c6ba" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.914 Oct 03 07:12:14 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 03 07:12:14 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:14.933+09:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 03 07:12:15 volumio volumio[1064]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Oct 03 07:12:16 volumio volumio[1064]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.0.10 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/154.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Oct 03 07:12:16 volumio volumio[1064]: info: Received Get System Info Oct 03 07:12:16 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 07:12:16 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 07:12:16 volumio volumio[1064]: info: Discovery: Getting this device information Oct 03 07:12:16 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Oct 03 07:12:16 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Oct 03 07:12:16 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 07:12:17 volumio volumio-remote-updater[628]: Test mode enabled Oct 03 07:12:17 volumio volumio-remote-updater[628]: No alpha test mode Oct 03 07:12:18 volumio volumio[1064]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 07:12:18 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 07:12:18 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Oct 03 07:12:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Oct 03 07:12:18 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Oct 03 07:12:18 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Oct 03 07:12:19 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 07:12:20 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 07:12:20 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 07:12:22 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 07:12:22 volumio volumio[1064]: info: Listing playlists Oct 03 07:12:22 volumio volumio[1064]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 07:12:22 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 07:12:22 volumio volumio[1064]: info: CoreCommandRouter::volumioGetQueue Oct 03 07:12:22 volumio volumio[1064]: info: CoreStateMachine::getQueue Oct 03 07:12:22 volumio volumio[1064]: info: CorePlayQueue::getQueue Oct 03 07:12:36 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:36.768+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Oct 03 07:12:36 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:36.782+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Oct 03 07:12:36 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:36.782+09:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Oct 03 07:12:36 volumio volumio5-onboarding[2140]: time=2026-10-03T07:12:36.782+09:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Oct 03 07:12:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 07:12:42 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 07:12:42 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 03 07:12:44 volumio volumio5-onboarding[2140]: failed to bootstrap state: failed to get available plugins: could not get available plugins: context deadline exceeded Oct 03 07:12:44 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 07:12:44 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 07:12:45 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 03 07:12:45 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Oct 03 07:12:45 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Oct 03 07:12:45 volumio systemd[1]: Started Volumio5 Onboarding Server. Oct 03 07:12:45 volumio volumio5-onboarding[2207]: time=2026-10-03T07:12:45.291+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 07:12:49 volumio volumio-remote-updater[628]: [2026-10-03 07:12:49] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Oct 03 07:12:54 volumio volumio-remote-updater[628]: [2026-10-03 07:12:54] [connect] Successful connection Oct 03 07:12:55 volumio volumio5-onboarding[2207]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:41868->127.0.0.1:3000: i/o timeout Oct 03 07:12:55 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 03 07:12:55 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 03 07:12:55 volumio systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. Oct 03 07:12:55 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Oct 03 07:12:55 volumio systemd[1]: Stopped Volumio5 Onboarding Server. Oct 03 07:12:55 volumio systemd[1]: Started Volumio5 Onboarding Server. Oct 03 07:12:55 volumio volumio5-onboarding[2230]: time=2026-10-03T07:12:55.564+09:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Oct 03 07:13:05 volumio volumio[1064]: 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: 6 Oct 03 07:13:06 volumio volumio-remote-updater[628]: [2026-10-03 07:13:06] [connect] Successful connection Oct 03 07:13:09 volumio volumio[1064]: 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: 6 Oct 03 07:13:09 volumio volumio[1064]: info: Received Get System Info Oct 03 07:13:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 07:13:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 07:13:09 volumio volumio[1064]: info: Discovery: Getting this device information Oct 03 07:13:09 volumio volumio[1064]: info: CoreCommandRouter::volumioGetState Oct 03 07:13:09 volumio volumio[1064]: info: CorePlayQueue::getTrack 0 Oct 03 07:13:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 07:13:09 volumio volumio5-onboarding[2230]: time=2026-10-03T07:13:09.126+09:00 level=INFO msg="system info for 169d5188372c6d8aedf0b10fbe83c6ba" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=3.914 Oct 03 07:13:09 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 07:13:09 volumio volumio5-onboarding[2230]: time=2026-10-03T07:13:09.175+09:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 03 07:13:10 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 07:13:10 volumio volumio[1064]: Error: socket hang up Oct 03 07:13:10 volumio volumio[1064]: at connResetException (internal/errors.js:607:14) Oct 03 07:13:10 volumio volumio[1064]: at TLSSocket.socketOnEnd (_http_client.js:493:23) Oct 03 07:13:10 volumio volumio[1064]: at TLSSocket.emit (events.js:327:22) Oct 03 07:13:10 volumio volumio[1064]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 03 07:13:10 volumio volumio[1064]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Oct 03 07:13:10 volumio volumio[1064]: code: 'ECONNRESET' Oct 03 07:13:10 volumio volumio[1064]: } Oct 03 07:13:10 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 07:13:10 volumio sudo[2304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-03 07:12 Oct 03 07:13:10 volumio sudo[2304]: 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="5c0e72e4b5817fe66c91c9d15d75ff13b8e3f4ba" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Mar 2026 02:51:29 PM CEST" VOLUMIO_VERSION="3.914" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="98fe12c88d8ff391f569279e2f874892"