-- Logs begin at Sat 2024-04-27 02:12:53 CST, end at Sat 2024-04-27 02:38:41 CST. -- Apr 27 02:37:00 volumio go-librespot[3130]: time="2024-04-27T02:37:00+08:00" level=info msg="generated new device id: e6bb766c87768ad299ea8d52cda748beed7b72e2" Apr 27 02:37:00 volumio go-librespot[3130]: time="2024-04-27T02:37:00+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:00 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:00 volumio go-librespot[3130]: time="2024-04-27T02:37:00+08:00" level=debug msg="new websocket client" Apr 27 02:37:00 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:37:00 volumio go-librespot[3130]: time="2024-04-27T02:37:00+08:00" level=debug msg="obtained new client token: AADfhc/RD6xDDQcgwhId01WyTtfeArXCUrtDsbGnJhFV1KZnJwG+TUegDT6/zztsfSbHyYOtL2CB6cdy3rbeFsM9h7YuHiljJHsn6qrmI/YdXdOCkH2Ho1LHFyDT3GrU7fmQlU+sZ0WWf62HUlcjqm5H24R19wNet1oNyGWLfDOQMwBfyMLuWf1bojEWsWI+EQPoZ2hHTEtdla7bSRvcEFNFkBtz38HtzYIn2ffeZJoDUxlyzYrcb0ARTAx9+HQ=" Apr 27 02:37:01 volumio go-librespot[3130]: time="2024-04-27T02:37:01+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 27 02:37:02 volumio go-librespot[3130]: time="2024-04-27T02:37:02+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:02 volumio go-librespot[3130]: time="2024-04-27T02:37:02+08:00" level=debug msg="completed challenge" Apr 27 02:37:02 volumio go-librespot[3130]: time="2024-04-27T02:37: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" Apr 27 02:37:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:02 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:37:03 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:37:03 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:03 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:37:03 volumio volumio[867]: (node:867) 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) Apr 27 02:37:03 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 27 02:37:03 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:03 volumio volumio[867]: info: CorePlayQueue::getTrack 10 Apr 27 02:37:03 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:05 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:05 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Apr 27 02:37:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:06 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:06 volumio go-librespot[3180]: Librespot-go daemon starting... Apr 27 02:37:06 volumio go-librespot[3180]: time="2024-04-27T02:37:06+08:00" level=info msg="generated new device id: aa573e34e6783cc7203395df875228cbab5f61b4" Apr 27 02:37:06 volumio go-librespot[3180]: time="2024-04-27T02:37:06+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:07 volumio go-librespot[3180]: time="2024-04-27T02:37:07+08:00" level=debug msg="obtained new client token: AAAakaLpdoZxQ8YpthPP1Ave7VhShYfWg/QZ4vasAkvh8Z9g8WvX5ZQISCqmzxFRCnzopXNAmf4WSx9sEEzEg/MP5Z/89nb9VNuOuLy+MD9s4S8FfPNAzEGtJ37RhHxIvDJd0b1n60U/GKLW2vZ9RSzzD7aIuKjaOkQGoRtfXvu6ornL7rgkGHTYUEaXfkl3VnHIhv//RdSlFCyC9rk1BLNvpqyRjnMe5phK6uBbj9CMJllk1hphLVvh7kRLoSs=" Apr 27 02:37:08 volumio go-librespot[3180]: time="2024-04-27T02:37: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-gew4.spotify.com:80]" Apr 27 02:37:08 volumio go-librespot[3180]: time="2024-04-27T02:37:08+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:08 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:08 volumio go-librespot[3180]: time="2024-04-27T02:37:08+08:00" level=debug msg="new websocket client" Apr 27 02:37:08 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:37:08 volumio go-librespot[3180]: time="2024-04-27T02:37:08+08:00" level=debug msg="completed challenge" Apr 27 02:37:09 volumio go-librespot[3180]: time="2024-04-27T02:37: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 27 02:37:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:09 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:37:11 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:37:11 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:11 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:37:11 volumio volumio[867]: (node:867) 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) Apr 27 02:37:11 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 27 02:37:11 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:11 volumio volumio[867]: info: CorePlayQueue::getTrack 10 Apr 27 02:37:11 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:12 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:12 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Apr 27 02:37:12 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:12 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:12 volumio go-librespot[3188]: Librespot-go daemon starting... Apr 27 02:37:12 volumio go-librespot[3188]: time="2024-04-27T02:37:12+08:00" level=info msg="generated new device id: ba7a1021727d6467246f04f42002dad5daabecea" Apr 27 02:37:12 volumio go-librespot[3188]: time="2024-04-27T02:37:12+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:13 volumio go-librespot[3188]: time="2024-04-27T02:37:13+08:00" level=debug msg="obtained new client token: AAD1UDdjWtMAly2M8lPg/Z096nLZt0U9F3t7gGA+jsZDEmsDoAIIZeHFQSo5+qTdtty2BbPtqJ61WWZsXhiP6LI9EiICKvYHulUm99vCSxD5I2k4DmlLUsV12+rrhI+b2DveCN0dzjFUNG5NLG1aQE8WUH2TYwd9OvcwPJAo6H/H1NzGNxvJUHkOM08jAFblspfHx7q2IlNz6/yLwh0JOJwu6q5k3TGcOzNtSqu9T2nBrJR5V3PMbY+hYczNycM=" Apr 27 02:37:13 volumio go-librespot[3188]: time="2024-04-27T02:37:13+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 27 02:37:14 volumio go-librespot[3188]: time="2024-04-27T02:37:14+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPlay Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::play index 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::stop Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::updateTrackBlock Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrackBlock Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::stPlaybackTimer Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 10 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::serviceStop Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 10 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::serviceStop Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::stop Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 02:37:14 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand stop took 17 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::play index undefined Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::startPlaybackTimer Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::clearAddPlayTracks USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand stop Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 17 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand stop took 4 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 3 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 2 milliseconds Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand clear Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces system playlist update Apr 27 02:37:14 volumio volumio[867]: info: Ignoring MPD Status Update Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand clear took 3 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand add "USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape" Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"流浪花","artist":"[51ape.com]吕方","album":null,"uri":"USB/288016118015E65A/无损音乐/无损音乐-吕方 - 流浪花.flac","trackType":"flac"} Apr 27 02:37:14 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState stateService stop Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus stop Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: No code Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 27 02:37:14 volumio volumio[867]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 76ms Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 61ms Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 60ms Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces system playlist update Apr 27 02:37:14 volumio volumio[867]: info: Ignoring MPD Status Update Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces system playlist update Apr 27 02:37:14 volumio volumio[867]: info: Ignoring MPD Status Update Apr 27 02:37:14 volumio volumio[867]: error: updateQueue error: null Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces system playlist update Apr 27 02:37:14 volumio volumio[867]: info: Ignoring MPD Status Update Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 60ms Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand add "USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape" took 58 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 5ms Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 4ms Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand play Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces system playlist update Apr 27 02:37:14 volumio volumio[867]: info: Ignoring MPD Status Update Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces system playlist update Apr 27 02:37:14 volumio volumio[867]: info: Ignoring MPD Status Update Apr 27 02:37:14 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 40ms Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand play took 38 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 38ms Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 36ms Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio go-librespot[3188]: time="2024-04-27T02:37:14+08:00" level=debug msg="completed challenge" Apr 27 02:37:14 volumio go-librespot[3188]: time="2024-04-27T02:37: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" Apr 27 02:37:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 226 milliseconds Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 27 02:37:14 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: Apr 27 02:37:14 volumio volumio[867]: ---------------------------- MPD announces state update: player Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::getState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand status Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 234 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 233 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":468,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冲动的惩罚","artist":"[www.51ape.com]刀郎","album":null,"uri":"USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape","trackType":"ape"} Apr 27 02:37:14 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState stateService play Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus stop Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 240ms Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 9 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 6 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand status took 5 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseState Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:14 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":468,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冲动的惩罚","artist":"[www.51ape.com]刀郎","album":null,"uri":"USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape","trackType":"ape"} Apr 27 02:37:14 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState stateService play Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus play Apr 27 02:37:14 volumio volumio[867]: info: Received an update from plugin. extracting info from payload Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":468,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冲动的惩罚","artist":"[www.51ape.com]刀郎","album":null,"uri":"USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape","trackType":"ape"} Apr 27 02:37:14 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState stateService play Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus play Apr 27 02:37:14 volumio volumio[867]: info: Received an update from plugin. extracting info from payload Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:14 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:14 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 293ms Apr 27 02:37:14 volumio volumio[867]: info: ------------------------------ 293ms Apr 27 02:37:14 volumio volumio[867]: info: sendMpdCommand playlistinfo took 55 milliseconds Apr 27 02:37:15 volumio volumio[867]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 27 02:37:15 volumio volumio[867]: info: sendMpdCommand playlistinfo took 55 milliseconds Apr 27 02:37:15 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:15 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:15 volumio volumio[867]: verbose: ControllerMpd::parseTrackInfo Apr 27 02:37:15 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":468,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冲动的惩罚","artist":"[www.51ape.com]刀郎","album":null,"uri":"USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape","trackType":"ape"} Apr 27 02:37:15 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::syncState stateService play Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus play Apr 27 02:37:15 volumio volumio[867]: info: Received an update from plugin. extracting info from payload Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:15 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":468,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冲动的惩罚","artist":"[www.51ape.com]刀郎","album":null,"uri":"USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape","trackType":"ape"} Apr 27 02:37:15 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::syncState stateService play Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus play Apr 27 02:37:15 volumio volumio[867]: info: Received an update from plugin. extracting info from payload Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:15 volumio volumio[867]: info: ControllerMpd::pushState Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::servicePushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: verbose: STATE SERVICE {"status":"play","position":0,"seek":709,"duration":468,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"冲动的惩罚","artist":"[www.51ape.com]刀郎","album":null,"uri":"USB/288016118015E65A/无损音乐/刀郎-冲动的惩罚.ape","trackType":"ape"} Apr 27 02:37:15 volumio volumio[867]: verbose: CURRENT POSITION 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::syncState stateService play Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::syncState currentStatus play Apr 27 02:37:15 volumio volumio[867]: info: Received an update from plugin. extracting info from payload Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:15 volumio volumio[867]: info: CoreStateMachine::pushState Apr 27 02:37:15 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:15 volumio volumio[867]: info: CoreCommandRouter::volumioPushState Apr 27 02:37:15 volumio volumio[867]: info: ------------------------------ 168ms Apr 27 02:37:15 volumio volumio[867]: info: ------------------------------ 165ms Apr 27 02:37:15 volumio volumio[867]: info: ------------------------------ 164ms Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:15 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:15 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 53. Apr 27 02:37:17 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:18 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:18 volumio go-librespot[3207]: Librespot-go daemon starting... Apr 27 02:37:18 volumio go-librespot[3207]: time="2024-04-27T02:37:18+08:00" level=info msg="generated new device id: e2472c21add184e89e81efa7f2ef61c5bc1267e0" Apr 27 02:37:18 volumio go-librespot[3207]: time="2024-04-27T02:37:18+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:18 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:18 volumio go-librespot[3207]: time="2024-04-27T02:37:18+08:00" level=debug msg="new websocket client" Apr 27 02:37:18 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:37:19 volumio go-librespot[3207]: time="2024-04-27T02:37:19+08:00" level=debug msg="obtained new client token: AADq6o7+/gpHH6nRfumxxb2ZXCF7cx+D0PPdmWeidBzEnbwq5uoSWRwQDEfwyyNhk01Tl7G6sbSB2oKeSBO/PX3w4g/xf8aeW3+6W+qjOFoFSFoe7zfvUvSoGUW8oiDWkX0cFPwHmPrzhEledeqS9aeJSu+TqWUSCNQCXyYRVc/Thc12dARYY4HmVppiX+ZMkTL5A1OkvU8iRVvea2zBPIq1Y9yCHOF6H3zAlW+3nIQsUoSSvZjv+2RPAiXRSUg=" Apr 27 02:37:20 volumio go-librespot[3207]: time="2024-04-27T02:37:20+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 27 02:37:20 volumio go-librespot[3207]: time="2024-04-27T02:37:20+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:21 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:37:21 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 27 02:37:21 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:21 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:21 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:21 volumio go-librespot[3207]: time="2024-04-27T02:37:21+08:00" level=debug msg="completed challenge" Apr 27 02:37:21 volumio go-librespot[3207]: time="2024-04-27T02:37: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" Apr 27 02:37:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:21 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: socket hang up Apr 27 02:37:21 volumio volumio[867]: at connResetException (internal/errors.js:607:14) Apr 27 02:37:21 volumio volumio[867]: at Socket.socketOnEnd (_http_client.js:493:23) Apr 27 02:37:21 volumio volumio[867]: at Socket.emit (events.js:327:22) Apr 27 02:37:21 volumio volumio[867]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 27 02:37:21 volumio volumio[867]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 27 02:37:21 volumio volumio[867]: (node:867) 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: 27) Apr 27 02:37:21 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:37:24 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:24 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 54. Apr 27 02:37:24 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:24 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:24 volumio go-librespot[3232]: Librespot-go daemon starting... Apr 27 02:37:24 volumio go-librespot[3232]: time="2024-04-27T02:37:24+08:00" level=info msg="generated new device id: 30b562cd4708f1135b0878ce3a0b6f52732948f6" Apr 27 02:37:24 volumio go-librespot[3232]: time="2024-04-27T02:37:24+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:25 volumio go-librespot[3232]: time="2024-04-27T02:37:25+08:00" level=debug msg="obtained new client token: AAAktosSQz6oLeUFJ13cj0TiGgoDuYJ8XlLwp5xMgGv2nirHCurSneBx8Rm7IeKKyoKaiqkf0SNdFqSVJNZpw/OZ57gbWGQhtrKSqEvtLRZjuoSIR19TFepup9XDq2adx2zm4ILN31tZDddUvHyS8ZB9k2R29ZnYH4GslUD8tT68DrbLgedF8wnAkchmqJ8/0KnFO9/bGgzCB3W+hW/e338mADTcBs83rSPcF3ewTmib3GRU9MAi9eA4F5yfDfE=" Apr 27 02:37:26 volumio go-librespot[3232]: time="2024-04-27T02:37: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 27 02:37:27 volumio go-librespot[3232]: time="2024-04-27T02:37:27+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:27 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:27 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:37:27 volumio go-librespot[3232]: time="2024-04-27T02:37:27+08:00" level=debug msg="new websocket client" Apr 27 02:37:27 volumio go-librespot[3232]: time="2024-04-27T02:37:27+08:00" level=debug msg="completed challenge" Apr 27 02:37:27 volumio go-librespot[3232]: time="2024-04-27T02:37: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 27 02:37:27 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:27 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:27 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:37:30 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:37:30 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:30 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:37:30 volumio volumio[867]: (node:867) 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: 28) Apr 27 02:37:30 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 27 02:37:30 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:30 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:30 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:30 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:30 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:30 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:30 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 55. Apr 27 02:37:30 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:30 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:30 volumio go-librespot[3256]: Librespot-go daemon starting... Apr 27 02:37:31 volumio go-librespot[3256]: time="2024-04-27T02:37:31+08:00" level=info msg="generated new device id: 27cdcf445371559d1ab763596bc4508bb4cf3835" Apr 27 02:37:31 volumio go-librespot[3256]: time="2024-04-27T02:37:31+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:31 volumio go-librespot[3256]: time="2024-04-27T02:37:31+08:00" level=debug msg="obtained new client token: AAAIxm5Q/rhji/x3HSj4G3Rsf5rvOBqdgZi+lSpadAq3Epqsor8ldaqGPCKqMwVeQkIam485hlEPJXLxBLwEYf59I0P1THJKBmQOfuS4P7P7UnGgDDiV/sRVa5ymL3BQsYtbao7OfcMkcxvxGb4rCUv5+AfrripoTVNbNTDNRYvk+pn3lEFCXL8hM1gCdTDS8cTzfdPKlf7ngZf27n19BUCI8zWjWEF+h4Vye2sPic5r0b+cYxrdSkMYtfNRNQs=" Apr 27 02:37:32 volumio volumio[867]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 27 02:37:32 volumio volumio[867]: info: touch_display: Setting screensaver timeout to 15 seconds. Apr 27 02:37:32 volumio go-librespot[3256]: time="2024-04-27T02:37:32+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 27 02:37:32 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 9 Apr 27 02:37:32 volumio go-librespot[3256]: time="2024-04-27T02:37:32+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:32 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 Apr 27 02:37:32 volumio volumio[867]: info: peppy_screensaver: Start PeppyMeter Apr 27 02:37:33 volumio go-librespot[3256]: time="2024-04-27T02:37:33+08:00" level=debug msg="completed challenge" Apr 27 02:37:33 volumio go-librespot[3256]: time="2024-04-27T02:37:33+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 27 02:37:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:33 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:33 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 56. Apr 27 02:37:36 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:36 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:36 volumio go-librespot[3303]: Librespot-go daemon starting... Apr 27 02:37:36 volumio go-librespot[3303]: time="2024-04-27T02:37:36+08:00" level=info msg="generated new device id: eb85e09b90f3d3d4dc30a537a28cfbbf35bc3b57" Apr 27 02:37:36 volumio go-librespot[3303]: time="2024-04-27T02:37:36+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:36 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:36 volumio go-librespot[3303]: time="2024-04-27T02:37:36+08:00" level=debug msg="new websocket client" Apr 27 02:37:36 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:37:37 volumio go-librespot[3303]: time="2024-04-27T02:37:37+08:00" level=debug msg="obtained new client token: AAAWaDynNQEctlzc6K7M/sgjxERcepAoNegpptQIyx+TI/+k7unvh/31GOTarKYfEKwXyCd69jiweBI8faXbX8nSHoghGrFG/elSW1fOyaoeeV6c8s+lDdZEcSH2qltOkkLwy4/w8K5wtg6qaWuByV1/SkXyAOe/rW2OQeNCf9FxYnXBuI4p0jKUZZ6AsgcTeMFL2/q7ixtnG3JjDsZcTXgo5NTYv9blC9hrRxJJ5P3dUKm1hVR5zK8DL//z" Apr 27 02:37:38 volumio go-librespot[3303]: time="2024-04-27T02:37:38+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 27 02:37:38 volumio go-librespot[3303]: time="2024-04-27T02:37:38+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:38 volumio go-librespot[3303]: time="2024-04-27T02:37:38+08:00" level=debug msg="completed challenge" Apr 27 02:37:39 volumio go-librespot[3303]: time="2024-04-27T02:37:39+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 27 02:37:39 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:37:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:39 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:37:39 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:39 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:37:39 volumio volumio[867]: (node:867) 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: 29) Apr 27 02:37:39 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 27 02:37:39 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:39 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:39 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:42 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:42 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 57. Apr 27 02:37:42 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:42 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:42 volumio go-librespot[3321]: Librespot-go daemon starting... Apr 27 02:37:42 volumio go-librespot[3321]: time="2024-04-27T02:37:42+08:00" level=info msg="generated new device id: 4fe0fa6c76e19904cfd83df9d95ff1715e2dcba5" Apr 27 02:37:42 volumio go-librespot[3321]: time="2024-04-27T02:37:42+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:43 volumio go-librespot[3321]: time="2024-04-27T02:37:43+08:00" level=debug msg="obtained new client token: AABkDFuiMbquKxxSLj5nqyprarQ8ZqiRpV6OYOVgfydUmW9HcW4zJc4oKgXplQTG1IdVdcBdoV9e1fTz7eTtUwIGsk853sip7hwPok1n6VJX+Z4YR2okHijdY9KpYZCWyAE6z3+LzjvNoUcvmI+VnSPF6vtu9xTRf4LycU52asBGV0dixr/iWZYUhxy++7BJ1zaFcb/KadUTQKOiaw4tQeg1yIyIGqexdAXyoluSCKAbkZHC5NTy/AmYbHi9" Apr 27 02:37:44 volumio go-librespot[3321]: time="2024-04-27T02:37:44+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 27 02:37:44 volumio go-librespot[3321]: time="2024-04-27T02:37:44+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:44 volumio go-librespot[3321]: time="2024-04-27T02:37:44+08:00" level=debug msg="completed challenge" Apr 27 02:37:44 volumio go-librespot[3321]: time="2024-04-27T02:37:44+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 27 02:37:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:45 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:45 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:47 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 Apr 27 02:37:47 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 11 Apr 27 02:37:47 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:47 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 58. Apr 27 02:37:47 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:47 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:47 volumio go-librespot[3369]: Librespot-go daemon starting... Apr 27 02:37:48 volumio go-librespot[3369]: time="2024-04-27T02:37:48+08:00" level=info msg="generated new device id: f29bfeea813b7333873d988df126d683321a079d" Apr 27 02:37:48 volumio go-librespot[3369]: time="2024-04-27T02:37:48+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:48 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:48 volumio go-librespot[3369]: time="2024-04-27T02:37:48+08:00" level=debug msg="new websocket client" Apr 27 02:37:48 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:37:48 volumio go-librespot[3369]: time="2024-04-27T02:37:48+08:00" level=debug msg="obtained new client token: AABnXeYDLQloiQioNIJV1QtI6+BLYy7B5ozArIxMKwKtr15kxygmdjvghoSHb6ZQIquy4ZQt6T4IzmQ29pdPK7rXOK1pHIwcvJqA3Pskp31/K9J7ZLKN4L3IyHkL/6fNBwgV/VXTIfVCV1igE2by8PU96FdbJO+1PXeCSxpJATq84TyJFLnHrg6YB25zlBHzVFMA60aQBFrFQ+Y5gBUpPUelPUfwIk6i9UD4hzGzpMqExPKeMAay6v5zZVfuOkY=" Apr 27 02:37:49 volumio go-librespot[3369]: time="2024-04-27T02:37: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-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 27 02:37:49 volumio go-librespot[3369]: time="2024-04-27T02:37:49+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:50 volumio go-librespot[3369]: time="2024-04-27T02:37:50+08:00" level=debug msg="completed challenge" Apr 27 02:37:50 volumio go-librespot[3369]: time="2024-04-27T02:37: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 27 02:37:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:50 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:37:51 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:37:51 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:51 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:37:51 volumio volumio[867]: (node:867) 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: 30) Apr 27 02:37:51 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Apr 27 02:37:51 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:37:51 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:37:51 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:37:53 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:53 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 59. Apr 27 02:37:53 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:53 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:53 volumio go-librespot[3387]: Librespot-go daemon starting... Apr 27 02:37:53 volumio go-librespot[3387]: time="2024-04-27T02:37:53+08:00" level=info msg="generated new device id: 6a09de21c1ecb1b20b84002b7929dd1cb09803b6" Apr 27 02:37:53 volumio go-librespot[3387]: time="2024-04-27T02:37:53+08:00" level=debug msg="stored credentials not found" Apr 27 02:37:54 volumio go-librespot[3387]: time="2024-04-27T02:37:54+08:00" level=debug msg="obtained new client token: AADrG+eGXdpwVOFdGH58sK9OA+iuvdqG9PKJ1njsWYWuzJYspFHWZ8QoyTbuK7Gk/TCdLCi/rKZVFIA4L5eW8O35JZ5xQ92UEoGI/KeV7sHaKwl7kOIDA3+FOh77iiA8gDJ7FmkIC3lWdvdDIy6KYkbEuzfkCctEm5QPHaM/1dCWPdVgQogICbg5gWe4XNUCSxn6dILmFA5YLslyJLpikPqRgzjapeMbsU7/f8Iwt3Omxum/U2QRI0nvwcMI" Apr 27 02:37:55 volumio go-librespot[3387]: time="2024-04-27T02:37:55+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 27 02:37:56 volumio go-librespot[3387]: time="2024-04-27T02:37:56+08:00" level=debug msg="completed keyexchange" Apr 27 02:37:56 volumio go-librespot[3387]: time="2024-04-27T02:37:56+08:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed solving challenge: failed login: BadCredentials" Apr 27 02:37:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:37:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:37:56 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:56 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:59 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:37:59 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:37:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:37:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 60. Apr 27 02:37:59 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:37:59 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:37:59 volumio go-librespot[3407]: Librespot-go daemon starting... Apr 27 02:37:59 volumio go-librespot[3407]: time="2024-04-27T02:37:59+08:00" level=info msg="generated new device id: a2a705ab75cdbb62dd09e7a30620431db2c67889" Apr 27 02:37:59 volumio go-librespot[3407]: time="2024-04-27T02:37:59+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:00 volumio go-librespot[3407]: time="2024-04-27T02:38:00+08:00" level=debug msg="obtained new client token: AABioA0q0SEwzcaRcxCc/EgVmJ6AHnwhdF9E4dfKtxz4FK1XCwGSmHLErexVsI6h1ds0FnLjN2vjA6LyiJQkhDUr23xQXkcDNceouGZAIsoHbWaBjrJ60mGrZNE/bPt1TxaNaYzgl5AHzY/WgXYlWxQjeb81YAAi4CtrwwcfNGJGdZiedMRY9BGgaKyp2PQVCMTMfkMvEZIAS2FMdbEGJQFOpONbdgYRd/LJ6MUXe7pEbnGadjLR5G6exhxnPng=" Apr 27 02:38:02 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:02 volumio go-librespot[3407]: time="2024-04-27T02:38:02+08:00" level=debug msg="new websocket client" Apr 27 02:38:02 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:38:03 volumio go-librespot[3407]: time="2024-04-27T02:38: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 27 02:38:03 volumio go-librespot[3407]: time="2024-04-27T02:38:03+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:04 volumio go-librespot[3407]: time="2024-04-27T02:38:04+08:00" level=debug msg="completed challenge" Apr 27 02:38:04 volumio go-librespot[3407]: time="2024-04-27T02:38: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 27 02:38:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:04 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:38:05 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:38:05 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:05 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:38:05 volumio volumio[867]: (node:867) 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 27 02:38:05 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Apr 27 02:38:05 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:38:05 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:38:05 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:38:07 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:07 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:38:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 61. Apr 27 02:38:07 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:38:08 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:38:08 volumio go-librespot[3475]: Librespot-go daemon starting... Apr 27 02:38:08 volumio go-librespot[3475]: time="2024-04-27T02:38:08+08:00" level=info msg="generated new device id: 0358c9cb4693c22703872a5902cb5b4d890a3069" Apr 27 02:38:08 volumio go-librespot[3475]: time="2024-04-27T02:38:08+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:09 volumio go-librespot[3475]: time="2024-04-27T02:38:09+08:00" level=debug msg="obtained new client token: AACfOtYWQnj3fj+kcKgInaVthQ6QL/hwIdoLLIKy56i56uWlZIv9HZkC0mqI87+9go+gzmX7x3N01IX1cANQmJEnoos2O+KvyFJKvMshbCZJ4qIO8AiBGIEQ5Wrnk01baHC/Eg++34nEV4h2kj8S9vIVpy6rMlAP/THYS3QM9loQH9+GVPGljDwth0+T5YCD3WpUPTw4GBkp0Y2seHYfe8uhHSFJAIhoF9KJOnrOaNFFs8PEKRX6jFfEu7S4" Apr 27 02:38:10 volumio go-librespot[3475]: time="2024-04-27T02:38:10+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 27 02:38:10 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:10 volumio go-librespot[3475]: time="2024-04-27T02:38:10+08:00" level=debug msg="new websocket client" Apr 27 02:38:10 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:38:10 volumio go-librespot[3475]: time="2024-04-27T02:38:10+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:11 volumio go-librespot[3475]: time="2024-04-27T02:38:11+08:00" level=debug msg="completed challenge" Apr 27 02:38:11 volumio go-librespot[3475]: time="2024-04-27T02:38:11+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 27 02:38:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:11 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:38:13 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:38:13 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:13 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:38:13 volumio volumio[867]: (node:867) 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: 32) Apr 27 02:38:13 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Apr 27 02:38:13 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:38:13 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:38:13 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:38:14 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:14 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:38:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 62. Apr 27 02:38:14 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:38:14 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:38:14 volumio go-librespot[3496]: Librespot-go daemon starting... Apr 27 02:38:14 volumio go-librespot[3496]: time="2024-04-27T02:38:14+08:00" level=info msg="generated new device id: a3dc686fa61f682e1cce1b196f829c34e3925e46" Apr 27 02:38:14 volumio go-librespot[3496]: time="2024-04-27T02:38:14+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:15 volumio go-librespot[3496]: time="2024-04-27T02:38:15+08:00" level=debug msg="obtained new client token: AAABx1l7oVdI/qkyZ/QEQCk6sjXGNduScW2TTAJQY85tXwIeNE9kQxB8lUEw8bujlDHYDclmEzRaf7rfiDeu5snEIzdB+fWB6Z386UjXXTx6gSOSQ7XTMbLJ68D+3vfnRijbIMdaolri5ORPgmz99b3aXT61t2UaBT9A7lZ1GrXa9h1AJgODbVju96YkXNnnHdPMr/yhExPzzyuDQXOyO9z3ig4A5NS5qu1oUOjycI7nGixGa3cduy44k6uyJoE=" Apr 27 02:38:16 volumio go-librespot[3496]: time="2024-04-27T02:38:16+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 27 02:38:16 volumio go-librespot[3496]: time="2024-04-27T02:38:16+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:17 volumio go-librespot[3496]: time="2024-04-27T02:38:17+08:00" level=debug msg="completed challenge" Apr 27 02:38:17 volumio go-librespot[3496]: time="2024-04-27T02:38:17+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 27 02:38:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:17 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:17 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:20 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:20 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:38:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 63. Apr 27 02:38:20 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:38:20 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:38:20 volumio go-librespot[3519]: Librespot-go daemon starting... Apr 27 02:38:20 volumio go-librespot[3519]: time="2024-04-27T02:38:20+08:00" level=info msg="generated new device id: 7dda61924dd43726df7d214fbbbfbd6a1522a332" Apr 27 02:38:20 volumio go-librespot[3519]: time="2024-04-27T02:38:20+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:21 volumio go-librespot[3519]: time="2024-04-27T02:38:21+08:00" level=debug msg="obtained new client token: AABBnWqZ7eSiC19c28A40dEhg49R4RQ3+p2pw6PETTZKdhxIUiRUjnDZQXWgXPBnNdz7FVaZtlzHRYzh4F2vy/VCJ8EA84CjO+8MrmuGrEe8RLztJKBI0w+Q+mVYa69fYFgnZKssVQWEUQ82gKD0Aa3/yBzmg04U8oTBYRJyyc9kMzk0TCd44JUNeo5g/eVDkz+PmLRaA/2NZYBpFBoYxPbFjQcNfHMfL1S5xlipelTozJhUjOTXKOt+4vtR" Apr 27 02:38:22 volumio go-librespot[3519]: time="2024-04-27T02:38: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 27 02:38:22 volumio go-librespot[3519]: time="2024-04-27T02:38:22+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:22 volumio go-librespot[3519]: time="2024-04-27T02:38:22+08:00" level=debug msg="completed challenge" Apr 27 02:38:22 volumio go-librespot[3519]: time="2024-04-27T02:38:22+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 27 02:38:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:23 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:23 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:38:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 64. Apr 27 02:38:26 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:38:26 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:38:26 volumio go-librespot[3539]: Librespot-go daemon starting... Apr 27 02:38:26 volumio go-librespot[3539]: time="2024-04-27T02:38:26+08:00" level=info msg="generated new device id: d6c902a138df9aa2059f111c9aa20eab24a38192" Apr 27 02:38:26 volumio go-librespot[3539]: time="2024-04-27T02:38:26+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:26 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:26 volumio go-librespot[3539]: time="2024-04-27T02:38:26+08:00" level=debug msg="new websocket client" Apr 27 02:38:26 volumio volumio[867]: info: Connection to go-librespot Websocket established Apr 27 02:38:27 volumio go-librespot[3539]: time="2024-04-27T02:38:27+08:00" level=debug msg="obtained new client token: AAAXrPjBw321nXX0ilMElAeOK1+yUaqhf8mFzyWSxsM2SHNgGxJAV1BejIDoxRbLthTPduYMtuehQr9pgPB1C5TGpN96Sz6PIPF8gBzgJGVZY3RresXR1gOYPJKkNxVh5wzdFgbzIZ/zWD6NEe7/AzB+M9MB7+XT53wtdxSjU5joIS3msCF7pd6BtErp5o54N12f1YCOmQA1h+wDtfVEgFOzDCQC3K57Iccgjbu1MQaRPVRAAN1eKpd3BDOO" Apr 27 02:38:27 volumio go-librespot[3539]: time="2024-04-27T02:38:27+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 27 02:38:28 volumio go-librespot[3539]: time="2024-04-27T02:38:28+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:28 volumio go-librespot[3539]: time="2024-04-27T02:38:28+08:00" level=debug msg="completed challenge" Apr 27 02:38:28 volumio go-librespot[3539]: time="2024-04-27T02:38:28+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 27 02:38:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:28 volumio volumio[867]: info: Connection to go-librespot Websocket closed Apr 27 02:38:29 volumio volumio[867]: info: Getting Spotify volume Apr 27 02:38:29 volumio volumio[867]: (node:867) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:29 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Apr 27 02:38:29 volumio volumio[867]: (node:867) 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 27 02:38:29 volumio volumio[867]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Apr 27 02:38:29 volumio volumio[867]: info: CoreCommandRouter::volumioGetState Apr 27 02:38:29 volumio volumio[867]: info: CorePlayQueue::getTrack 2 Apr 27 02:38:29 volumio volumio[867]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70 Apr 27 02:38:31 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:31 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:38:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 65. Apr 27 02:38:31 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:38:31 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:38:31 volumio go-librespot[3558]: Librespot-go daemon starting... Apr 27 02:38:32 volumio go-librespot[3558]: time="2024-04-27T02:38:32+08:00" level=info msg="generated new device id: a189d6d4a7175e1e4d614895bf3e09249e9839f0" Apr 27 02:38:32 volumio go-librespot[3558]: time="2024-04-27T02:38:32+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:32 volumio go-librespot[3558]: time="2024-04-27T02:38:32+08:00" level=debug msg="obtained new client token: AAB31/RzsuaRiLNCLkDIuGLqAaanTaX9DDHHF52vRfOXCkxkYeQ55JDpGCYkqPOIEh6ZEubWwlZ1sXVEIcbRUViF1Z9cvzMIRPRO/TDBSdbNDvG5A/YwnRwmLt/lb44RuvzQWrxD62JEK3yb4Ud1SWruGnu9zRXn3nlY02YSj1iUi69xkuZo0rsoGRB2mRRi+CgKVFVBvkKxskSUKuT0Yw0aZGkgY5qSLFgc6q9mn8WeX1Uj3RuDDnbt9yKL5lg=" Apr 27 02:38:33 volumio go-librespot[3558]: time="2024-04-27T02:38:33+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 27 02:38:34 volumio go-librespot[3558]: time="2024-04-27T02:38:34+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:34 volumio go-librespot[3558]: time="2024-04-27T02:38:34+08:00" level=debug msg="completed challenge" Apr 27 02:38:34 volumio go-librespot[3558]: time="2024-04-27T02:38: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" Apr 27 02:38:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:34 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:34 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:37 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:37 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 27 02:38:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 66. Apr 27 02:38:37 volumio systemd[1]: Stopped go-librespot Daemon. Apr 27 02:38:38 volumio systemd[1]: Started go-librespot Daemon. Apr 27 02:38:38 volumio go-librespot[3578]: Librespot-go daemon starting... Apr 27 02:38:38 volumio go-librespot[3578]: time="2024-04-27T02:38:38+08:00" level=info msg="generated new device id: 5cd1c00ecd4115018332b2c4574f4a731fd7e023" Apr 27 02:38:38 volumio go-librespot[3578]: time="2024-04-27T02:38:38+08:00" level=debug msg="stored credentials not found" Apr 27 02:38:39 volumio go-librespot[3578]: time="2024-04-27T02:38:39+08:00" level=debug msg="obtained new client token: AAC0rlapuukfDklhg+iASR99N3x63Ksb3DNlJELl8kcfB4kxfZF8O32u2iQU3fvRT9WQSX1WXdzKz3BMm34vnGHxH7YqfGqqgVzzmCriykKI7OvrOu1cLmL7zM1piJKKTG9z/uPDhsKbJuc4g3NvwIJaLIBwY2uNmjNYTMY+O2whr2oBH14Vz7KH2Hv6dqeAtQyX4r6sNBDjkLZlKNFkq8G5wqLuEcuF3snc9Wwe4uXlqYdOmdTdxX+Xcioyflg=" Apr 27 02:38:39 volumio go-librespot[3578]: time="2024-04-27T02:38: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]" Apr 27 02:38:40 volumio go-librespot[3578]: time="2024-04-27T02:38:40+08:00" level=debug msg="completed keyexchange" Apr 27 02:38:40 volumio go-librespot[3578]: time="2024-04-27T02:38:40+08:00" level=debug msg="completed challenge" Apr 27 02:38:40 volumio go-librespot[3578]: time="2024-04-27T02:38: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" Apr 27 02:38:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 27 02:38:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 27 02:38:40 volumio volumio[867]: info: Initializing connection to go-librespot Websocket Apr 27 02:38:40 volumio volumio[867]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 27 02:38:41 volumio volumio[867]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 02:38:41 volumio volumio[867]: Error: connect ETIMEDOUT 103.252.115.59:80 Apr 27 02:38:41 volumio volumio[867]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 27 02:38:41 volumio volumio[867]: errno: -110, Apr 27 02:38:41 volumio volumio[867]: code: 'ETIMEDOUT', Apr 27 02:38:41 volumio volumio[867]: syscall: 'connect', Apr 27 02:38:41 volumio volumio[867]: address: '103.252.115.59', Apr 27 02:38:41 volumio volumio[867]: port: 80 Apr 27 02:38:41 volumio volumio[867]: } Apr 27 02:38:41 volumio volumio[867]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 02:38:41 volumio sudo[3603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 02:37 Apr 27 02:38:41 volumio sudo[3603]: 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"