-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Tue 2025-04-22 17:21:50 CST. -- Apr 22 17:20:01 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:01 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Apr 22 17:20:02 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:02 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:02 volumio go-librespot[5836]: Librespot-go daemon starting... Apr 22 17:20:02 volumio go-librespot[5836]: time="2025-04-22T17:20:02+08:00" level=info msg="generated new device id: da1fccf4af1d038fdaa317c3b39f1477c8498537" Apr 22 17:20:02 volumio go-librespot[5836]: time="2025-04-22T17:20:02+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:02 volumio volumio[4772]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 22 17:20:02 volumio volumio[4772]: LoungeScreenDisconnected { Apr 22 17:20:02 volumio volumio[4772]: AID: null, Apr 22 17:20:02 volumio volumio[4772]: name: 'loungeScreenDisconnected', Apr 22 17:20:02 volumio volumio[4772]: payload: {} Apr 22 17:20:02 volumio volumio[4772]: } Apr 22 17:20:02 volumio volumio[4772]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 17:20:02 volumio volumio[4772]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 17:20:02 volumio volumio[4772]: Stack trace: Apr 22 17:20:02 volumio volumio[4772]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 17:20:02 volumio volumio[4772]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 17:20:02 volumio volumio[4772]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 17:20:02 volumio volumio[4772]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 17:20:02 volumio volumio[4772]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 17:20:02 volumio volumio[4772]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 17:20:02 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:02 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:02 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 17:20:02 volumio volumio[4772]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 17:20:02 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:02 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:02 volumio volumio[4772]: (node:4772) 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: 31) Apr 22 17:20:03 volumio volumio[4772]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 22 17:20:03 volumio volumio[4772]: LoungeScreenDisconnected { Apr 22 17:20:03 volumio volumio[4772]: AID: null, Apr 22 17:20:03 volumio volumio[4772]: name: 'loungeScreenDisconnected', Apr 22 17:20:03 volumio volumio[4772]: payload: {} Apr 22 17:20:03 volumio volumio[4772]: } Apr 22 17:20:03 volumio volumio[4772]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 17:20:03 volumio volumio[4772]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 17:20:03 volumio volumio[4772]: Stack trace: Apr 22 17:20:03 volumio volumio[4772]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 17:20:03 volumio volumio[4772]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 17:20:03 volumio volumio[4772]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 17:20:03 volumio volumio[4772]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 17:20:03 volumio volumio[4772]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 17:20:03 volumio volumio[4772]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 17:20:03 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:03 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:03 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 17:20:03 volumio volumio[4772]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 17:20:03 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:03 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:03 volumio volumio[4772]: (node:4772) 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: 33) Apr 22 17:20:03 volumio volumio[4772]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Apr 22 17:20:03 volumio volumio[4772]: LoungeScreenDisconnected { Apr 22 17:20:03 volumio volumio[4772]: AID: null, Apr 22 17:20:03 volumio volumio[4772]: name: 'loungeScreenDisconnected', Apr 22 17:20:03 volumio volumio[4772]: payload: {} Apr 22 17:20:03 volumio volumio[4772]: } Apr 22 17:20:03 volumio volumio[4772]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 17:20:03 volumio volumio[4772]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 17:20:03 volumio volumio[4772]: Stack trace: Apr 22 17:20:03 volumio volumio[4772]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 17:20:03 volumio volumio[4772]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 17:20:03 volumio volumio[4772]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 17:20:03 volumio volumio[4772]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 17:20:03 volumio volumio[4772]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 17:20:03 volumio volumio[4772]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 17:20:03 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:03 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:03 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 17:20:03 volumio volumio[4772]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 17:20:03 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:03 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:03 volumio volumio[4772]: (node:4772) 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: 35) Apr 22 17:20:03 volumio volumio[4772]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Apr 22 17:20:03 volumio volumio[4772]: LoungeScreenDisconnected { Apr 22 17:20:03 volumio volumio[4772]: AID: null, Apr 22 17:20:03 volumio volumio[4772]: name: 'loungeScreenDisconnected', Apr 22 17:20:03 volumio volumio[4772]: payload: {} Apr 22 17:20:03 volumio volumio[4772]: } Apr 22 17:20:03 volumio volumio[4772]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Apr 22 17:20:03 volumio volumio[4772]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Apr 22 17:20:03 volumio volumio[4772]: Stack trace: Apr 22 17:20:03 volumio volumio[4772]: IncompleteAPIDataError: Missing data required to construct query string from bind params Apr 22 17:20:03 volumio volumio[4772]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/BindParams.js:120:19) Apr 22 17:20:03 volumio volumio[4772]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:492:103) Apr 22 17:20:03 volumio volumio[4772]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:552:71) Apr 22 17:20:03 volumio volumio[4772]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/utils/AsyncTaskQueue.js:51:36) Apr 22 17:20:03 volumio volumio[4772]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:237:71) Apr 22 17:20:03 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:03 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:03 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'removeAllListeners' of undefined Apr 22 17:20:03 volumio volumio[4772]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:372:16) Apr 22 17:20:03 volumio volumio[4772]: at runMicrotasks () Apr 22 17:20:03 volumio volumio[4772]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Apr 22 17:20:03 volumio volumio[4772]: (node:4772) 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: 37) Apr 22 17:20:03 volumio go-librespot[5836]: time="2025-04-22T17:20:03+08:00" level=debug msg="obtained new client token: AAByxlITtKCYp0vWnDNIxmFcE/yEui9+27JP9nM4LmJclk2ny5MWBZtwfVn9/CfnowrH0kl1AGOD85LfYOnq29f3oUlgF2FqbThqF4qaiCIn9Qw3vxUlVxlhTFIPGE9SX0MvcohDl873duayQjpuf3gVG/pnlNYiUgoR6IZ+yTK1ePhpzLrVbaa3fa48jru8e6aT/pH4xcYe/h3I8wqd5kx+XPgMXJaxrqUQs6NiSyqHmZzbbXeG196n7MJuKg==" Apr 22 17:20:03 volumio go-librespot[5836]: time="2025-04-22T17:20:03+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]" Apr 22 17:20:03 volumio go-librespot[5836]: time="2025-04-22T17:20:03+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:04 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:04 volumio go-librespot[5836]: time="2025-04-22T17:20:04+08:00" level=debug msg="new websocket client" Apr 22 17:20:04 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:20:04 volumio go-librespot[5836]: time="2025-04-22T17:20:04+08:00" level=debug msg="completed challenge" Apr 22 17:20:04 volumio go-librespot[5836]: time="2025-04-22T17:20:04+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" Apr 22 17:20:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:04 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:20:07 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:20:07 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:07 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:20:07 volumio volumio[4772]: (node:4772) 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: 38) Apr 22 17:20:07 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 22 17:20:07 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:20:07 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:20:07 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:07 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Apr 22 17:20:07 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:07 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:07 volumio go-librespot[5844]: Librespot-go daemon starting... Apr 22 17:20:07 volumio go-librespot[5844]: time="2025-04-22T17:20:07+08:00" level=info msg="generated new device id: 3dd71813dac34719b5dc2cce54080931d18dd897" Apr 22 17:20:07 volumio go-librespot[5844]: time="2025-04-22T17:20:07+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:07 volumio go-librespot[5844]: time="2025-04-22T17:20:07+08:00" level=debug msg="obtained new client token: AAC3Txu/abuHCqfkyykwhIi7F5EIxhTsfesjBWtb3GcSvvdOn0va5mKLMZ123iOPYz0X6+Mdk9kgOvnCS10xVain19S0aKRn0MaaRL3S9eOrwUVE2ciKxHe+cAc0108OaL1PI8UGYE7Sh9v42oQL4h6YNhWM0ZEHRx+J/KC3Y6AnBXU0nqZsAxCPUSKQF/OI/GhD7MSWZLarVYSEBA4EG2rESvkzcbikHwSFYWROWJXRuaymMuI0s1SOFmmXQVHI" Apr 22 17:20:08 volumio go-librespot[5844]: time="2025-04-22T17:20:08+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]" Apr 22 17:20:08 volumio go-librespot[5844]: time="2025-04-22T17:20:08+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:08 volumio go-librespot[5844]: time="2025-04-22T17:20:08+08:00" level=debug msg="completed challenge" Apr 22 17:20:08 volumio go-librespot[5844]: time="2025-04-22T17:20: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" Apr 22 17:20:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:10 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:10 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Apr 22 17:20:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:12 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:12 volumio go-librespot[5852]: Librespot-go daemon starting... Apr 22 17:20:12 volumio go-librespot[5852]: time="2025-04-22T17:20:12+08:00" level=info msg="generated new device id: 8b1def8790572d958c6765df7fc897c57663f25d" Apr 22 17:20:12 volumio go-librespot[5852]: time="2025-04-22T17:20:12+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:12 volumio go-librespot[5852]: time="2025-04-22T17:20:12+08:00" level=debug msg="obtained new client token: AADRzrZphrQgTMtuy2MMACLG/J2TnI/umcAFUsPvEwKAKyX3lx9py8h+CT62lM2cy9Bz8lq4gLnNtzdVcDnmiejgaHx8McEuaeZq/mLdFQWvaPtBgyj9OmGnQGiT2JdC+aYPpubTdPnmvFUlrMHi5vsyoItxOrMXKOQUGJH1G2phD2KrxGo/cUFxDE+xfAXuBpElqRe85yZsyBe82NcLBRkEw8a5Yu8//azsXQY55Zpk2ENToKFrDdtjJ/oy/8Fs" Apr 22 17:20:12 volumio go-librespot[5852]: time="2025-04-22T17:20: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 22 17:20:12 volumio go-librespot[5852]: time="2025-04-22T17:20:12+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:13 volumio go-librespot[5852]: time="2025-04-22T17:20:13+08:00" level=debug msg="completed challenge" Apr 22 17:20:13 volumio go-librespot[5852]: time="2025-04-22T17:20:13+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" Apr 22 17:20:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:13 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:13 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:16 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:16 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Apr 22 17:20:16 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:16 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:16 volumio go-librespot[5860]: Librespot-go daemon starting... Apr 22 17:20:16 volumio go-librespot[5860]: time="2025-04-22T17:20:16+08:00" level=info msg="generated new device id: d0c76a12e4b20aa03d956b01e70c78fa3ee22a4a" Apr 22 17:20:16 volumio go-librespot[5860]: time="2025-04-22T17:20:16+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:16 volumio go-librespot[5860]: time="2025-04-22T17:20:16+08:00" level=debug msg="obtained new client token: AABqK8gChg559tobPP0tAVqCf2livPIVgxm0c9ocAsO6zdCRFU6wqsIWoe1u46ulUO9ZEQNogAS3xiXnCybT5X/5eBVeXTX5mfNSJAdx+FoH2itmFa+Kr0xutBHx5Kd6raQ7eaZ2W804yfZ378aG7jR+hXChkLi9Gn9nTMJ35uxH1yZ86R0SBegVKHRJIXkPUVDCmR2IGCALxc7vE2c3zN/TbmCC/RLXEMZUg5XceDDQQ5GLssUGtLXzcz6rkvWs" Apr 22 17:20:17 volumio go-librespot[5860]: time="2025-04-22T17:20:17+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]" Apr 22 17:20:17 volumio go-librespot[5860]: time="2025-04-22T17:20:17+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:18 volumio go-librespot[5860]: time="2025-04-22T17:20:18+08:00" level=debug msg="completed challenge" Apr 22 17:20:18 volumio go-librespot[5860]: time="2025-04-22T17:20:18+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" Apr 22 17:20:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:19 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:19 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Apr 22 17:20:21 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:21 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:21 volumio go-librespot[5869]: Librespot-go daemon starting... Apr 22 17:20:21 volumio go-librespot[5869]: time="2025-04-22T17:20:21+08:00" level=info msg="generated new device id: fcf52aa07bfad10c69de737dd288806f21939062" Apr 22 17:20:21 volumio go-librespot[5869]: time="2025-04-22T17:20:21+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:21 volumio go-librespot[5869]: time="2025-04-22T17:20:21+08:00" level=debug msg="obtained new client token: AADJ9klA3HpfpcYHIzPa9eOl5jD4+lr+18DfQIA6yVN9z8xkUXXpFgFCcTKFypD7ULsDGvtQNkuQDnqgvZbf2ERkMAxXk+TCArFABPQ3PcCoPoD+B0gBK1mCLnQLuea8yFhWc/4J2RguVXl/3H+PeAyJwUnHwyHDZEuJkJYLI7FwM/xZCRDwCdk9+dJcviTxKu7Oym5/btslgHuQshh8qVf2eFKD2uwD8JnX3/e04VKx1ckFFjxN1BJFPjtK9Etc" Apr 22 17:20:22 volumio go-librespot[5869]: time="2025-04-22T17:20:22+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]" Apr 22 17:20:22 volumio go-librespot[5869]: time="2025-04-22T17:20:22+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:22 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:22 volumio go-librespot[5869]: time="2025-04-22T17:20:22+08:00" level=debug msg="new websocket client" Apr 22 17:20:22 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:20:23 volumio go-librespot[5869]: time="2025-04-22T17:20:23+08:00" level=debug msg="completed challenge" Apr 22 17:20:23 volumio go-librespot[5869]: time="2025-04-22T17:20:23+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" Apr 22 17:20:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:23 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:20:25 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:20:25 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:25 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:20:25 volumio volumio[4772]: (node:4772) 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: 39) Apr 22 17:20:25 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 22 17:20:25 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:20:25 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:20:26 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:26 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Apr 22 17:20:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:26 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:26 volumio go-librespot[5879]: Librespot-go daemon starting... Apr 22 17:20:26 volumio go-librespot[5879]: time="2025-04-22T17:20:26+08:00" level=info msg="generated new device id: 0a2ff2243035a25bf215dc682e8b90ca5a0487b6" Apr 22 17:20:26 volumio go-librespot[5879]: time="2025-04-22T17:20:26+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:26 volumio go-librespot[5879]: time="2025-04-22T17:20:26+08:00" level=debug msg="obtained new client token: AADsOym4XWVrFr5FeCWykaUj2x320rz0v7W9gOLRPuztFgPpnnQhsNaAIFyqDyLfsArQaFkmsgynn4ZHRge2WPCBdvnTnq2fDgMQNO5PIVFB4x30tvllljf6feroY7K7PzT1O8KqPi8qwaT9TtnGSs/AqaUB5aPv7EteiA1amxwR2cH9kq5FKSxIWgENkBduOyeZBMZzPoxZgzCSVf6CjmLN/kHrUuojjJgJHtm9xFlPKgCyBUN8s745Ky+FDCwc" Apr 22 17:20:26 volumio go-librespot[5879]: time="2025-04-22T17:20:26+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]" Apr 22 17:20:27 volumio go-librespot[5879]: time="2025-04-22T17:20:27+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:27 volumio go-librespot[5879]: time="2025-04-22T17:20:27+08:00" level=debug msg="completed challenge" Apr 22 17:20:27 volumio go-librespot[5879]: time="2025-04-22T17:20: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" Apr 22 17:20:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:29 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:29 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Apr 22 17:20:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:30 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:30 volumio go-librespot[5887]: Librespot-go daemon starting... Apr 22 17:20:30 volumio go-librespot[5887]: time="2025-04-22T17:20:30+08:00" level=info msg="generated new device id: da471e3ebcd0494ce3d1b305480b86145275f1b7" Apr 22 17:20:30 volumio go-librespot[5887]: time="2025-04-22T17:20:30+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:31 volumio go-librespot[5887]: time="2025-04-22T17:20:31+08:00" level=debug msg="obtained new client token: AACYp8vchuh2BEhSHbdmnbczaGVI8j9MwikVEGF9T3Dfue51LL7Z/v6XK/foFU1AKiHwYcXvWHeth2JxLUnhs3JqWCJMLxva4vx+78sooT4hmK0Rz1m/wIYDhkVAvbxQlvYrPROOehHIPvO1iY/Gh9A4KAiArL064E4dM3DM5f1d9uqplvomgd0WQBFveQM+tScceETv8F2hZelDoNUE3fBQFbinQyHORQtpbeGwS0PHfHZ21ucUM5WyxcR5Ug==" Apr 22 17:20:31 volumio go-librespot[5887]: time="2025-04-22T17:20: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-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 22 17:20:31 volumio go-librespot[5887]: time="2025-04-22T17:20:31+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:32 volumio go-librespot[5887]: time="2025-04-22T17:20:32+08:00" level=debug msg="completed challenge" Apr 22 17:20:32 volumio go-librespot[5887]: time="2025-04-22T17:20:32+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" Apr 22 17:20:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:32 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:32 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:35 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:35 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Apr 22 17:20:35 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:35 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:35 volumio go-librespot[5895]: Librespot-go daemon starting... Apr 22 17:20:35 volumio go-librespot[5895]: time="2025-04-22T17:20:35+08:00" level=info msg="generated new device id: 58f5828c3f0b92d35d15dc75a98fc8bd7d6725f6" Apr 22 17:20:35 volumio go-librespot[5895]: time="2025-04-22T17:20:35+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:35 volumio go-librespot[5895]: time="2025-04-22T17:20:35+08:00" level=debug msg="obtained new client token: AABHtNaLSaS8TFm5Yh3RO5kiAMQFC7EhImMPXuXrAQ5TVA47+vxqb+0rF3um94UaMyI7BAm/Vg6eHAXXSQXxZdGfzDevs5agmMS9x508Z+Mc8DRA/wVlukO+ut/ckn0xx2bTdT8Hr875oT6kQxPhkzb2HTkOAGZeM4b1jcFvVwNZvElPR/VhCgBcfoRGz13/dle5XBruzYsnZkC5IaOrggIFDwHEbVOcebZy9wok97pAJJn1LvpGb2h6l8QOM+xC" Apr 22 17:20:35 volumio go-librespot[5895]: time="2025-04-22T17:20:35+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]" Apr 22 17:20:36 volumio go-librespot[5895]: time="2025-04-22T17:20:36+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:36 volumio go-librespot[5895]: time="2025-04-22T17:20:36+08:00" level=debug msg="completed challenge" Apr 22 17:20:36 volumio go-librespot[5895]: time="2025-04-22T17:20:36+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" Apr 22 17:20:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:38 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:38 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Apr 22 17:20:39 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:39 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:39 volumio go-librespot[5904]: Librespot-go daemon starting... Apr 22 17:20:39 volumio go-librespot[5904]: time="2025-04-22T17:20:39+08:00" level=info msg="generated new device id: 70f3726dd000873788236d7d6117f430ccfdae8b" Apr 22 17:20:39 volumio go-librespot[5904]: time="2025-04-22T17:20:39+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:40 volumio go-librespot[5904]: time="2025-04-22T17:20:40+08:00" level=debug msg="obtained new client token: AAD0P4kWDejCw1VMxs15Uln+2O9Uch/3mz5+b9EC6kRwBMzDFIOA7muVYvMQP6n80gV2CyhwfYSBLBCRK7UhEsdEdll2MnmLQFpHwZR+cB8CiYLFNO1u+bozlDPJFWB0bp7pWFKreVBX+CVr3n8GuHqpJZsjB4QYZsWS5eaOSrlDIZUJiuYpqfzvRwhpMg/o72IKiKkhDGFIn5xm/w+fVN0qT5xR4y9ZQLMy5iVMckSZ+Bi4wx7MKdnHyykkc9xg" Apr 22 17:20:40 volumio go-librespot[5904]: time="2025-04-22T17:20:40+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]" Apr 22 17:20:40 volumio go-librespot[5904]: time="2025-04-22T17:20:40+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:41 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:41 volumio go-librespot[5904]: time="2025-04-22T17:20:41+08:00" level=debug msg="new websocket client" Apr 22 17:20:41 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:20:41 volumio go-librespot[5904]: time="2025-04-22T17:20:41+08:00" level=debug msg="completed challenge" Apr 22 17:20:41 volumio go-librespot[5904]: time="2025-04-22T17:20:41+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" Apr 22 17:20:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:41 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:20:44 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:20:44 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:44 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:20:44 volumio volumio[4772]: (node:4772) 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: 40) Apr 22 17:20:44 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 22 17:20:44 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:20:44 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:20:44 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:44 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Apr 22 17:20:44 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:44 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:44 volumio go-librespot[5913]: Librespot-go daemon starting... Apr 22 17:20:44 volumio go-librespot[5913]: time="2025-04-22T17:20:44+08:00" level=info msg="generated new device id: f4ad180390570fde02f658506d2c109ca783c5a2" Apr 22 17:20:44 volumio go-librespot[5913]: time="2025-04-22T17:20:44+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:44 volumio go-librespot[5913]: time="2025-04-22T17:20:44+08:00" level=debug msg="obtained new client token: AABfagrEL85MqepS//hRYwZb6WEVOAHc3Y4WVFr7RomfpSj2X4bRf2F9ZDTEE4YToP0+yq45NICME/DlA+aJsAqZOLgVCQIZLRPKk86xn1p3+Clzt6Wb2w1qt63oYBuJnRRC48aeR7IqPoLKPnZ6Vz79Nn+JjKHtmBYRfQMy4JXknzdXOCLtEk92R08rtIG/wN5UqlzOirmhX9ArBfhTTJSrM4fth1KjE2RwwaWsZeV8ekkMVFzOFaMsKao/FHT2" Apr 22 17:20:45 volumio go-librespot[5913]: time="2025-04-22T17:20:45+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]" Apr 22 17:20:45 volumio go-librespot[5913]: time="2025-04-22T17:20:45+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:46 volumio go-librespot[5913]: time="2025-04-22T17:20:46+08:00" level=debug msg="completed challenge" Apr 22 17:20:46 volumio go-librespot[5913]: time="2025-04-22T17:20:46+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" Apr 22 17:20:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:47 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:47 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Apr 22 17:20:49 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:49 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:49 volumio go-librespot[5921]: Librespot-go daemon starting... Apr 22 17:20:49 volumio go-librespot[5921]: time="2025-04-22T17:20:49+08:00" level=info msg="generated new device id: c397d6d1eb7173a151b1db95f78efc833a277d52" Apr 22 17:20:49 volumio go-librespot[5921]: time="2025-04-22T17:20:49+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:49 volumio go-librespot[5921]: time="2025-04-22T17:20:49+08:00" level=debug msg="obtained new client token: AABh5JO1dGStNjBbe4iqP9dbKUtwx5+zEs6KYJ7EM2CdKz5EXj6co4zNbmHyZ43ltdaWdSOlqWG5SQIRXAiYT1tkVHTg+MQ5U2vWTyr87+EKYyrkKtvEx4Lg/E2PwOZ+UDEZB6fK/H9qhDodPwEBzgsYbLfKMDrmTsfcJkZjglCYu+ZEruEqL1ZEAsSbYUR3f8nEDIuV5smq3GDWSHaCOrBMybUcOjDuG1hhtG2fjX4BYFjUCDn1E7OqOI30uB19" Apr 22 17:20:49 volumio go-librespot[5921]: time="2025-04-22T17:20:49+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]" Apr 22 17:20:50 volumio go-librespot[5921]: time="2025-04-22T17:20:50+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:50 volumio go-librespot[5921]: time="2025-04-22T17:20:50+08:00" level=debug msg="completed challenge" Apr 22 17:20:50 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:50 volumio go-librespot[5921]: time="2025-04-22T17:20:50+08:00" level=debug msg="new websocket client" Apr 22 17:20:50 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:20:50 volumio go-librespot[5921]: time="2025-04-22T17:20:50+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" Apr 22 17:20:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:50 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:20:53 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:20:53 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:53 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:20:53 volumio volumio[4772]: (node:4772) 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: 41) Apr 22 17:20:53 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 22 17:20:53 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:20:53 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:20:53 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:53 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Apr 22 17:20:53 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:53 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:53 volumio go-librespot[5930]: Librespot-go daemon starting... Apr 22 17:20:53 volumio go-librespot[5930]: time="2025-04-22T17:20:53+08:00" level=info msg="generated new device id: 195f3370476904085dde86e0239447658a10d05c" Apr 22 17:20:53 volumio go-librespot[5930]: time="2025-04-22T17:20:53+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:54 volumio go-librespot[5930]: time="2025-04-22T17:20:54+08:00" level=debug msg="obtained new client token: AABVNLCJdma5eeH3D+r/TYrKPbm2SFrOYk+qSsZ5mzCh5R9+dlYDs68NqFooUmEw8/De9chk4iN3NJvQs7pnPZiChjTeepJswMVlwfrzsR1YZWQDEC2x7G46Tac39fiJPDlmrLc7V+DcknFIJUI9l42Nt1GsdGobS3O9EOi/kLq+llX5fbpbhQYbBPYTJFIgHmm5wSLNSCqFNFFAmYvIlBkdJfuNwimAtEUqFBmePM3R9GwNrdMb+U+Nezb0Zw==" Apr 22 17:20:54 volumio go-librespot[5930]: time="2025-04-22T17:20:54+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]" Apr 22 17:20:54 volumio go-librespot[5930]: time="2025-04-22T17:20:54+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:55 volumio go-librespot[5930]: time="2025-04-22T17:20:55+08:00" level=debug msg="completed challenge" Apr 22 17:20:55 volumio go-librespot[5930]: time="2025-04-22T17:20:55+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" Apr 22 17:20:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:20:55 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:20:56 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:56 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:20:58 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:20:58 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Apr 22 17:20:58 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:20:58 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:20:58 volumio go-librespot[5939]: Librespot-go daemon starting... Apr 22 17:20:58 volumio go-librespot[5939]: time="2025-04-22T17:20:58+08:00" level=info msg="generated new device id: eb1e43cd467f3f78f12df252453072fa0b0b78f2" Apr 22 17:20:58 volumio go-librespot[5939]: time="2025-04-22T17:20:58+08:00" level=debug msg="stored credentials not found" Apr 22 17:20:58 volumio volumio[4772]: info: peppy_screensaver: Start PeppyMeter Apr 22 17:20:59 volumio go-librespot[5939]: time="2025-04-22T17:20:59+08:00" level=debug msg="obtained new client token: AADuzVLilcyI00fq2zd6/Qf1hCxj9RieXSBwKIL/CpvxH0O+dwhA/HdZxyNdVJWvsD6ekVfB2U6+dLKY9bMCGjZ/i632DutkN4nE/UB8OVofNJD1BPhQiIqov1dosKsdbXqBOAa+iwfy8RXN7k9/A18CDqESSDeXwSep5fptKi9fAbc9z0rqKW/iijFHOMueozevnkuewlsqItXpiEj3qWE1EYqozFnc9qwzMYSBij/6BBBhVb4OEcHtKpK3uueJ" Apr 22 17:20:59 volumio go-librespot[5939]: time="2025-04-22T17:20:59+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]" Apr 22 17:20:59 volumio go-librespot[5939]: time="2025-04-22T17:20:59+08:00" level=debug msg="completed keyexchange" Apr 22 17:20:59 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:20:59 volumio go-librespot[5939]: time="2025-04-22T17:20:59+08:00" level=debug msg="new websocket client" Apr 22 17:20:59 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:21:00 volumio go-librespot[5939]: time="2025-04-22T17:21:00+08:00" level=debug msg="completed challenge" Apr 22 17:21:00 volumio go-librespot[5939]: time="2025-04-22T17:21:00+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" Apr 22 17:21:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:00 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:21:02 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:21:02 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:02 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:21:02 volumio volumio[4772]: (node:4772) 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: 42) Apr 22 17:21:02 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 22 17:21:02 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:21:02 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:21:03 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:03 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Apr 22 17:21:03 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:03 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:03 volumio go-librespot[5989]: Librespot-go daemon starting... Apr 22 17:21:03 volumio go-librespot[5989]: time="2025-04-22T17:21:03+08:00" level=info msg="generated new device id: 9d7068f189c5dab92d4bc588ae295d4ad9f7cc7e" Apr 22 17:21:03 volumio go-librespot[5989]: time="2025-04-22T17:21:03+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:03 volumio go-librespot[5989]: time="2025-04-22T17:21:03+08:00" level=debug msg="obtained new client token: AAC9eFqVEbnc1dYlU1yiItOGMMkK1s+FanRxVyL97ioMvVua3L5qDHiIl/CkWvUe8F/6zZkIWMh6dKG367fl8HI1CfGAOxMe4Qm+LqdqEPvrZ+bkSaLOfvijeAgWEyIk5WE1yb+kVP/2a+AnUW0AmDfkEacaqp1aLAvUkCDt7VcXX7uYXCwM5L9Z69Yj+oW9q7t9FxNYAV2xptF48P7JiIAVgWLMFdqAeWlFqeMTVSxP0omgQDYRGKL4uanvEanp" Apr 22 17:21:03 volumio go-librespot[5989]: time="2025-04-22T17:21:03+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]" Apr 22 17:21:04 volumio go-librespot[5989]: time="2025-04-22T17:21:04+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:04 volumio go-librespot[5989]: time="2025-04-22T17:21:04+08:00" level=debug msg="completed challenge" Apr 22 17:21:04 volumio go-librespot[5989]: time="2025-04-22T17:21:04+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" Apr 22 17:21:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:06 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:06 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:06 volumio volumio[4772]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 22 17:21:06 volumio volumio[4772]: info: touch_display: Setting screensaver timeout to 15 seconds. Apr 22 17:21:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Apr 22 17:21:07 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:07 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:07 volumio go-librespot[6025]: Librespot-go daemon starting... Apr 22 17:21:07 volumio go-librespot[6025]: time="2025-04-22T17:21:07+08:00" level=info msg="generated new device id: f417735b2d983a14f6bbcdd95624749710c75df6" Apr 22 17:21:07 volumio go-librespot[6025]: time="2025-04-22T17:21:07+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:08 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 8 Apr 22 17:21:08 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:21:08 volumio volumio[4772]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ROCK%20ANTENNE/3596e0bf-ebc4-4237-9cfe-094dfcfa453e.jpg' Apr 22 17:21:08 volumio go-librespot[6025]: time="2025-04-22T17:21:08+08:00" level=debug msg="obtained new client token: AAATEVT5BIi4vDcJhv+ZFquf8bWZmW451aQ8CEe46ARFKM5L50XBAsw+2PH/SCNsKK6Wb1joOZ5n/mcozDU7ocILq0RiPTm0/VMCqIcuIBRzrk2CzYnVdZR7L2L39uPrswXDg8kHxnml8iz/IWM+IZo5JPrSEtQk5r/oOJxnsaeEFkQXcF3B0i0WHGQx5K2acUrM9RWMQq1LOTUi1MZ2J1fFt6rFgizkzyxgQRCIMOYnsZjEjXWkcJ2Xsrd+Jl/u" Apr 22 17:21:08 volumio go-librespot[6025]: time="2025-04-22T17:21:08+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]" Apr 22 17:21:08 volumio go-librespot[6025]: time="2025-04-22T17:21:08+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:09 volumio go-librespot[6025]: time="2025-04-22T17:21:09+08:00" level=debug msg="completed challenge" Apr 22 17:21:09 volumio go-librespot[6025]: time="2025-04-22T17:21:09+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" Apr 22 17:21:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:09 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:09 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:12 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:12 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Apr 22 17:21:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:12 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:12 volumio go-librespot[6039]: Librespot-go daemon starting... Apr 22 17:21:12 volumio go-librespot[6039]: time="2025-04-22T17:21:12+08:00" level=info msg="generated new device id: e2e13ab729189873b774988a5f1afad2af84e45e" Apr 22 17:21:12 volumio go-librespot[6039]: time="2025-04-22T17:21:12+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:12 volumio go-librespot[6039]: time="2025-04-22T17:21:12+08:00" level=debug msg="obtained new client token: AACCkzC+oZAjXBbz7TtekD8+v5vE94piYHVj5KIMmSzGWAy1ZrDYDlLVsKklORSMi0s4oetWL6sSnA4FGyfLuaR/EjbCBK2EoWWagqIsyT1TaETSj6GstaQyRQa76iruJx5FBw5xmYl+9NUI+F69Hxbr/3NE584vr6qyLsqeujjFbCoteLfG0/HU8H4Pc332DGsVtUt/TxcQp9clI9CLkQpjAWdk5MU3HVLZZhjRWjrabbfrN4LSFmyo5YMi2SJA" Apr 22 17:21:12 volumio go-librespot[6039]: time="2025-04-22T17:21: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-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 22 17:21:13 volumio go-librespot[6039]: time="2025-04-22T17:21:13+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:13 volumio go-librespot[6039]: time="2025-04-22T17:21:13+08:00" level=debug msg="completed challenge" Apr 22 17:21:13 volumio go-librespot[6039]: time="2025-04-22T17:21:13+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" Apr 22 17:21:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:15 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:15 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Apr 22 17:21:16 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:16 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:16 volumio go-librespot[6047]: Librespot-go daemon starting... Apr 22 17:21:16 volumio go-librespot[6047]: time="2025-04-22T17:21:16+08:00" level=info msg="generated new device id: 2e72355ce5fa6bf1484d5c3d4b119689de99816a" Apr 22 17:21:16 volumio go-librespot[6047]: time="2025-04-22T17:21:16+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:17 volumio go-librespot[6047]: time="2025-04-22T17:21:17+08:00" level=debug msg="obtained new client token: AADn2tuSPG6v0Lhe4vUzwOA84gyvu7KhCn0iM3I5bjCfcCEr1KVB8t25wAIBAkaL+dYbffGfOtgLgEVzFqvS1QChKeT5oC3qHr/QV1lVvGWbMO/eWXNK7F/sztfeFKu+Szf7DpzMQkpsX2s77uCDPpw59JuZ22J8D5BSU6XUZ1061VPY7PWt40Kav+j7xxZUGkRK1eItxz7g4RozliUKVSkn9KThE0xYrbr5CxdeVknmBZ7IjpPvb21fbu62mg==" Apr 22 17:21:17 volumio go-librespot[6047]: time="2025-04-22T17:21:17+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]" Apr 22 17:21:17 volumio go-librespot[6047]: time="2025-04-22T17:21:17+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:18 volumio go-librespot[6047]: time="2025-04-22T17:21:18+08:00" level=debug msg="completed challenge" Apr 22 17:21:18 volumio go-librespot[6047]: time="2025-04-22T17:21:18+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" Apr 22 17:21:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:18 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:18 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:21 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Apr 22 17:21:21 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:21 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:21 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:21 volumio go-librespot[6056]: Librespot-go daemon starting... Apr 22 17:21:21 volumio go-librespot[6056]: time="2025-04-22T17:21:21+08:00" level=info msg="generated new device id: 40df7851d2b80b6ada557238e792edb0ac883d9f" Apr 22 17:21:21 volumio go-librespot[6056]: time="2025-04-22T17:21:21+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:21 volumio go-librespot[6056]: time="2025-04-22T17:21:21+08:00" level=debug msg="obtained new client token: AAAKX7kEfIocIr7I2wlHdyMkEizw6GrZpPrdR7ApHfOQCFM9xsjerrjL9b9y5L9tQeIAOs8XPudExNYPT8x5y7baKyC1AVc6/E384AsSRg91nksLVJktAT8EJupBU4vhtxAw0FhfbrYKHlYxZRcnfbSMGAnBMa4rhCrSpq9AGRvZFli0foi7G5X8b0uwj1Fmqu0sWEI6Saso60zqRkfjh3ZUxWgxwVvuEE2ysVFyZBQmDzsNvPllqK63OY3gD9Lw" Apr 22 17:21:21 volumio go-librespot[6056]: time="2025-04-22T17:21:21+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]" Apr 22 17:21:22 volumio go-librespot[6056]: time="2025-04-22T17:21:22+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:22 volumio go-librespot[6056]: time="2025-04-22T17:21:22+08:00" level=debug msg="completed challenge" Apr 22 17:21:23 volumio go-librespot[6056]: time="2025-04-22T17:21:23+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" Apr 22 17:21:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:24 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:24 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Apr 22 17:21:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:26 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:26 volumio go-librespot[6064]: Librespot-go daemon starting... Apr 22 17:21:26 volumio go-librespot[6064]: time="2025-04-22T17:21:26+08:00" level=info msg="generated new device id: 7652a9f38a917ce6d81df3d478e0777274594e5d" Apr 22 17:21:26 volumio go-librespot[6064]: time="2025-04-22T17:21:26+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:26 volumio go-librespot[6064]: time="2025-04-22T17:21:26+08:00" level=debug msg="obtained new client token: AAAZxWaGBb8f+b/EdaBlrtGj8rcEzmVxxBpJpRAvLYBJy/xEQjLi6dYFKJJWkZjWoqUO1si0L8WNQcRhDjBqVeVt4VSh8F6S2HhRvCNGote3Dp6c4IAlkgzKvnQ9fZ0rEsklQ4Yv4B1oDr3dETf7l6SeBJLUI8mn1PgvGbovt9+jZ8RJ0KMK8R2hhB7EqaGXRmbcxBtOu40H7uM32bWAtaP8H0Y72TwxMe0qFgjPwMrT654s1pt8SaLMSk0O8SWA" Apr 22 17:21:26 volumio go-librespot[6064]: time="2025-04-22T17:21:26+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]" Apr 22 17:21:27 volumio go-librespot[6064]: time="2025-04-22T17:21:27+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:27 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:27 volumio go-librespot[6064]: time="2025-04-22T17:21:27+08:00" level=debug msg="new websocket client" Apr 22 17:21:27 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:21:27 volumio go-librespot[6064]: time="2025-04-22T17:21:27+08:00" level=debug msg="completed challenge" Apr 22 17:21:27 volumio go-librespot[6064]: time="2025-04-22T17:21: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" Apr 22 17:21:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:27 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:21:29 volumio volumio[4772]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Apr 22 17:21:29 volumio volumio[4772]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:106:19) Apr 22 17:21:29 volumio volumio[4772]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:113:37) Apr 22 17:21:29 volumio volumio[4772]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Apr 22 17:21:29 volumio volumio[4772]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 22 17:21:29 volumio volumio[4772]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:144:13) Apr 22 17:21:29 volumio volumio[4772]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:114:3) Apr 22 17:21:29 volumio volumio[4772]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 22 17:21:29 volumio volumio[4772]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Apr 22 17:21:29 volumio volumio[4772]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Apr 22 17:21:29 volumio volumio[4772]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Apr 22 17:21:29 volumio volumio[4772]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Apr 22 17:21:29 volumio volumio[4772]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Apr 22 17:21:29 volumio volumio[4772]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Apr 22 17:21:29 volumio volumio[4772]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Apr 22 17:21:29 volumio volumio[4772]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 22 17:21:30 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:21:30 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:30 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:21:30 volumio volumio[4772]: (node:4772) 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: 44) Apr 22 17:21:30 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 22 17:21:30 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:21:30 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:21:30 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:30 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Apr 22 17:21:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:30 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:30 volumio go-librespot[6072]: Librespot-go daemon starting... Apr 22 17:21:30 volumio go-librespot[6072]: time="2025-04-22T17:21:30+08:00" level=info msg="generated new device id: a2c6f119da0f90a2f2be166f9c7f387e5d0c5f0a" Apr 22 17:21:30 volumio go-librespot[6072]: time="2025-04-22T17:21:30+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:31 volumio go-librespot[6072]: time="2025-04-22T17:21:31+08:00" level=debug msg="obtained new client token: AACguLfLLnZCe5Lvlp1IV7eC484+sMYsiRDCHVbFl/vkXyYzBf9tidPRx832yx8LFYYlY/PPs5y/SVNKnTSEhVeZywUVfP/XWl32z1Id4Bvc0AzNfl6mxA9EATPam4HKRYahm1Zd4XbMd2fyUPLAF7Liw2D7B/ZuqTtWkdO7zNZWdlmIINPtj5XQU8/cdlQfbFCzJJZpcu+OS/b9twZGRnm/HdWUVtdbsz8n1YtKGC+yPKi+gOu3aMHepVovBQ==" Apr 22 17:21:31 volumio go-librespot[6072]: time="2025-04-22T17:21: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]" Apr 22 17:21:31 volumio go-librespot[6072]: time="2025-04-22T17:21:31+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:32 volumio go-librespot[6072]: time="2025-04-22T17:21:32+08:00" level=debug msg="completed challenge" Apr 22 17:21:32 volumio go-librespot[6072]: time="2025-04-22T17:21:32+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" Apr 22 17:21:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:33 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:33 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Apr 22 17:21:35 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:35 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:35 volumio go-librespot[6080]: Librespot-go daemon starting... Apr 22 17:21:35 volumio go-librespot[6080]: time="2025-04-22T17:21:35+08:00" level=info msg="generated new device id: 3e51088529d61b049300202048d3921de749b2be" Apr 22 17:21:35 volumio go-librespot[6080]: time="2025-04-22T17:21:35+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:35 volumio go-librespot[6080]: time="2025-04-22T17:21:35+08:00" level=debug msg="obtained new client token: AADbmM8jI5XNASVxWKkhrj1xjseqZjKFBQ+BpClii+mTrZ6ld43+SnlCJkf821c3ZB6AzwOWv1uLkpSXU8OslNbmY1LK8pcdCjn919t2nttaroUMhQJv8uH380WU6ANidJegF9w0CoWVok/B0CRJxEVP8Yx527lyeb0vFa87pd4dxVFnIgFR0OYlVPasWGrTLva+L6RfzyQ49XZLQTXZURAEvbpJr9XcWpo5v/db07pkRtxSTMnciXFZGR063vkb" Apr 22 17:21:36 volumio go-librespot[6080]: time="2025-04-22T17:21:36+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]" Apr 22 17:21:36 volumio go-librespot[6080]: time="2025-04-22T17:21:36+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:36 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:36 volumio go-librespot[6080]: time="2025-04-22T17:21:36+08:00" level=debug msg="new websocket client" Apr 22 17:21:36 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:21:36 volumio go-librespot[6080]: time="2025-04-22T17:21:36+08:00" level=debug msg="completed challenge" Apr 22 17:21:36 volumio go-librespot[6080]: time="2025-04-22T17:21:36+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" Apr 22 17:21:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:36 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:21:39 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:21:39 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:39 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:21:39 volumio volumio[4772]: (node:4772) 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: 45) Apr 22 17:21:39 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 22 17:21:39 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:21:39 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:21:39 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:39 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:40 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:40 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Apr 22 17:21:40 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:40 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:40 volumio go-librespot[6089]: Librespot-go daemon starting... Apr 22 17:21:40 volumio go-librespot[6089]: time="2025-04-22T17:21:40+08:00" level=info msg="generated new device id: 730ef8c90962cd0d009fd95fab1fa75a61267b66" Apr 22 17:21:40 volumio go-librespot[6089]: time="2025-04-22T17:21:40+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:40 volumio go-librespot[6089]: time="2025-04-22T17:21:40+08:00" level=debug msg="obtained new client token: AAA9Ba3hTULAVxbr7zGySZwn6ElwjEM102UKkMSjlKKf3jVDzUKCw9nuBW3nVlLnyLhKHSOajqbg4TsBE2JchIuBi1iGoFrIyc1fQ8g6xViqle5YUbqyegIyKgfYo1xlacllj1UmkwI4xqQ63RA/7M86ErtvXBwrBcCYX+mYXHK2aemaSl4g7REP0kNY5Cr+ZTy6A1n9odBcFEGG/cFoH4N3uOKWmVKjq+tEuJnqnLtAj7tPrGAbKYbf+vy7Rg2h" Apr 22 17:21:40 volumio go-librespot[6089]: time="2025-04-22T17:21:40+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]" Apr 22 17:21:40 volumio go-librespot[6089]: time="2025-04-22T17:21:40+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:41 volumio go-librespot[6089]: time="2025-04-22T17:21:41+08:00" level=debug msg="completed challenge" Apr 22 17:21:41 volumio go-librespot[6089]: time="2025-04-22T17:21:41+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" Apr 22 17:21:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:42 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:42 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Apr 22 17:21:44 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:44 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:44 volumio go-librespot[6098]: Librespot-go daemon starting... Apr 22 17:21:44 volumio go-librespot[6098]: time="2025-04-22T17:21:44+08:00" level=info msg="generated new device id: a73ca61b6dc3b07dcb6f50d3aa32a388747b6973" Apr 22 17:21:44 volumio go-librespot[6098]: time="2025-04-22T17:21:44+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:45 volumio go-librespot[6098]: time="2025-04-22T17:21:45+08:00" level=debug msg="obtained new client token: AACz9lF/i5W7qwWWFO0r4kFiyui40IoBXFJtc3m3lLR7/uzaHN4crLbuBqndFggHPsEIBwlQ7Kl2MBJUbd6Q8slciIhcUHEFLgUbIJwkS6WkjLZbd8+RpzCVVtuYYT7pmJjmKpHuI23X1MjJwu4RVQ8gfTF4DMwyCWWxd/jUvYT/lTATJ4esZ6zQc//bdoNx7qlP23Ft7WX43PTQ0+xBnej/Z2F9Wyoc87UjHkMmQmvDW8KhunuV5csoJRkVX74m" Apr 22 17:21:45 volumio go-librespot[6098]: time="2025-04-22T17:21:45+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]" Apr 22 17:21:45 volumio go-librespot[6098]: time="2025-04-22T17:21:45+08:00" level=debug msg="completed keyexchange" Apr 22 17:21:45 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:45 volumio go-librespot[6098]: time="2025-04-22T17:21:45+08:00" level=debug msg="new websocket client" Apr 22 17:21:45 volumio volumio[4772]: info: Connection to go-librespot Websocket established Apr 22 17:21:46 volumio go-librespot[6098]: time="2025-04-22T17:21:46+08:00" level=debug msg="completed challenge" Apr 22 17:21:46 volumio go-librespot[6098]: time="2025-04-22T17:21:46+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" Apr 22 17:21:46 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 22 17:21:46 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 22 17:21:46 volumio volumio[4772]: info: Connection to go-librespot Websocket closed Apr 22 17:21:48 volumio volumio[4772]: info: Getting Spotify volume Apr 22 17:21:48 volumio volumio[4772]: (node:4772) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:48 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 22 17:21:48 volumio volumio[4772]: (node:4772) 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: 46) Apr 22 17:21:48 volumio volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 22 17:21:48 volumio volumio[4772]: info: CoreCommandRouter::volumioGetState Apr 22 17:21:48 volumio volumio[4772]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Apr 22 17:21:49 volumio volumio[4772]: info: Initializing connection to go-librespot Websocket Apr 22 17:21:49 volumio volumio[4772]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 22 17:21:49 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 22 17:21:49 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Apr 22 17:21:49 volumio systemd[1]: Stopped go-librespot Daemon. Apr 22 17:21:49 volumio systemd[1]: Started go-librespot Daemon. Apr 22 17:21:49 volumio go-librespot[6106]: Librespot-go daemon starting... Apr 22 17:21:49 volumio go-librespot[6106]: time="2025-04-22T17:21:49+08:00" level=info msg="generated new device id: caf8761ca726bf81e37e8d70be88fe707c3ef028" Apr 22 17:21:49 volumio go-librespot[6106]: time="2025-04-22T17:21:49+08:00" level=debug msg="stored credentials not found" Apr 22 17:21:50 volumio go-librespot[6106]: time="2025-04-22T17:21:50+08:00" level=debug msg="obtained new client token: AAAUbOaE7BvIdKXoZTLiqGRXIwJcXFGOys6wPDYpxrloU0i0pH/OIJAV24P1N8U3E7JP8a/8PGR29mQZBVDQVoNCVeLUgRBDPQWS3qSRminRW7sb8Jd4+Ck5O6hrEWsXnOGvEFqB5F/ClBB7j/haY9ZYqRLjl0cg2uxufZrUZQxSZqfBghokcvbsUIyW+WwaBIbbhZTUqv5v/IURzJ+hirP03NIaQbCpt07yHQJqqI+gwnTJX34VR0t6QY8EdQ==" Apr 22 17:21:50 volumio go-librespot[6106]: time="2025-04-22T17:21:50+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]" Apr 22 17:21:50 volumio volumio[4772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 17:21:50 volumio volumio[4772]: Error: connect ETIMEDOUT 199.59.148.222:80 Apr 22 17:21:50 volumio volumio[4772]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 22 17:21:50 volumio volumio[4772]: errno: -110, Apr 22 17:21:50 volumio volumio[4772]: code: 'ETIMEDOUT', Apr 22 17:21:50 volumio volumio[4772]: syscall: 'connect', Apr 22 17:21:50 volumio volumio[4772]: address: '199.59.148.222', Apr 22 17:21:50 volumio volumio[4772]: port: 80 Apr 22 17:21:50 volumio volumio[4772]: } Apr 22 17:21:50 volumio volumio[4772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 17:21:50 volumio sudo[6123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 17:20 Apr 22 17:21:50 volumio sudo[6123]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"