-- Logs begin at Sat 2026-02-07 07:40:44 JST, end at Tue 2026-02-10 18:55:41 JST. -- Feb 10 18:54:05 volumio go-librespot[24685]: time="2026-02-10T18:54:05+09:00" level=trace msg="received accesspoint ping" Feb 10 18:54:05 volumio go-librespot[24685]: time="2026-02-10T18:54:05+09:00" level=trace msg="received accesspoint pong ack" Feb 10 18:54:06 volumio go-librespot[24685]: time="2026-02-10T18:54:06+09:00" level=trace msg="sent dealer ping" Feb 10 18:54:06 volumio go-librespot[24685]: time="2026-02-10T18:54:06+09:00" level=trace msg="received dealer pong" Feb 10 18:54:13 volumio sudo[25037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 18:54:13 volumio sudo[25037]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 18:54:13 volumio sudo[25039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 10 18:54:13 volumio sudo[25039]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 10 18:54:13 volumio volumio[24214]: info: Getting Spotify volume Feb 10 18:54:13 volumio systemd[1]: Stopping MPD Monitor Service... Feb 10 18:54:13 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 10 18:54:13 volumio volumio[24214]: error: MyVolumio Plugin failed to start in a timely fashion Feb 10 18:54:13 volumio volumio[24214]: [Metrics] CommandRouter: 267s 345.58ms Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::volumiosetStartupVolume Feb 10 18:54:13 volumio systemd[1]: mpd_monitor.service: Succeeded. Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 18:54:13 volumio systemd[1]: Stopped MPD Monitor Service. Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::Close All Modals sent Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::Close All Modals sent Feb 10 18:54:13 volumio systemd[1]: Started MPD Monitor Service. Feb 10 18:54:13 volumio sudo[25037]: pam_unix(sudo:session): session closed for user root Feb 10 18:54:13 volumio mpd_monitor.sh[25042]: MPD Monitor Service: Starting MPD Monitor Service Feb 10 18:54:13 volumio sudo[25039]: pam_unix(sudo:session): session closed for user root Feb 10 18:54:13 volumio volumio[24214]: info: Spotify volume: 100 Feb 10 18:54:13 volumio volumio[24214]: info: Successfully started MPD Monitor Feb 10 18:54:13 volumio volumio[24214]: info: Successfully started MPD Monitor Feb 10 18:54:13 volumio volumio[24214]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 10 18:54:13 volumio volumio[24214]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::volumioGetState Feb 10 18:54:13 volumio volumio[24214]: info: CorePlayQueue::getTrack 0 Feb 10 18:54:13 volumio volumio[24214]: SPOTIFY: User informations: {"country":"JP","display_name":"Fukushima M","email":"zest4rock@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31a7urbppb7ez5ym3pnmyl26ihva"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31a7urbppb7ez5ym3pnmyl26ihva","id":"31a7urbppb7ez5ym3pnmyl26ihva","images":[],"product":"premium","type":"user","uri":"spotify:user:31a7urbppb7ez5ym3pnmyl26ihva"} Feb 10 18:54:13 volumio volumio[24214]: info: Spotify Successfully logged in Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 10 18:54:13 volumio volumio[24214]: info: [1770717253848] CoreMusicLibrary::Adding element Spotify Feb 10 18:54:13 volumio volumio[24214]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 10 18:54:13 volumio volumio[24214]: Cannot find translation for source Spotify Feb 10 18:54:22 volumio volumio-remote-updater[607]: [2026-02-10 18:54:22] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Feb 10 18:54:27 volumio volumio-remote-updater[607]: [2026-02-10 18:54:27] [connect] Successful connection Feb 10 18:54:36 volumio go-librespot[24685]: time="2026-02-10T18:54:36+09:00" level=trace msg="sent dealer ping" Feb 10 18:54:36 volumio go-librespot[24685]: time="2026-02-10T18:54:36+09:00" level=trace msg="received dealer pong" Feb 10 18:54:40 volumio volumio-remote-updater[607]: [2026-02-10 18:54:40] [connect] Successful connection Feb 10 18:54:55 volumio volumio-remote-updater[607]: [2026-02-10 18:54:55] [connect] Successful connection Feb 10 18:55:06 volumio go-librespot[24685]: time="2026-02-10T18:55:06+09:00" level=trace msg="sent dealer ping" Feb 10 18:55:06 volumio go-librespot[24685]: time="2026-02-10T18:55:06+09:00" level=trace msg="received dealer pong" Feb 10 18:55:10 volumio volumio-remote-updater[607]: [2026-02-10 18:55:10] [connect] Successful connection Feb 10 18:55:25 volumio volumio-remote-updater[607]: [2026-02-10 18:55:25] [connect] Successful connection Feb 10 18:55:36 volumio go-librespot[24685]: time="2026-02-10T18:55:36+09:00" level=trace msg="sent dealer ping" Feb 10 18:55:36 volumio go-librespot[24685]: time="2026-02-10T18:55:36+09:00" level=trace msg="received dealer pong" Feb 10 18:55:40 volumio volumio-remote-updater[607]: [2026-02-10 18:55:40] [connect] Successful connection Feb 10 18:55:41 volumio volumio[24214]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 10 18:55:41 volumio volumio[24214]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 10 18:55:41 volumio volumio[24214]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 10 18:55:41 volumio volumio[24214]: info: BOOT COMPLETED Feb 10 18:55:41 volumio volumio[24214]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 18:55:41 volumio volumio[24214]: Error: socket hang up Feb 10 18:55:41 volumio volumio[24214]: at connResetException (internal/errors.js:607:14) Feb 10 18:55:41 volumio volumio[24214]: at TLSSocket.socketOnEnd (_http_client.js:493:23) Feb 10 18:55:41 volumio volumio[24214]: at TLSSocket.emit (events.js:327:22) Feb 10 18:55:41 volumio volumio[24214]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 10 18:55:41 volumio volumio[24214]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Feb 10 18:55:41 volumio volumio[24214]: code: 'ECONNRESET' Feb 10 18:55:41 volumio volumio[24214]: } Feb 10 18:55:41 volumio volumio[24214]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 18:55:41 volumio sudo[25223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-10 18:54 Feb 10 18:55:41 volumio sudo[25223]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"