-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sat 2026-03-28 12:04:13 CST. -- Mar 28 12:03:00 volumio go-librespot[2382]: time="2026-03-28T12:03:00+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 28 12:03:00 volumio go-librespot[2382]: time="2026-03-28T12:03:00+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 28 12:03:00 volumio go-librespot[2382]: time="2026-03-28T12:03:00+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 28 12:03:00 volumio go-librespot[2382]: time="2026-03-28T12:03:00+08:00" level=debug msg="zeroconf server listening on port 34763" Mar 28 12:03:00 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:01 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 12:03:01 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 12:03:01 volumio go-librespot[2382]: time="2026-03-28T12:03:01+08:00" level=debug msg="new websocket client" Mar 28 12:03:01 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:01 volumio volumio[1007]: Upnp client error: Error: This socket has been ended by the other party Mar 28 12:03:01 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:01 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:01 volumio go-librespot[2382]: time="2026-03-28T12:03:01+08:00" level=debug msg="obtained new client token: AACMb7Kx8P8UYt8tKo0CI4/abgT0PeuWWGFeyMs2/1bxSxCCExyWNZEiseJ4f+f9D1JvWvNCGE1kN+XcB5OixnEHbBy6rG9lAumzjnqX7uxox/37fFxEree5U1X/eBB/nuwCu72ZQcIbOQd7XKajS996da3Lp/dYUK1/72fK6+j5JhF9SrWwVZSgLWnoaKdMkqYBLnnbmF84C7Vtjp0JrBTC7wFn7RPCIsTt3hrv6AHoNxqDlYMSlNM+8Ok=" Mar 28 12:03:01 volumio go-librespot[2382]: time="2026-03-28T12:03:01+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:01 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 12:03:01 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:01 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:02 volumio go-librespot[2382]: time="2026-03-28T12:03:02+08:00" level=debug msg="completed challenge" Mar 28 12:03:02 volumio go-librespot[2382]: time="2026-03-28T12:03:02+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:02 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:04 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:04 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:04 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:04 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 17) Mar 28 12:03:04 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:03:04 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:04 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:05 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:05 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 23. Mar 28 12:03:05 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:05 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:05 volumio go-librespot[2415]: Librespot-go daemon starting... Mar 28 12:03:05 volumio go-librespot[2415]: time="2026-03-28T12:03:05+08:00" level=info msg="generated new device id: c1a9d5fcb48f3769af3328440ceabc1dd1894f34" Mar 28 12:03:05 volumio go-librespot[2415]: time="2026-03-28T12:03:05+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:06 volumio volumio[1007]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 12:03:06 volumio volumio[1007]: error: Cannot download Available plugins list: Error: Client network socket disconnected before secure TLS connection was established Mar 28 12:03:06 volumio go-librespot[2415]: time="2026-03-28T12:03:06+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:06 volumio go-librespot[2415]: time="2026-03-28T12:03:06+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:06 volumio go-librespot[2415]: time="2026-03-28T12:03:06+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:06 volumio go-librespot[2415]: time="2026-03-28T12:03:06+08:00" level=debug msg="zeroconf server listening on port 40861" Mar 28 12:03:06 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%89%8D%E6%97%A6%E5%8D%93%E7%8E%9B/aecc4351-5663-4ee9-bfc8-d74c5d5e7cbe.jpg' Mar 28 12:03:07 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%89%BF%E5%88%A9/2d06a358-efac-41f1-8861-8a16d4f1ec35.jpg' Mar 28 12:03:07 volumio go-librespot[2415]: time="2026-03-28T12:03:07+08:00" level=debug msg="obtained new client token: AABOqWDsqfS4EM2UGjDJhM7WaPZicYdu3IGNyLNo5w/PCZ6QWBXdVu0bTW8L063mwhebMimAqPtg8jOux7i4xBpGhyTU4gkPPXwLif747+xJ3orEYTJODG2NuoNqU0DWyTlWI0m8JFM0fTy8YMh3o/Yx5XCCWznejVloizHZD65ubFmm72ExIez+QCMzg4JhmBtCE7cavAyb/thenbJagPCmY/w/IUmmq1LM7D4Lqnf9Jc1l47eY7dSYCug=" Mar 28 12:03:07 volumio go-librespot[2415]: time="2026-03-28T12:03:07+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:08 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:08 volumio go-librespot[2415]: time="2026-03-28T12:03:08+08:00" level=debug msg="completed challenge" Mar 28 12:03:08 volumio go-librespot[2415]: time="2026-03-28T12:03:08+08:00" level=debug msg="new websocket client" Mar 28 12:03:08 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:08 volumio go-librespot[2415]: time="2026-03-28T12:03:08+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:08 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:10 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E7%8E%B2%E7%8E%89/29f49210-6653-4b06-8033-08ad3ed7fa23.jpg' Mar 28 12:03:11 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%8E%E9%9D%96%E7%AD%A0/aa718a21-70cb-497c-95e7-bbbca05c91c3.jpg' Mar 28 12:03:11 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:11 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:11 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:11 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 18) Mar 28 12:03:11 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:03:11 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:11 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:11 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:11 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:11 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9D%A8%E5%8D%83%E5%AC%85/42e02079-27eb-45a4-b51a-1ee2e8fe535c.jpg' Mar 28 12:03:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 24. Mar 28 12:03:11 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:11 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:11 volumio go-librespot[2450]: Librespot-go daemon starting... Mar 28 12:03:11 volumio go-librespot[2450]: time="2026-03-28T12:03:11+08:00" level=info msg="generated new device id: ccfb339dd47025b208caf943f2a05200c67b1ae9" Mar 28 12:03:11 volumio go-librespot[2450]: time="2026-03-28T12:03:11+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:12 volumio go-librespot[2450]: time="2026-03-28T12:03:12+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:12 volumio go-librespot[2450]: time="2026-03-28T12:03:12+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:12 volumio go-librespot[2450]: time="2026-03-28T12:03:12+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:12 volumio go-librespot[2450]: time="2026-03-28T12:03:12+08:00" level=debug msg="zeroconf server listening on port 45619" Mar 28 12:03:13 volumio volumio[1007]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Mar 28 12:03:13 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Mar 28 12:03:13 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:13 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:13 volumio volumio[1007]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 12:03:13 volumio go-librespot[2450]: time="2026-03-28T12:03:13+08:00" level=debug msg="obtained new client token: AADs2DLAjkN5pH48QFH4KbUim4iVlvQf3JgOLt1iLKIQzHnfx9IPsbBY3ssoUd+OGFMOAC+fgzeTJXtMPhdFXMqIBUG66WSq29N910u9eu1L0GEjmvJdKjdAGBdabyaof0XOceMS+jS3B39xaA2bHWsSOr7SgknRwxeKqAPtgLPl58qdzDTw8bLYq50P6VNqntU9u0UzAyLmYA4bmoKGqVNjj1T5MC6V+uoBzw6ppTZ+vhvvh/CMC52KU69p9A==" Mar 28 12:03:14 volumio go-librespot[2450]: time="2026-03-28T12:03:14+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:14 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:14 volumio go-librespot[2450]: time="2026-03-28T12:03:14+08:00" level=debug msg="new websocket client" Mar 28 12:03:14 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:14 volumio go-librespot[2450]: time="2026-03-28T12:03:14+08:00" level=debug msg="completed challenge" Mar 28 12:03:14 volumio go-librespot[2450]: time="2026-03-28T12:03:14+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:14 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:16 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E5%BF%97%E7%82%AB/63959f18-c583-46d3-af39-d8cfc07a6ef3.jpg' Mar 28 12:03:16 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E6%99%8F%E5%A6%82/199a67c0-adc1-48d9-bae0-58b297c18cbf.jpg' Mar 28 12:03:17 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E6%9F%94%E5%9D%87/b2c3e644-ebda-4ed2-9d5d-e17d52439bc5.jpg' Mar 28 12:03:17 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:17 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:17 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:17 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 19) Mar 28 12:03:17 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:03:17 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:17 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:17 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9E%97%E7%BE%8E%E6%83%A0/02a4fcd1-1ee1-4a29-a04a-9aed03b1abff.jpg' Mar 28 12:03:17 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%9F%B3%E5%BD%B1%E8%99%B9/9d31d1bf-f983-4989-b28a-a9368ce4cd12.jpg' Mar 28 12:03:17 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:17 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 25. Mar 28 12:03:17 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:17 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:18 volumio go-librespot[2475]: Librespot-go daemon starting... Mar 28 12:03:18 volumio go-librespot[2475]: time="2026-03-28T12:03:18+08:00" level=info msg="generated new device id: edba0c8c7358fd69c85407f39de41e674b92051e" Mar 28 12:03:18 volumio go-librespot[2475]: time="2026-03-28T12:03:18+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:18 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%81%E9%9D%99%E8%8C%B9/fb0f5055-7d9b-4635-851e-ea962e852553.jpg' Mar 28 12:03:18 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%A2%85%E8%89%B3%E8%8A%B3/3d9d9f99-dd18-4614-942d-945acded825e.jpg' Mar 28 12:03:18 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B1%9F%E7%86%9A%E7%94%9F/7b709005-5461-47c4-920e-a766f8bc015f.jpg' Mar 28 12:03:19 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B3%B0%E8%BF%AA%E7%BD%97%E5%AE%BE/4f79169f-a652-433d-9065-41721d9ea21c.jpg' Mar 28 12:03:19 volumio go-librespot[2475]: time="2026-03-28T12:03:19+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:19 volumio go-librespot[2475]: time="2026-03-28T12:03:19+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:19 volumio go-librespot[2475]: time="2026-03-28T12:03:19+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:19 volumio go-librespot[2475]: time="2026-03-28T12:03:19+08:00" level=debug msg="zeroconf server listening on port 43249" Mar 28 12:03:20 volumio go-librespot[2475]: time="2026-03-28T12:03:20+08:00" level=debug msg="obtained new client token: AABlC8C2bSep9BVMGGSMXTLzXX6vxT0egb/ETHN2PFNxg7NcerokShGVqCIK2SjJzs/Y9maDQf7k5AwvQmrv9wanhyChZk2Ftd/cmUt2ediRG/zSNle7C4djYPLIrlMf/dulEjUfwmN2SjLrnRKoigqe28pzvEs4RrPR6NZgdzKHofHIoo4Eu6azRxZiRxWfm2lARTFlrzu0LiwpWbf0ZetVFjHHieHZ80wnFtWfdwtd5CRc3P+YA2ZN+3Q=" Mar 28 12:03:20 volumio go-librespot[2475]: time="2026-03-28T12:03:20+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:20 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:20 volumio go-librespot[2475]: time="2026-03-28T12:03:20+08:00" level=debug msg="new websocket client" Mar 28 12:03:20 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:20 volumio go-librespot[2475]: time="2026-03-28T12:03:20+08:00" level=debug msg="completed challenge" Mar 28 12:03:21 volumio go-librespot[2475]: time="2026-03-28T12:03:21+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:21 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:22 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%B8%A9%E5%B2%9A/dbf0d6f2-cd88-4482-bf82-209085c85459.jpg' Mar 28 12:03:22 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%89%9B%E6%AC%A3%E6%AC%A3/b094686d-c939-4b17-8f78-0c6d4d5c8998.jpg' Mar 28 12:03:22 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8C%AA%E5%93%A5%E4%BA%AE/c5446a4c-b548-43d8-9032-eef066764f7a.jpg' Mar 28 12:03:23 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E5%8A%9B%E5%AE%8F/d599406f-8c85-4645-b08d-5e55dcb349b9.jpg' Mar 28 12:03:23 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:23 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:23 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:23 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 20) Mar 28 12:03:23 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:03:23 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:23 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:24 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:24 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:24 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E7%91%9E%E9%9C%9E/f409af6b-4eb9-46df-9506-d29d8b826c1c.jpg' Mar 28 12:03:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 26. Mar 28 12:03:24 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:24 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:24 volumio go-librespot[2550]: Librespot-go daemon starting... Mar 28 12:03:24 volumio go-librespot[2550]: time="2026-03-28T12:03:24+08:00" level=info msg="generated new device id: 66fce077a154f688b8376ef60868cbe5c426fd8d" Mar 28 12:03:24 volumio go-librespot[2550]: time="2026-03-28T12:03:24+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:25 volumio go-librespot[2550]: time="2026-03-28T12:03:25+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:25 volumio go-librespot[2550]: time="2026-03-28T12:03:25+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:25 volumio go-librespot[2550]: time="2026-03-28T12:03:25+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:25 volumio go-librespot[2550]: time="2026-03-28T12:03:25+08:00" level=debug msg="zeroconf server listening on port 43469" Mar 28 12:03:25 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%A5%E6%AC%A3/d716ff32-156d-423f-b2d5-e78481e29b62.jpg' Mar 28 12:03:26 volumio go-librespot[2550]: time="2026-03-28T12:03:26+08:00" level=debug msg="obtained new client token: AADuke/v0s5IGcg/i3nYAwUdN1tWoT1tDkh/J81TNWofHmTAq2Hn8p/pqkEuQC2mvM04qSq/xGTN09GMvbLSIDcczi9kJyx2rLGYu9k2EYS7Pk3/Q1l/I9udpaBB0nGMiUesKscJHQp2rv3ep7BgzsiGOi8W8vHyy+NOn06z5Vyhr0Jcv3B0oKxMqtLWyYXZRwwohBaKYbj+guBVMQRC5EpHnc8rVatbVJ8kFtHmE0+Y/q0Jf+Pg8gWEH6RswQ==" Mar 28 12:03:26 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%B4%A2%E6%9C%97%E6%97%BA%E5%A7%86/9d619dc0-9160-4953-869c-a19691680197.jpg' Mar 28 12:03:26 volumio go-librespot[2550]: time="2026-03-28T12:03:26+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:26 volumio go-librespot[2550]: time="2026-03-28T12:03:26+08:00" level=debug msg="completed challenge" Mar 28 12:03:27 volumio go-librespot[2550]: time="2026-03-28T12:03:27+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:27 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:27 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:30 volumio volumio[1007]: info: CALLMETHOD: user_interface touch_display saveOrientationConf [object Object] Mar 28 12:03:30 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveOrientationConf Mar 28 12:03:30 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:30 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Mar 28 12:03:30 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:30 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:30 volumio go-librespot[2584]: Librespot-go daemon starting... Mar 28 12:03:30 volumio go-librespot[2584]: time="2026-03-28T12:03:30+08:00" level=info msg="generated new device id: cbf1fd8f98c2537872b9697aff1b965daea53c0c" Mar 28 12:03:30 volumio go-librespot[2584]: time="2026-03-28T12:03:30+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:31 volumio go-librespot[2584]: time="2026-03-28T12:03:31+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:31 volumio go-librespot[2584]: time="2026-03-28T12:03:31+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:31 volumio go-librespot[2584]: time="2026-03-28T12:03:31+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:31 volumio go-librespot[2584]: time="2026-03-28T12:03:31+08:00" level=debug msg="zeroconf server listening on port 43037" Mar 28 12:03:32 volumio volumio[1007]: info: CoreCommandRouter::Close All Modals sent Mar 28 12:03:33 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:33 volumio go-librespot[2584]: time="2026-03-28T12:03:33+08:00" level=debug msg="new websocket client" Mar 28 12:03:33 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:33 volumio go-librespot[2584]: time="2026-03-28T12:03:33+08:00" level=debug msg="obtained new client token: AAASTnPlkJYU4oA4r4olGc1c9Ncz8j6Wkhbn94O3RQiyzCwfSkxPYAO1Az0fQnSIcV/XHez0JgIx9fTv+7otJy/IRBD3GRpSrxbkoSti5iGYKstZJDdq2UdR6Yum2AjUzQx5CtHrXvY43MqfKLUuwJWZ737jdoW5B4CpW2CKNdrGEwexlM6PdBdGPtZZBj/WW5E7HTWvOPOMPGDlG/7ssgXORuZ+rrhu/SmELY5jTKpLRkteQ4fiHc+UV/luKQ==" Mar 28 12:03:33 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8B%8F%E5%8B%92%E4%BA%9A%E5%85%B6%E5%85%B6%E6%A0%BC/03599943-a084-4a03-84f2-91a84e1d8895.jpg' Mar 28 12:03:33 volumio go-librespot[2584]: time="2026-03-28T12:03:33+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:34 volumio go-librespot[2584]: time="2026-03-28T12:03:34+08:00" level=debug msg="completed challenge" Mar 28 12:03:34 volumio go-librespot[2584]: time="2026-03-28T12:03:34+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:34 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:36 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:36 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:36 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:36 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 21) Mar 28 12:03:36 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:03:36 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:36 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:36 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8B%8F%E6%B0%B8%E5%BA%B7/a62767a9-b89b-4352-9a55-841a6860c289.jpg' Mar 28 12:03:37 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:37 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Mar 28 12:03:37 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:37 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:37 volumio go-librespot[2609]: Librespot-go daemon starting... Mar 28 12:03:37 volumio go-librespot[2609]: time="2026-03-28T12:03:37+08:00" level=info msg="generated new device id: 24b79fc37837e14ac81713746c6da59ec7d75342" Mar 28 12:03:37 volumio go-librespot[2609]: time="2026-03-28T12:03:37+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:39 volumio go-librespot[2609]: time="2026-03-28T12:03:39+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Mar 28 12:03:39 volumio go-librespot[2609]: time="2026-03-28T12:03:39+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Mar 28 12:03:39 volumio go-librespot[2609]: time="2026-03-28T12:03:39+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Mar 28 12:03:39 volumio go-librespot[2609]: time="2026-03-28T12:03:39+08:00" level=debug msg="zeroconf server listening on port 39809" Mar 28 12:03:39 volumio go-librespot[2609]: time="2026-03-28T12:03:39+08:00" level=debug msg="obtained new client token: AADdiQgLvuofkbnMD6g1E4Q42MOgiUrfSN0S03/Es5V6wx7r04K1Kj7wvyo8wgVTR3exmrzxQesbhpTAp+VhXAebNY4flJ5Qvj55jfAlqEQTepllDYNLt6UWeQcj3eiK/GNg3o5u0igGQL3i16DyqNn+/c7kLt6poIPxQpmogKyG1fBIAYOScO/O65klgaZrtI9xfUG7AzL22w2pce8tEYUx2ojRtgQxptusVNwGF1U7VveNYfwk/ReyfrwhgA==" Mar 28 12:03:39 volumio go-librespot[2609]: time="2026-03-28T12:03:39+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:40 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%94%A1%E5%B0%8F%E8%99%8E/617c1fb1-c6b9-4bad-b6d0-67a3f3b80c8f.jpg' Mar 28 12:03:40 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:40 volumio go-librespot[2609]: time="2026-03-28T12:03:40+08:00" level=debug msg="new websocket client" Mar 28 12:03:40 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:40 volumio go-librespot[2609]: time="2026-03-28T12:03:40+08:00" level=debug msg="completed challenge" Mar 28 12:03:40 volumio go-librespot[2609]: time="2026-03-28T12:03:40+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:40 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:40 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%9C%9C%E9%9B%AA%E8%96%87%E7%90%AA/8e347c17-61c3-4fd1-aa41-e46622e515eb.jpg' Mar 28 12:03:41 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%A2%81%E5%B0%8F%E8%BF%AA/ef9b715e-e6b4-4bb0-b2b7-c08c572df61f.jpg' Mar 28 12:03:41 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%86%A0%E8%8B%B1/c8cc42c5-3c9b-4951-adaa-36d84c83593d.jpg' Mar 28 12:03:41 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%BF%97%E5%AE%89/5c0ac885-49f1-472f-b38a-e71f7ca519d6.jpg' Mar 28 12:03:41 volumio ntpd[815]: Soliciting pool server 84.16.73.33 Mar 28 12:03:41 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E9%9D%96%E9%9F%B5/3e407f19-68a7-4a5c-bb02-1bf91a76d3db.jpg' Mar 28 12:03:42 volumio volumio[1007]: info: CALLMETHOD: user_interface touch_display savePointerConf [object Object] Mar 28 12:03:42 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: touch_display , savePointerConf Mar 28 12:03:43 volumio sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /ExecStart=\\/usr\\/bin\\/startx \\/etc\\/X11\\/Xsession \\/opt\\/volumiokiosk.sh/c\\ExecStart=\\/usr\\/bin\\/startx \\/etc\\/X11\\/Xsession \\/opt\\/volumiokiosk.sh /lib/systemd/system/volumio-kiosk.service Mar 28 12:03:43 volumio sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 12:03:43 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:43 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:43 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:43 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 22) Mar 28 12:03:43 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:03:43 volumio sudo[2643]: pam_unix(sudo:session): session closed for user root Mar 28 12:03:43 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:43 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:43 volumio sudo[2648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 28 12:03:43 volumio sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 12:03:43 volumio systemd[1]: Reloading. Mar 28 12:03:43 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:43 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:44 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 28 12:03:45 volumio systemd[1]: volumio-kiosk.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 28 12:03:45 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:45 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Mar 28 12:03:45 volumio sudo[2648]: pam_unix(sudo:session): session closed for user root Mar 28 12:03:45 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:45 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:45 volumio volumio[1007]: info: touch_display: systemctl daemon-reload succeeded. Mar 28 12:03:45 volumio go-librespot[2667]: Librespot-go daemon starting... Mar 28 12:03:45 volumio go-librespot[2667]: time="2026-03-28T12:03:45+08:00" level=info msg="generated new device id: 7d8b31dd6fa33b540bfb3a311696d51729db27dd" Mar 28 12:03:45 volumio go-librespot[2667]: time="2026-03-28T12:03:45+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:45 volumio sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Mar 28 12:03:45 volumio sudo[2675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 12:03:45 volumio systemd[1]: Stopping Volumio Kiosk... Mar 28 12:03:45 volumio startx[1342]: xinit: connection to X server lost Mar 28 12:03:45 volumio startx[1342]: [1B blob data] Mar 28 12:03:45 volumio startx[1342]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Mar 28 12:03:46 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%B5%B5%E5%AD%A6%E8%80%8C/45d5eab7-a991-40c4-975f-cf857e30de93.jpg' Mar 28 12:03:46 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:46 volumio go-librespot[2667]: time="2026-03-28T12:03:46+08:00" level=debug msg="new websocket client" Mar 28 12:03:46 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:46 volumio go-librespot[2667]: time="2026-03-28T12:03:46+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:46 volumio go-librespot[2667]: time="2026-03-28T12:03:46+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:46 volumio go-librespot[2667]: time="2026-03-28T12:03:46+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:46 volumio ntpd[815]: Soliciting pool server 139.199.214.202 Mar 28 12:03:46 volumio ntpd[815]: Soliciting pool server 95.111.202.5 Mar 28 12:03:46 volumio go-librespot[2667]: time="2026-03-28T12:03:46+08:00" level=debug msg="zeroconf server listening on port 39995" Mar 28 12:03:46 volumio startx[1342]: xinit: unexpected signal 15 Mar 28 12:03:46 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:46 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 28 12:03:46 volumio systemd[1]: Stopped Volumio Kiosk. Mar 28 12:03:46 volumio systemd[1]: Started Volumio Kiosk. Mar 28 12:03:46 volumio sudo[2675]: pam_unix(sudo:session): session closed for user root Mar 28 12:03:46 volumio volumio[1007]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Mar 28 12:03:46 volumio volumio[1007]: info: touch_display: Volumio Kiosk restarted. Mar 28 12:03:47 volumio volumio[1007]: info: touch_display: X display number found: Mar 28 12:03:47 volumio volumio[1007]: info: touch_display: X display number found: 0 Mar 28 12:03:47 volumio startx[2685]: X.Org X Server 1.20.4 Mar 28 12:03:47 volumio startx[2685]: X Protocol Version 11, Revision 0 Mar 28 12:03:47 volumio startx[2685]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 28 12:03:47 volumio startx[2685]: Current Operating System: Linux volumio 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Mar 28 12:03:47 volumio startx[2685]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=480 bcm2708_fb.fbheight=320 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=0c3a77ec-ae84-4f1b-8028-c61cc251ffe5 imgfile=/volumio_current.sqsh bootpart=UUID=41CA-9A8B datapart=UUID=0324d53a-d066-482b-b659-eb98af2b49e7 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 28 12:03:47 volumio startx[2685]: Build Date: 04 April 2023 07:50:56AM Mar 28 12:03:47 volumio startx[2685]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 28 12:03:47 volumio startx[2685]: Current version of pixman: 0.36.0 Mar 28 12:03:47 volumio startx[2685]: Before reporting problems, check http://wiki.x.org Mar 28 12:03:47 volumio startx[2685]: to make sure that you have the latest version. Mar 28 12:03:47 volumio startx[2685]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 28 12:03:47 volumio startx[2685]: (++) from command line, (!!) notice, (II) informational, Mar 28 12:03:47 volumio startx[2685]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 28 12:03:47 volumio startx[2685]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Mar 28 12:03:47 2026 Mar 28 12:03:47 volumio volumio[1007]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 28 12:03:47 volumio startx[2685]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 28 12:03:47 volumio startx[2685]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 28 12:03:47 volumio go-librespot[2667]: time="2026-03-28T12:03:47+08:00" level=debug msg="obtained new client token: AACQvfnV/FLRGg0PU2W79wcm/i2wSAvrdgHfXoI+R03y6vyxycOQeNRP0Lnsi017Ad+WVy0t/ci6Q3PcDBKUwt7q0gdSw268oGcGkYfTh/VbIOk4+aMn6tJUR7P4oh7wptFuV1gAz46mWJC6JfnQ6g24FROGKH7EjWpAPUguiNuo6TmUEA7l5uv3R9owaaZfikypV1jp6A+KeQJm4UR8CH+nc5IVlcalh5ZsCN2lr60ItTe+dNMEBvOuHqk=" Mar 28 12:03:47 volumio volumio[1007]: info: touch_display: X display number found: 0 Mar 28 12:03:47 volumio go-librespot[2667]: time="2026-03-28T12:03:47+08:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.241.202:4070: connect: connection refused" Mar 28 12:03:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:47 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:47 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%BF%9E%E8%AF%97%E9%9B%85/07c2d3c7-fcc9-4b67-9978-2b620e5e63cc.jpg' Mar 28 12:03:48 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:48 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:48 volumio volumio[1007]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 12:03:48 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%91%E7%A7%80%E6%96%87/5dba0ef6-6839-49eb-993a-d8faa254e87e.jpg' Mar 28 12:03:48 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%83%AD%E5%AF%8C%E5%9F%8E/b94f14b2-3235-4c46-aa9b-9853dcce3eab.jpg' Mar 28 12:03:49 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:49 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:49 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:49 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 23) Mar 28 12:03:49 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 12:03:49 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:49 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:50 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:50 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Mar 28 12:03:50 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:50 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:50 volumio go-librespot[2816]: Librespot-go daemon starting... Mar 28 12:03:50 volumio go-librespot[2816]: time="2026-03-28T12:03:50+08:00" level=info msg="generated new device id: 8b55b1bdac55e968fd0084ac2bc04b7b371d2a9b" Mar 28 12:03:50 volumio go-librespot[2816]: time="2026-03-28T12:03:50+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:51 volumio ntpd[815]: Soliciting pool server 101.35.255.34 Mar 28 12:03:52 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%94%A6%E7%BB%A3%E4%BA%8C%E9%87%8D%E5%94%B1/9c034e64-b5e1-49fb-bc6d-215c0b5d33ea.jpg' Mar 28 12:03:52 volumio go-librespot[2816]: time="2026-03-28T12:03:52+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:52 volumio go-librespot[2816]: time="2026-03-28T12:03:52+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:52 volumio go-librespot[2816]: time="2026-03-28T12:03:52+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:52 volumio go-librespot[2816]: time="2026-03-28T12:03:52+08:00" level=debug msg="zeroconf server listening on port 39613" Mar 28 12:03:52 volumio volumio[1007]: info: CALLMETHOD: user_interface touch_display saveScaleConf [object Object] Mar 28 12:03:52 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScaleConf Mar 28 12:03:52 volumio go-librespot[2816]: time="2026-03-28T12:03:52+08:00" level=debug msg="obtained new client token: AABKQ8m2zJVgTEGFehD9Ik1/zfJ8Ur3aO1x/P4bt4w4VR+fg81m52NPzJAH5fdHDRy4jSwh4hgk3lSJSJRGC5Qo9ABsRk3nCIHV//2LQHfOVK466urPhnrGXHjEZRxTYpmCYjEPGv3xSRYSm86abcF5aI2E7ZQs8Ab9oKYYabrRQ25a5aNOzw3xh42aViYx7JWXYrtr0thOiXRnLa4QZSkbBZkS0Rre/K6wgfmntPUPsCh3c91Kef64aV9cYEQ==" Mar 28 12:03:53 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:53 volumio go-librespot[2816]: time="2026-03-28T12:03:53+08:00" level=debug msg="new websocket client" Mar 28 12:03:53 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:03:53 volumio go-librespot[2816]: time="2026-03-28T12:03:53+08:00" level=debug msg="completed keyexchange" Mar 28 12:03:54 volumio sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/factor=.* /factor=1.5 / /opt/volumiokiosk.sh Mar 28 12:03:54 volumio sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 12:03:54 volumio sudo[2878]: pam_unix(sudo:session): session closed for user root Mar 28 12:03:54 volumio go-librespot[2816]: time="2026-03-28T12:03:54+08:00" level=debug msg="completed challenge" Mar 28 12:03:54 volumio sudo[2885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Mar 28 12:03:54 volumio sudo[2885]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 28 12:03:54 volumio systemd[1]: Stopping Volumio Kiosk... Mar 28 12:03:54 volumio startx[2685]: xinit: connection to X server lost Mar 28 12:03:54 volumio startx[2685]: [1B blob data] Mar 28 12:03:54 volumio go-librespot[2816]: time="2026-03-28T12:03:54+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:03:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:54 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:03:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:03:54 volumio startx[2685]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Mar 28 12:03:55 volumio startx[2685]: xinit: unexpected signal 15 Mar 28 12:03:55 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:03:55 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Mar 28 12:03:55 volumio systemd[1]: Stopped Volumio Kiosk. Mar 28 12:03:55 volumio systemd[1]: Started Volumio Kiosk. Mar 28 12:03:55 volumio sudo[2885]: pam_unix(sudo:session): session closed for user root Mar 28 12:03:55 volumio volumio[1007]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Mar 28 12:03:55 volumio volumio[1007]: info: touch_display: Volumio Kiosk restarted. Mar 28 12:03:55 volumio volumio[1007]: info: touch_display: X display number found: Mar 28 12:03:55 volumio startx[2894]: X.Org X Server 1.20.4 Mar 28 12:03:55 volumio startx[2894]: X Protocol Version 11, Revision 0 Mar 28 12:03:55 volumio startx[2894]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Mar 28 12:03:55 volumio startx[2894]: Current Operating System: Linux volumio 6.12.74-v7+ #1948 SMP Mon Mar 2 11:25:27 GMT 2026 armv7l Mar 28 12:03:55 volumio startx[2894]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=480 bcm2708_fb.fbheight=320 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=0c3a77ec-ae84-4f1b-8028-c61cc251ffe5 imgfile=/volumio_current.sqsh bootpart=UUID=41CA-9A8B datapart=UUID=0324d53a-d066-482b-b659-eb98af2b49e7 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Mar 28 12:03:55 volumio startx[2894]: Build Date: 04 April 2023 07:50:56AM Mar 28 12:03:55 volumio startx[2894]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Mar 28 12:03:55 volumio startx[2894]: Current version of pixman: 0.36.0 Mar 28 12:03:55 volumio startx[2894]: Before reporting problems, check http://wiki.x.org Mar 28 12:03:55 volumio startx[2894]: to make sure that you have the latest version. Mar 28 12:03:55 volumio startx[2894]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 28 12:03:55 volumio startx[2894]: (++) from command line, (!!) notice, (II) informational, Mar 28 12:03:55 volumio startx[2894]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 28 12:03:55 volumio startx[2894]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Mar 28 12:03:55 2026 Mar 28 12:03:55 volumio startx[2894]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 28 12:03:55 volumio startx[2894]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 28 12:03:55 volumio volumio[1007]: info: touch_display: X display number found: 0 Mar 28 12:03:55 volumio volumio[1007]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 28 12:03:55 volumio volumio[1007]: info: touch_display: X display number found: 0 Mar 28 12:03:56 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E5%83%96%E4%BB%AA/389a92c4-b8e2-4b77-b2a5-435fa317118f.jpg' Mar 28 12:03:56 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:56 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:56 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:03:56 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:56 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:03:56 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 24) Mar 28 12:03:56 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 12:03:56 volumio volumio[1007]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 28 12:03:56 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E5%8D%87/11d4cf69-f14d-4569-83ad-240526c3154d.jpg' Mar 28 12:03:56 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:03:56 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:03:57 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:03:57 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:03:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:03:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Mar 28 12:03:57 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:03:57 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:03:57 volumio go-librespot[2986]: Librespot-go daemon starting... Mar 28 12:03:57 volumio go-librespot[2986]: time="2026-03-28T12:03:57+08:00" level=info msg="generated new device id: a14af9a71b75913c9046cf66db34e357dfaaba20" Mar 28 12:03:57 volumio go-librespot[2986]: time="2026-03-28T12:03:57+08:00" level=debug msg="stored credentials not found" Mar 28 12:03:57 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E5%B0%8F%E9%9C%9E/05404fd9-3df5-455b-b192-e5e3f3eb031c.jpg' Mar 28 12:03:57 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E6%85%A7%E5%A8%B4/84ec20a7-6bcf-466a-a343-427675d6c3fe.jpg' Mar 28 12:03:58 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Mar 28 12:03:58 volumio volumio[1007]: info: [bandcamp-browse] browseUri: bandcamp Mar 28 12:03:58 volumio go-librespot[2986]: time="2026-03-28T12:03:58+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:03:58 volumio go-librespot[2986]: time="2026-03-28T12:03:58+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:03:58 volumio go-librespot[2986]: time="2026-03-28T12:03:58+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:03:58 volumio go-librespot[2986]: time="2026-03-28T12:03:58+08:00" level=debug msg="zeroconf server listening on port 38501" Mar 28 12:03:59 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E6%B7%91%E8%90%8D/69c3a3f0-5f41-4e57-b634-46dbbfb6f309.jpg' Mar 28 12:04:00 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:04:00 volumio go-librespot[2986]: time="2026-03-28T12:04:00+08:00" level=debug msg="new websocket client" Mar 28 12:04:00 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:04:00 volumio go-librespot[2986]: time="2026-03-28T12:04:00+08:00" level=debug msg="obtained new client token: AABz2H1shI5d5BdotAhtleeGgmsJgrmYfJ515azCltV9z17DIpl1GddtnxjQW1dm+jbiKB4ScV5Nhh00YFeMiXH8hOl1gJdQ2ODe8/8RRRreeS/MlVrQFIc1oZp0NguiWFFauHbHPv18Imd1HrfdnOkhRWW45GLf4j1DFGPo00O2GOot7S6etf26BWnrLZj2SIcqyyf+csPx03huimhrcfD6fKU1rgfriwSMbyDwSZwbX160viLQAM1dIIs=" Mar 28 12:04:00 volumio go-librespot[2986]: time="2026-03-28T12:04:00+08:00" level=debug msg="completed keyexchange" Mar 28 12:04:01 volumio go-librespot[2986]: time="2026-03-28T12:04:01+08:00" level=debug msg="completed challenge" Mar 28 12:04:01 volumio go-librespot[2986]: time="2026-03-28T12:04:01+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:04:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:04:01 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:04:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:04:02 volumio volumio[1007]: error: Failed to execute browseSource: Error: request to https://bandcamp.com/ failed, reason: Client network socket disconnected before secure TLS connection was established Mar 28 12:04:03 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:04:03 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:04:03 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:04:03 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 25) Mar 28 12:04:03 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 28 12:04:03 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:04:03 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:04:04 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:04:04 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:04:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:04:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Mar 28 12:04:04 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:04:04 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:04:04 volumio go-librespot[3143]: Librespot-go daemon starting... Mar 28 12:04:04 volumio go-librespot[3143]: time="2026-03-28T12:04:04+08:00" level=info msg="generated new device id: 9ca1a5c44e328c78be8e68840fedbd454d9152c8" Mar 28 12:04:04 volumio go-librespot[3143]: time="2026-03-28T12:04:04+08:00" level=debug msg="stored credentials not found" Mar 28 12:04:06 volumio go-librespot[3143]: time="2026-03-28T12:04:06+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:04:06 volumio go-librespot[3143]: time="2026-03-28T12:04:06+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:04:06 volumio go-librespot[3143]: time="2026-03-28T12:04:06+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:04:06 volumio go-librespot[3143]: time="2026-03-28T12:04:06+08:00" level=debug msg="zeroconf server listening on port 45153" Mar 28 12:04:07 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:04:07 volumio go-librespot[3143]: time="2026-03-28T12:04:07+08:00" level=debug msg="new websocket client" Mar 28 12:04:07 volumio volumio[1007]: info: Connection to go-librespot Websocket established Mar 28 12:04:07 volumio go-librespot[3143]: time="2026-03-28T12:04:07+08:00" level=debug msg="obtained new client token: AADBKMtrZKoJwC1482nzTnxe1+ILLrxTtSAsNGe4fxJWaEagICkzRdgiGlQihOu1fIoyuxDvN54oZKuePPY0s+jbc1aoQ2ZZUYga9gmw4uT4D7DLWVpDYsgrWAhgViyVxHEcskSdR/gpPxasz/53SLKFMEZBH9XBIy5B/LOKRTetJTMGCedn/JMQ0/e/KCXMnVoCW+p0N2ycs/KJ+j5X1Rb2qNdTAHT5AQaypKwP5AWjafUbTupqLEa3Tqbn5Q==" Mar 28 12:04:08 volumio go-librespot[3143]: time="2026-03-28T12:04:08+08:00" level=debug msg="completed keyexchange" Mar 28 12:04:08 volumio volumio[1007]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 28 12:04:08 volumio go-librespot[3143]: time="2026-03-28T12:04:08+08:00" level=debug msg="completed challenge" Mar 28 12:04:08 volumio go-librespot[3143]: time="2026-03-28T12:04:08+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: TravelRestriction" Mar 28 12:04:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Mar 28 12:04:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 12:04:08 volumio volumio[1007]: info: Connection to go-librespot Websocket closed Mar 28 12:04:10 volumio volumio[1007]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%AB%98%E5%8E%9F%E7%BA%A2/f61523aa-66e2-43b5-bbc0-58b6c4d4d063.jpg' Mar 28 12:04:10 volumio volumio[1007]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 12:04:10 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 12:04:10 volumio volumio[1007]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 12:04:10 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:04:10 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:04:10 volumio volumio[1007]: info: Getting Spotify volume Mar 28 12:04:10 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:04:10 volumio volumio[1007]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Mar 28 12:04:10 volumio volumio[1007]: (node:1007) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 26) Mar 28 12:04:10 volumio volumio[1007]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 28 12:04:10 volumio volumio[1007]: info: CoreCommandRouter::volumioGetState Mar 28 12:04:10 volumio volumio[1007]: info: CorePlayQueue::getTrack 0 Mar 28 12:04:11 volumio volumio[1007]: info: Initializing connection to go-librespot Websocket Mar 28 12:04:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Mar 28 12:04:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Mar 28 12:04:11 volumio systemd[1]: Stopped go-librespot Daemon. Mar 28 12:04:11 volumio volumio[1007]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 12:04:11 volumio systemd[1]: Started go-librespot Daemon. Mar 28 12:04:11 volumio go-librespot[3177]: Librespot-go daemon starting... Mar 28 12:04:11 volumio go-librespot[3177]: time="2026-03-28T12:04:11+08:00" level=info msg="generated new device id: 02ac4a6e8a2032454a69517074a302410389c15f" Mar 28 12:04:11 volumio go-librespot[3177]: time="2026-03-28T12:04:11+08:00" level=debug msg="stored credentials not found" Mar 28 12:04:11 volumio volumio[1007]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 12:04:11 volumio volumio[1007]: Error: socket hang up Mar 28 12:04:11 volumio volumio[1007]: at connResetException (internal/errors.js:607:14) Mar 28 12:04:11 volumio volumio[1007]: at Socket.socketOnEnd (_http_client.js:493:23) Mar 28 12:04:11 volumio volumio[1007]: at Socket.emit (events.js:327:22) Mar 28 12:04:11 volumio volumio[1007]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 28 12:04:11 volumio volumio[1007]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Mar 28 12:04:11 volumio volumio[1007]: code: 'ECONNRESET' Mar 28 12:04:11 volumio volumio[1007]: } Mar 28 12:04:11 volumio volumio[1007]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 12:04:12 volumio go-librespot[3177]: time="2026-03-28T12:04:12+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 12:04:12 volumio go-librespot[3177]: time="2026-03-28T12:04:12+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 12:04:12 volumio go-librespot[3177]: time="2026-03-28T12:04:12+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 12:04:12 volumio go-librespot[3177]: time="2026-03-28T12:04:12+08:00" level=debug msg="zeroconf server listening on port 44625" Mar 28 12:04:12 volumio nmbd[806]: [2026/03/28 12:04:12.953280, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 28 12:04:12 volumio nmbd[806]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.188 for name WORKGROUP<1d>. Mar 28 12:04:12 volumio nmbd[806]: This response was from IP 192.168.0.88, reporting an IP address of 192.168.0.88. Mar 28 12:04:13 volumio go-librespot[3177]: time="2026-03-28T12:04:13+08:00" level=debug msg="obtained new client token: AAClHtCRArB8PN10vNB/8ePMDHS0cK5MiB6D0ExJU50x23vr983JxKJGLN7SuWwQmESl0Yo3Wm8agv4aPiE0Y7/wvNb4KaPv+56bgiysdzV9k/ZaI3KfuwhTWbb7B3R1xQXyzy/973Dj2t3jsZHCOVLl/1Q/DXphGZA0JP3H7GnOw/0lzVDBXQd14+sPySAk/sF2HwPkFBBlofVBVJBV+iKpjkmiIHluwL/1V/X3NWeCLAMgzRm0xf2uM0w=" Mar 28 12:04:13 volumio sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-28 12:03 Mar 28 12:04:13 volumio sudo[3197]: 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="f164e8123098cc1274b0ab48da1dc0b2e1d466e1" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 12 Mar 2026 01:30:21 PM CET" VOLUMIO_VERSION="3.913" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b937ce07521b678e27c1fac2a502bb4d"