-- Logs begin at Fri 2024-08-23 07:36:39 EDT, end at Fri 2024-08-23 08:52:32 EDT. -- Aug 23 08:51:00 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:00 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:00 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:00 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:01 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:01 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:01 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:01 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117799. Aug 23 08:51:01 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:01 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:01 volumio go-librespot[692]: Librespot-go daemon starting... Aug 23 08:51:01 volumio go-librespot[692]: time="2024-08-23T08:51:01-04:00" level=info msg="generated new device id: a81fe3a5e6650247bc14f8f14d8a4f5e718ca17f" Aug 23 08:51:01 volumio go-librespot[692]: time="2024-08-23T08:51:01-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:01 volumio go-librespot[692]: time="2024-08-23T08:51:01-04:00" level=debug msg="obtained new client token: AABVgxnpUI8cfpUC4jkieTf5j3rZssjOTVB0zWen+Onlctovt0Es23WVTvHzkuoQaQEuZ9QSLOhaB/ThtIMfmphboI1naPGcYT5SxJO7ILYGR9zx2+WUk2Nxz42mWmG7pPtl6ECvTsAENX7lg6VCi26CAkBdBGhI+sIzIgSCdLGWLn7WF4CJtgLVK4hqM8Wx9MB0WSZc85OjHI+JCC6WfCcjTYbM2OZaSdJMiROsRZUf31iC9oNPZhoObpuXDQ==" Aug 23 08:51:01 volumio go-librespot[692]: time="2024-08-23T08:51:01-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:51:02 volumio go-librespot[692]: time="2024-08-23T08:51:02-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:02 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:02 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:02 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:02 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:02 volumio go-librespot[692]: time="2024-08-23T08:51:02-04:00" level=debug msg="completed challenge" Aug 23 08:51:02 volumio go-librespot[692]: time="2024-08-23T08:51:02-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:02 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:02 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:03 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:03 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:03 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:03 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:04 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:04 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:04 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:04 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:05 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:05 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:05 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:05 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:05 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:05 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117800. Aug 23 08:51:05 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:05 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:05 volumio go-librespot[699]: Librespot-go daemon starting... Aug 23 08:51:05 volumio go-librespot[699]: time="2024-08-23T08:51:05-04:00" level=info msg="generated new device id: e1b2fa5b47c34549633a4a68314963ee8a80f9bd" Aug 23 08:51:05 volumio go-librespot[699]: time="2024-08-23T08:51:05-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:06 volumio go-librespot[699]: time="2024-08-23T08:51:06-04:00" level=debug msg="obtained new client token: AAApmgeNOIlw2iyq49szzwaBi5KeyOZ5Kf/KJOSg5TQ4B5Roteo979vl5Cwn9nR83jCEQ2EKtkLDxbmL55ksd3rBqOryFc+HR2biVJzyQBY2WpRy8SXdPkIQ4mVk/Itc701x4fGcnzIAhwqyVJgAdICuzeKdbTbeeHaVJrkq0g4kNQkV+sRqsxJGQCwTDfhBxHKGZwSfSwUXpN9l3vcaiazWYgE+Bo/tHY3qTbh5XTQthQqkvlBvbIfLh54=" Aug 23 08:51:06 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:06 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:06 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:06 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:06 volumio go-librespot[699]: time="2024-08-23T08:51:06-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:06 volumio go-librespot[699]: time="2024-08-23T08:51:06-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:06 volumio go-librespot[699]: time="2024-08-23T08:51:06-04:00" level=debug msg="completed challenge" Aug 23 08:51:07 volumio go-librespot[699]: time="2024-08-23T08:51:07-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:07 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:07 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:07 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:07 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:08 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:08 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:08 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:08 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:08 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:08 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:09 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:09 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:09 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:09 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117801. Aug 23 08:51:10 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:10 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:10 volumio go-librespot[706]: Librespot-go daemon starting... Aug 23 08:51:10 volumio go-librespot[706]: time="2024-08-23T08:51:10-04:00" level=info msg="generated new device id: 387cf01a354f417cfa2f44d7c24c53379c61687b" Aug 23 08:51:10 volumio go-librespot[706]: time="2024-08-23T08:51:10-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:10 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:10 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:10 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:10 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:10 volumio go-librespot[706]: time="2024-08-23T08:51:10-04:00" level=debug msg="obtained new client token: AAD8wN6JYop/AR9zU3FXn7RHADwgK+L/nSa06e8BgWmzaiAl6HF+VL+/oE19h0YT/zhY5y39EWsMyu0nWAWCTwMazvo0Wlf7qqmlcmbz1GnPEvhCd6HErDHyUvXzd7o7Ii8R6VZ4+gsy+6fine3pfkazLVxjm0thksQxgM1HEabHHLR29wB+z2w7W//IjUPC4lNZc6YUHdUaQIcvoRVvQutXdp+AjrhnwR2zfUQoMi6DjVi6/THIo6CD0VKV8A==" Aug 23 08:51:10 volumio go-librespot[706]: time="2024-08-23T08:51:10-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:51:10 volumio go-librespot[706]: time="2024-08-23T08:51:10-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:11 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:11 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:11 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:11 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:11 volumio go-librespot[706]: time="2024-08-23T08:51:11-04:00" level=debug msg="completed challenge" Aug 23 08:51:11 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:11 volumio go-librespot[706]: time="2024-08-23T08:51:11-04:00" level=debug msg="new websocket client" Aug 23 08:51:11 volumio volumio[5502]: info: Connection to go-librespot Websocket established Aug 23 08:51:12 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:12 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:12 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:12 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:12 volumio go-librespot[706]: time="2024-08-23T08:51:12-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:12 volumio volumio[5502]: info: Connection to go-librespot Websocket closed Aug 23 08:51:13 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:13 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:13 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:13 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:14 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:14 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:14 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:14 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:14 volumio volumio[5502]: info: Getting Spotify volume Aug 23 08:51:14 volumio volumio[5502]: (node:5502) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:14 volumio volumio[5502]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 23 08:51:14 volumio volumio[5502]: (node:5502) 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: 1460) Aug 23 08:51:14 volumio volumio[5502]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Aug 23 08:51:14 volumio volumio[5502]: info: CoreCommandRouter::volumioGetState Aug 23 08:51:15 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:15 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:15 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:15 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:15 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:15 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117802. Aug 23 08:51:15 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:15 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:15 volumio go-librespot[716]: Librespot-go daemon starting... Aug 23 08:51:15 volumio go-librespot[716]: time="2024-08-23T08:51:15-04:00" level=info msg="generated new device id: ba2fd6c67dc8f9a896dff8075a8040064826a55f" Aug 23 08:51:15 volumio go-librespot[716]: time="2024-08-23T08:51:15-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:15 volumio go-librespot[716]: time="2024-08-23T08:51:15-04:00" level=debug msg="obtained new client token: AADxTNF14gL78FMDUGduk+LIgWEhevoGhtJsNTgWq9H8PPpOljm2ozWQKLcbaR/TD395LR/uAMvh/o4GWd82d+szO6Pfr8kk7N6QsmRirM/gKv+pfyFXwwPGlYZY83fSmC8rU7JG8eofGe+NrEYjYnzxRCtBVzxX0aB91ii4xl4+Tm5MAFEy5XB7ZWuaTwUv2VMbNznuwWfussd0UZKyyK2AnuopWjmOer8Lb1iahTtjSNxR/+fpwmAj6Wsrlw==" Aug 23 08:51:16 volumio go-librespot[716]: time="2024-08-23T08:51:16-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:16 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:16 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:16 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:16 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:16 volumio go-librespot[716]: time="2024-08-23T08:51:16-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:16 volumio go-librespot[716]: time="2024-08-23T08:51:16-04:00" level=debug msg="completed challenge" Aug 23 08:51:16 volumio go-librespot[716]: time="2024-08-23T08:51:16-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:17 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:17 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:17 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:17 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:18 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:18 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:18 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:18 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:18 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:18 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:19 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:19 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:19 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:19 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117803. Aug 23 08:51:19 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:19 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:19 volumio go-librespot[724]: Librespot-go daemon starting... Aug 23 08:51:19 volumio go-librespot[724]: time="2024-08-23T08:51:19-04:00" level=info msg="generated new device id: 1559856c6caf3c26d49306b6d17b76067676deda" Aug 23 08:51:19 volumio go-librespot[724]: time="2024-08-23T08:51:19-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:20 volumio go-librespot[724]: time="2024-08-23T08:51:20-04:00" level=debug msg="obtained new client token: AACLyIZW78B71MuSquc6qHkvjINClMAeTaij6BVE2ZlYmfFUnfYlFNZYKmJQR/4iaZKego0USAodElsLxfE0nxQui3TZwrVQ8oSXLk1HEHioYVnH3vUFi+NzsXKh36zgS4Pff9/tAXAx3Z5yC1qTi8ULK2iWSpaUleJg3GATv1TiGy4z2D3H6OOPnSRHQ2XtMEAWEQCKRfCRC4dWDqRkrcq37uVW82apG7lF73zdXkSdnA1Il0jDj7IlXHg=" Aug 23 08:51:20 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:20 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:20 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:20 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:20 volumio go-librespot[724]: time="2024-08-23T08:51:20-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:51:20 volumio go-librespot[724]: time="2024-08-23T08:51:20-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:21 volumio go-librespot[724]: time="2024-08-23T08:51:21-04:00" level=debug msg="completed challenge" Aug 23 08:51:21 volumio go-librespot[724]: time="2024-08-23T08:51:21-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:21 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:21 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:21 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:21 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:21 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:21 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:22 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:22 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:22 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:22 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:23 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:23 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:23 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:23 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:24 volumio nmbd[658]: [2024/08/23 08:51:24.108098, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 23 08:51:24 volumio nmbd[658]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.44 for name WORKGROUP<1d>. Aug 23 08:51:24 volumio nmbd[658]: This response was from IP 192.168.100.45, reporting an IP address of 192.168.100.45. Aug 23 08:51:24 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:24 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:24 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:24 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117804. Aug 23 08:51:24 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:24 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:24 volumio go-librespot[733]: Librespot-go daemon starting... Aug 23 08:51:24 volumio go-librespot[733]: time="2024-08-23T08:51:24-04:00" level=info msg="generated new device id: cbfd5c4cab25e7abd0064febb80a35736897fd33" Aug 23 08:51:24 volumio go-librespot[733]: time="2024-08-23T08:51:24-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:24 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:24 volumio go-librespot[733]: time="2024-08-23T08:51:24-04:00" level=debug msg="new websocket client" Aug 23 08:51:24 volumio volumio[5502]: info: Connection to go-librespot Websocket established Aug 23 08:51:24 volumio go-librespot[733]: time="2024-08-23T08:51:24-04:00" level=debug msg="obtained new client token: AAA5680F0veC4dAbcianntJKJzHPoCPBiE7Yv8eVNswqtvMW6OvAlJTYXu8JDkHuEFveBflsGN+OhrvlEQ9jpZssjV25JfDbrK6J2naDmXCg3QWaHqDMArs6szmqmeoCOK/FLM1EfM79Oc70KgnSOvYw5OGw20ukn/Z9/22tDk0ME2hTMQBIOarJTIWKExdpx/o9WZNSFoAyeJkxY9Nk5Xkt6uUjFep8t+fXsUDMMegjMuPuO74NFgu4smLTDw==" Aug 23 08:51:24 volumio go-librespot[733]: time="2024-08-23T08:51:24-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:51:25 volumio go-librespot[733]: time="2024-08-23T08:51:25-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:25 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:25 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:25 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:25 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:25 volumio go-librespot[733]: time="2024-08-23T08:51:25-04:00" level=debug msg="completed challenge" Aug 23 08:51:25 volumio go-librespot[733]: time="2024-08-23T08:51:25-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:25 volumio volumio[5502]: info: Connection to go-librespot Websocket closed Aug 23 08:51:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:26 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:26 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:26 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:26 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:27 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:27 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:27 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:27 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:27 volumio volumio[5502]: info: Getting Spotify volume Aug 23 08:51:27 volumio volumio[5502]: (node:5502) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:27 volumio volumio[5502]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Aug 23 08:51:27 volumio volumio[5502]: (node:5502) 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: 1461) Aug 23 08:51:27 volumio volumio[5502]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Aug 23 08:51:27 volumio volumio[5502]: info: CoreCommandRouter::volumioGetState Aug 23 08:51:28 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:28 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:28 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:28 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:28 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:28 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117805. Aug 23 08:51:28 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:28 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:28 volumio go-librespot[742]: Librespot-go daemon starting... Aug 23 08:51:28 volumio go-librespot[742]: time="2024-08-23T08:51:28-04:00" level=info msg="generated new device id: be658ca264971f306d24efc5c4846a9d0e5a2ddb" Aug 23 08:51:28 volumio go-librespot[742]: time="2024-08-23T08:51:28-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:29 volumio go-librespot[742]: time="2024-08-23T08:51:29-04:00" level=debug msg="obtained new client token: AABtMjFyhHXHMtxSlWeMcKZKJIwKPv9I4OtRdjmCOlDsutM07ohraxazUhL+0FuGS+uRVW+Ok0PutU+VxlmWFC4mdAfIprwgRYTtwfjBFZnmQR6StgDt151FyhkemKttN6RM9xa5TM4gVqmNkukkrn+USeIaMSmTgVzhwG9d2oS5xwPyGAPviqX9SOkS07R7/csBz7kgfFMpygaC6wqbVmk7Bkxf1wG7TPoVXaTQqPbinFkSuJDIQqXPVZI=" Aug 23 08:51:29 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:29 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:29 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:29 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:29 volumio go-librespot[742]: time="2024-08-23T08:51:29-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:51:29 volumio go-librespot[742]: time="2024-08-23T08:51:29-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:30 volumio go-librespot[742]: time="2024-08-23T08:51:30-04:00" level=debug msg="completed challenge" Aug 23 08:51:30 volumio go-librespot[742]: time="2024-08-23T08:51:30-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:30 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:30 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:30 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:30 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:31 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:31 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:31 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:31 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:31 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:31 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:32 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:32 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:32 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:32 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:33 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:33 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:33 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:33 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117806. Aug 23 08:51:33 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:33 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:33 volumio go-librespot[749]: Librespot-go daemon starting... Aug 23 08:51:33 volumio go-librespot[749]: time="2024-08-23T08:51:33-04:00" level=info msg="generated new device id: 5c6bf7f46ba731761c57deafa9e0dbfabbb6e523" Aug 23 08:51:33 volumio go-librespot[749]: time="2024-08-23T08:51:33-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:33 volumio go-librespot[749]: time="2024-08-23T08:51:33-04:00" level=debug msg="obtained new client token: AADLwmL5bU4LGC31W4qRgF8T/tDIyR/h2wpUr6WDxhagfbDe6kWVEPTp875UVSXpQopN4gPeDz7zLpKkKJKl3MqA3PkRdIfNe4LBpCMjnTQI79HEH2me1DybIGCMBdV2e2DcEH2bGCzIcAXXsKvUxupnF8pNrJ6xSauLHSHg0+AM8CwdjfGihG4bVuaDSpPCDBGdw9lJYo+Wl+/wOX/I5kQp+QroUwQ0MLZ0qXKPOfTA+2R6NsZOgB3fof4GQQ==" Aug 23 08:51:33 volumio go-librespot[749]: time="2024-08-23T08:51:33-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:34 volumio go-librespot[749]: time="2024-08-23T08:51:34-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:34 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:34 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:34 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:34 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:34 volumio go-librespot[749]: time="2024-08-23T08:51:34-04:00" level=debug msg="completed challenge" Aug 23 08:51:34 volumio go-librespot[749]: time="2024-08-23T08:51:34-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:34 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:34 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:35 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:35 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:35 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:35 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:36 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:36 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:36 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:36 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:37 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:37 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:37 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:37 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:37 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:37 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117807. Aug 23 08:51:37 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:37 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:37 volumio go-librespot[756]: Librespot-go daemon starting... Aug 23 08:51:37 volumio go-librespot[756]: time="2024-08-23T08:51:37-04:00" level=info msg="generated new device id: 6c6257313a5e1830b4db6df1f957fb11a7197395" Aug 23 08:51:37 volumio go-librespot[756]: time="2024-08-23T08:51:37-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:38 volumio go-librespot[756]: time="2024-08-23T08:51:38-04:00" level=debug msg="obtained new client token: AAChiOQLtfkbvyaPvviHpPAq2QRO44tWwyfd+Fm3fhLT/3tIMe0HuVhKY6PlnNHOnimROKEHWCepaZ3z1ezkXDQ9XpHk2h79WfGc5lBSIFfNVh7lB8ZLkREFGcbZVghRIzaRSWBC1nSaQ7Fhgn8HVKHcAdgtJ6T9oAui8HL/9Vavn4Hk6nR041cO8XdP3i4b/3jMEK13X4fuvjPwA7MDdERSI4owBa0L6c4ZywdbAEXF1v9R3sdQXNKUYHE=" Aug 23 08:51:38 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:38 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:38 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:38 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:38 volumio go-librespot[756]: time="2024-08-23T08:51:38-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:38 volumio go-librespot[756]: time="2024-08-23T08:51:38-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:39 volumio go-librespot[756]: time="2024-08-23T08:51:39-04:00" level=debug msg="completed challenge" Aug 23 08:51:39 volumio go-librespot[756]: time="2024-08-23T08:51:39-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:39 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:39 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:39 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:39 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:39 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:39 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:40 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:40 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:40 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:40 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:40 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:40 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:41 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:41 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:41 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:41 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:42 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:42 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:42 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:42 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:42 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:42 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117808. Aug 23 08:51:42 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:42 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:42 volumio go-librespot[763]: Librespot-go daemon starting... Aug 23 08:51:42 volumio go-librespot[763]: time="2024-08-23T08:51:42-04:00" level=info msg="generated new device id: c3ba7863074eff82dd52abbfc93f88f12b8fc490" Aug 23 08:51:42 volumio go-librespot[763]: time="2024-08-23T08:51:42-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:42 volumio go-librespot[763]: time="2024-08-23T08:51:42-04:00" level=debug msg="obtained new client token: AADVh3z/Np10yiGIhLqChf4bAnDzhn3B5XtMRz/qY7qJifeX5ub2P5OYUiqkI2RT06TRiaI63qxODhgJCsSlCFQYs5adVmhtcpuP70mf57kmVHWOxoYPlOBAFo8+PtX355g/IPUauJIoq68a984OFlARGM6+V65awXF95G6YEHmFlTbrAzD/atDJ4NqkQhmrdxwgRTN1PWNO3Gw7nNrE0Azv0FYtDMXXqQ3OeDMrCrEpXJCdjHGjdZQEWvE4GA==" Aug 23 08:51:42 volumio go-librespot[763]: time="2024-08-23T08:51:42-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:43 volumio go-librespot[763]: time="2024-08-23T08:51:43-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:43 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:43 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:43 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:43 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:43 volumio go-librespot[763]: time="2024-08-23T08:51:43-04:00" level=debug msg="completed challenge" Aug 23 08:51:43 volumio go-librespot[763]: time="2024-08-23T08:51:43-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:43 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:43 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:44 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:44 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:44 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:44 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:45 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:45 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:45 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:45 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:46 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:46 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:46 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:46 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:46 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:46 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117809. Aug 23 08:51:46 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:46 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:46 volumio go-librespot[771]: Librespot-go daemon starting... Aug 23 08:51:46 volumio go-librespot[771]: time="2024-08-23T08:51:46-04:00" level=info msg="generated new device id: e877a147ef2c206a4dba0a506da5ba45d9ace878" Aug 23 08:51:46 volumio go-librespot[771]: time="2024-08-23T08:51:46-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:47 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:47 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:47 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:47 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:47 volumio go-librespot[771]: time="2024-08-23T08:51:47-04:00" level=debug msg="obtained new client token: AADkvHK78dDrfAnHjJg/9NktgmyP5CIoC+G3dSHwYsvaU6p3bW2Ik5j8oabBMu9vZN1pzfy60lY6m2gqfF6kNEbM6Q3gVjguljxn7fqz9NSp6I64fH31auLZBdr0U9c4GDsCpivzrZRyYb8QhPfUB65NcARnNnsSHNXr+fgDjVI2NbxvUdnQNn07JGCkgBMgdLKh+W+gxs4VGOp33JJGnjVXd4olFZZkTxAl6hh6sVoazTw+cG0sixMwHAM=" Aug 23 08:51:47 volumio go-librespot[771]: time="2024-08-23T08:51:47-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:51:47 volumio go-librespot[771]: time="2024-08-23T08:51:47-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:48 volumio go-librespot[771]: time="2024-08-23T08:51:48-04:00" level=debug msg="completed challenge" Aug 23 08:51:48 volumio go-librespot[771]: time="2024-08-23T08:51:48-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:48 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:48 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:48 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:48 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:49 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:49 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:49 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:49 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:49 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:49 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:50 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:50 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:50 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:50 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:51 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:51 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:51 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:51 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117810. Aug 23 08:51:51 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:51 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:51 volumio go-librespot[778]: Librespot-go daemon starting... Aug 23 08:51:51 volumio go-librespot[778]: time="2024-08-23T08:51:51-04:00" level=info msg="generated new device id: f1c20c2590eeba32725f6284cd149997bf8a2377" Aug 23 08:51:51 volumio go-librespot[778]: time="2024-08-23T08:51:51-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:51 volumio go-librespot[778]: time="2024-08-23T08:51:51-04:00" level=debug msg="obtained new client token: AACYHtC0s4wG00sKwKJ7z7Kx8PTxmrHjty8XdodmuuWCS3vcZBzTq1PNFXb2z0KqruzCVecJ1+Iq8gJJk8FBTRFZYuse3pIk4yPMVzGIKKpGXPPmxfKYi9jxEDEkVlIqOfFtANvt6frIStKwYH7jQJWwpKcTDlp8j+jE2cSdW1EFdX0DuNOuxQ4SRPlAVS/BQQT72/rHk/xb/vgBgov+UVScTT1zSFkuxT/TG9g61bF3FdsXKZSQhD/m9o1GCg==" Aug 23 08:51:51 volumio go-librespot[778]: time="2024-08-23T08:51:51-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:52 volumio go-librespot[778]: time="2024-08-23T08:51:52-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:52 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:52 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:52 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:52 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:52 volumio go-librespot[778]: time="2024-08-23T08:51:52-04:00" level=debug msg="completed challenge" Aug 23 08:51:52 volumio go-librespot[778]: time="2024-08-23T08:51:52-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:52 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:52 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:52 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:52 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:53 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:53 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:53 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:53 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:54 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:54 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:54 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:54 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:55 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:55 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:55 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:55 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:55 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:55 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:55 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:51:55 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117811. Aug 23 08:51:55 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:51:55 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:51:55 volumio go-librespot[785]: Librespot-go daemon starting... Aug 23 08:51:55 volumio go-librespot[785]: time="2024-08-23T08:51:55-04:00" level=info msg="generated new device id: 494f9d4da676eb9dd9bad8723739ff67b211b5d2" Aug 23 08:51:55 volumio go-librespot[785]: time="2024-08-23T08:51:55-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:51:56 volumio go-librespot[785]: time="2024-08-23T08:51:56-04:00" level=debug msg="obtained new client token: AAA3Yp7wYnnlLW4+C1G3fYv+pA9sHDzHaPtG6HipMH+IaxqNUEnWr1vJZgi52c6vZKpP8SBhJoithe24y9H8lVmpMVZnG4/6IFlCs8mEg08ve7CCD1Zf5aX63/MrC1rvAipDwj/h5dyT4uxWB7vMsIN/TpRB3vXHtL5m+G8O85nEhCqIOPzNxXgTdCCoTROpvvBby6Lioao5mlwKiVh0DFsig6S6jcJDPnkZwkhI4rtcMLPVGWqkrqiF95s=" Aug 23 08:51:56 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:56 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:56 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:56 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:56 volumio go-librespot[785]: time="2024-08-23T08:51:56-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:51:56 volumio go-librespot[785]: time="2024-08-23T08:51:56-04:00" level=debug msg="completed keyexchange" Aug 23 08:51:57 volumio go-librespot[785]: time="2024-08-23T08:51:57-04:00" level=debug msg="completed challenge" Aug 23 08:51:57 volumio go-librespot[785]: time="2024-08-23T08:51:57-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:51:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:51:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:51:57 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:57 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:57 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:57 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:58 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:58 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:58 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:58 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:58 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:51:58 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:51:59 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:59 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:51:59 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:51:59 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:00 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:00 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:00 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:00 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117812. Aug 23 08:52:00 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:00 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:00 volumio go-librespot[793]: Librespot-go daemon starting... Aug 23 08:52:00 volumio go-librespot[793]: time="2024-08-23T08:52:00-04:00" level=info msg="generated new device id: 11f0e3cb6e1412ba741547242e71699e84a69a84" Aug 23 08:52:00 volumio go-librespot[793]: time="2024-08-23T08:52:00-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:00 volumio go-librespot[793]: time="2024-08-23T08:52:00-04:00" level=debug msg="obtained new client token: AABWsHk0EfTbIp2JJWMI9Dr7od3rCe+taXeJSPG8m+LMtylCabK/z7CyP1Tw7Lxc1KLAzFDyvTFsII66xfX7VB5gOPV/dNCQnetgXFbNwgPqr6I4QvtOkZG00DQAcXZbF0j07R4PrCZGGjtPayljYtIW4/ox6dU4CYgNTeRqAAP6UeTMnlx7S7JQMpHQqNyKwWEc1BWq2Mp+Hvxw86ChBChJboZ9ONXBL0xIpDBi8U99RdOj2nanRLJEu66Ezw==" Aug 23 08:52:00 volumio go-librespot[793]: time="2024-08-23T08:52:00-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:52:01 volumio go-librespot[793]: time="2024-08-23T08:52:01-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:01 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:01 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:01 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:01 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:01 volumio go-librespot[793]: time="2024-08-23T08:52:01-04:00" level=debug msg="completed challenge" Aug 23 08:52:01 volumio go-librespot[793]: time="2024-08-23T08:52:01-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:01 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:01 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:02 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:02 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:02 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:02 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:03 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:03 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:03 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:03 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:04 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:04 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:04 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:04 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:04 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:04 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117813. Aug 23 08:52:04 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:04 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:04 volumio go-librespot[800]: Librespot-go daemon starting... Aug 23 08:52:04 volumio go-librespot[800]: time="2024-08-23T08:52:04-04:00" level=info msg="generated new device id: 0698fbc64857edd8b83e20cafe1d5023db8570e1" Aug 23 08:52:04 volumio go-librespot[800]: time="2024-08-23T08:52:04-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:05 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:05 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:05 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:05 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:05 volumio go-librespot[800]: time="2024-08-23T08:52:05-04:00" level=debug msg="obtained new client token: AACzQj4tDlky7Pu7YaKHTDvg4bP1PjmVh3QKGO3JArxB6pPOK7/I+MI489BE2aWAClEsW2irGyuA+eWMW4Vv055D6OSxe6mucRim3OekFqHH7kFXF1GX/8IcfGvPVAoWMgp8lqAyjHMx5ztUiD8qE7OXMWwOyn9Tca5qmCdNlBkmeIbGdXyym6y1WG6m8cNJZPDuh+if9U5Y0ggDIMtBpRKr3zUft4V3gKLiaNWzOSMwmj5mnlX/pyvnDxc=" Aug 23 08:52:05 volumio go-librespot[800]: time="2024-08-23T08:52:05-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Aug 23 08:52:05 volumio go-librespot[800]: time="2024-08-23T08:52:05-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:06 volumio go-librespot[800]: time="2024-08-23T08:52:06-04:00" level=debug msg="completed challenge" Aug 23 08:52:06 volumio go-librespot[800]: time="2024-08-23T08:52:06-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:06 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:06 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:06 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:06 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:07 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:07 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:07 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:07 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:07 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:07 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:08 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:08 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:08 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:08 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:09 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:09 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:09 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:09 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117814. Aug 23 08:52:09 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:09 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:09 volumio go-librespot[809]: Librespot-go daemon starting... Aug 23 08:52:09 volumio go-librespot[809]: time="2024-08-23T08:52:09-04:00" level=info msg="generated new device id: d1bef8f0bcef9fa33853483c14396586c286f97e" Aug 23 08:52:09 volumio go-librespot[809]: time="2024-08-23T08:52:09-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:09 volumio go-librespot[809]: time="2024-08-23T08:52:09-04:00" level=debug msg="obtained new client token: AAATmTw6IBn0iyS4z4uOaKGqiYtCVAb9b5MjrTr9gbYRQvS98nKztG+PPJWlqyiKaIuvN6LdwCAHdRl8emPnXdvcydVeG6uy+4GkPl3Ww2xNN18RnvvUce8WFkugbUQ102bNyiET6i7Su6Xc2dfTjuUvkjuUHQnN580hu2+osZdYB7o5KRki8bW4K0txDCJj+QCevy4JllWJa7qM3b1TwfKWsjdE63FSMGGUBnJL0Jn0TvdOUHzONRYfd65qNw==" Aug 23 08:52:09 volumio go-librespot[809]: time="2024-08-23T08:52:09-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:52:10 volumio go-librespot[809]: time="2024-08-23T08:52:10-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:10 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:10 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:10 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:10 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:10 volumio go-librespot[809]: time="2024-08-23T08:52:10-04:00" level=debug msg="completed challenge" Aug 23 08:52:10 volumio go-librespot[809]: time="2024-08-23T08:52:10-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:10 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:10 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:11 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:11 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:11 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:11 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:12 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:12 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:12 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:12 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:13 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:13 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:13 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:13 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:13 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:13 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117815. Aug 23 08:52:13 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:13 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:13 volumio go-librespot[816]: Librespot-go daemon starting... Aug 23 08:52:13 volumio go-librespot[816]: time="2024-08-23T08:52:13-04:00" level=info msg="generated new device id: 8c3a5a14319f4b5fadb99079b0e7bc6aeec55b01" Aug 23 08:52:13 volumio go-librespot[816]: time="2024-08-23T08:52:13-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:14 volumio go-librespot[816]: time="2024-08-23T08:52:14-04:00" level=debug msg="obtained new client token: AAA4OomHwGcT6VpFC2Rwk1mwUN69KOTAO6whrACV2dOPgjKrk9KNzvsVduTfAypnif24iVzYfbMqhSNoBZhgloq8VxbayiF67ZxhuOeepj+cz26V96T3wQFC6Qp6i/KU5mhVDTbl6VeADB5ZmiAUu4e7NOCbrlnUNq4QMp23RIy/U6tMukvBHGusRc2Z6qfaSd0iuASrvUscxDXhKg360sAKmAxXY46VUSAWR6z8fmqJrUykH5p3NJFV96M=" Aug 23 08:52:14 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:14 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:14 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:14 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:14 volumio go-librespot[816]: time="2024-08-23T08:52:14-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:52:14 volumio go-librespot[816]: time="2024-08-23T08:52:14-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:15 volumio go-librespot[816]: time="2024-08-23T08:52:15-04:00" level=debug msg="completed challenge" Aug 23 08:52:15 volumio go-librespot[816]: time="2024-08-23T08:52:15-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:15 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:15 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:15 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:15 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:16 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:16 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:16 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:16 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:16 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:16 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:17 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:17 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:17 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:17 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:18 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:18 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:18 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:18 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117816. Aug 23 08:52:18 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:18 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:18 volumio go-librespot[823]: Librespot-go daemon starting... Aug 23 08:52:18 volumio go-librespot[823]: time="2024-08-23T08:52:18-04:00" level=info msg="generated new device id: 5db3af45b26368661320e8edf376b320cde3d0f3" Aug 23 08:52:18 volumio go-librespot[823]: time="2024-08-23T08:52:18-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:18 volumio go-librespot[823]: time="2024-08-23T08:52:18-04:00" level=debug msg="obtained new client token: AADUM1+BM5BhnGKiA7FqZYKtE34CaMv7xtx7EsZ/wtz7h6QzsKXPR8TpykWitVFCA2Y7oTsNUPVJy7Cnmqg+8W6b0Hpjxg4glqW5W026w5HIfqTAwJSbRlLQ+rWwIpVsFz3bcnIif2Uq1V3rXUp/wmsCwPDnMP5ORSiWxQjD1iIOaBMftKa8xtY0+kGYS4Rb211u5t/b2fxumrGC1RTPcz9BKbxtpEwCpoB/ZfvLet4T4zgEP83BbHaHplNkvQ==" Aug 23 08:52:18 volumio go-librespot[823]: time="2024-08-23T08:52:18-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:52:19 volumio go-librespot[823]: time="2024-08-23T08:52:19-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:19 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:19 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:19 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:19 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:19 volumio go-librespot[823]: time="2024-08-23T08:52:19-04:00" level=debug msg="completed challenge" Aug 23 08:52:19 volumio go-librespot[823]: time="2024-08-23T08:52:19-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:19 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:19 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:20 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:20 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:20 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:20 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:21 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:21 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:21 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:21 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:22 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:22 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:22 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:22 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:22 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:22 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117817. Aug 23 08:52:22 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:22 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:22 volumio go-librespot[830]: Librespot-go daemon starting... Aug 23 08:52:22 volumio go-librespot[830]: time="2024-08-23T08:52:22-04:00" level=info msg="generated new device id: efd1e005262698dacdc686e5125c7d9d6794e255" Aug 23 08:52:22 volumio go-librespot[830]: time="2024-08-23T08:52:22-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:23 volumio go-librespot[830]: time="2024-08-23T08:52:23-04:00" level=debug msg="obtained new client token: AABxcPvro6Pc44wbS2h6h+sO2m2AyOsD5kHTjTvmDAdh/2eSi7KQlrVDKkd0Ff6mmQwd2qON4L0oAeLh22uAjGSGe1i0J6MisHPBogkLPxlhwMhakR/KynrK7co5E1s72Z1uw5vOpo/+Csei7LD4/JOqZq9t3UQR9ViYYMCpprsE9Aa3SzyAE16YirpukHdJnDtK17BusgeGIi4awL26tYvjpBmDg5DsmUOGQYi6UGv1ImLFhDvkyvG39ps=" Aug 23 08:52:23 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:23 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:23 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:23 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:23 volumio go-librespot[830]: time="2024-08-23T08:52:23-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:52:23 volumio volumio[5502]: info: Aug 23 08:52:23 volumio volumio[5502]: ---------------------------- MPD announces system playlist update Aug 23 08:52:23 volumio volumio[5502]: info: Ignoring MPD Status Update Aug 23 08:52:23 volumio volumio[5502]: info: Aug 23 08:52:23 volumio volumio[5502]: ---------------------------- MPD announces state update: player Aug 23 08:52:23 volumio volumio[5502]: info: ControllerMpd::getState Aug 23 08:52:23 volumio volumio[5502]: verbose: ControllerMpd::sendMpdCommand status Aug 23 08:52:23 volumio volumio[5502]: info: ------------------------------ 2ms Aug 23 08:52:23 volumio volumio[5502]: info: sendMpdCommand status took 1 milliseconds Aug 23 08:52:23 volumio volumio[5502]: verbose: ControllerMpd::parseState Aug 23 08:52:23 volumio volumio[5502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 08:52:23 volumio volumio[5502]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 08:52:23 volumio volumio[5502]: verbose: ControllerMpd::parseTrackInfo Aug 23 08:52:23 volumio volumio[5502]: info: ControllerMpd::pushState Aug 23 08:52:23 volumio volumio[5502]: info: CoreCommandRouter::servicePushState Aug 23 08:52:23 volumio volumio[5502]: info: CorePlayQueue::getTrack 0 Aug 23 08:52:23 volumio volumio[5502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39040197,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO 1 ROCK - ЕДНО РОК РАДИО","artist":"RADIO 1 ROCK","album":null,"uri":"http://149.13.0.81/radio1rock128","trackType":""} Aug 23 08:52:23 volumio volumio[5502]: verbose: CURRENT POSITION 0 Aug 23 08:52:23 volumio volumio[5502]: info: CoreStateMachine::syncState stateService play Aug 23 08:52:23 volumio volumio[5502]: info: CoreStateMachine::syncState currentStatus play Aug 23 08:52:23 volumio volumio[5502]: info: Received an update from plugin. extracting info from payload Aug 23 08:52:23 volumio volumio[5502]: info: CoreStateMachine::pushState Aug 23 08:52:23 volumio volumio[5502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:52:23 volumio volumio[5502]: info: CoreCommandRouter::volumioPushState Aug 23 08:52:23 volumio go-librespot[830]: time="2024-08-23T08:52:23-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:23 volumio volumio[5502]: info: CoreStateMachine::pushState Aug 23 08:52:23 volumio volumio[5502]: info: CoreCommandRouter::volumioPushState Aug 23 08:52:23 volumio volumio[5502]: info: ------------------------------ 42ms Aug 23 08:52:23 volumio volumio[5502]: info: [LastFM] Split composite title into artist: RADIO 1 ROCK and title: ЕДНО РОК РАДИО Aug 23 08:52:23 volumio volumio[5502]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"ЕДНО РОК РАДИО","url":"https://www.last.fm/music/Radio+1+Rock/_/%D0%95%D0%94%D0%9D%D0%9E+%D0%A0%D0%9E%D0%9A+%D0%A0%D0%90%D0%94%D0%98%D0%9E","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"48","playcount":"27171","artist":{"name":"Radio 1 Rock","url":"https://www.last.fm/music/Radio+1+Rock"},"userplaycount":"2039","userloved":"0","toptags":""}} Aug 23 08:52:23 volumio volumio[5502]: info: [LastFM] Updated missing track duration: 0 Aug 23 08:52:24 volumio go-librespot[830]: time="2024-08-23T08:52:24-04:00" level=debug msg="completed challenge" Aug 23 08:52:24 volumio go-librespot[830]: time="2024-08-23T08:52:24-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:24 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:24 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:24 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:24 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:25 volumio volumio[5502]: info: Aug 23 08:52:25 volumio volumio[5502]: ---------------------------- MPD announces system playlist update Aug 23 08:52:25 volumio volumio[5502]: info: Ignoring MPD Status Update Aug 23 08:52:25 volumio volumio[5502]: info: Aug 23 08:52:25 volumio volumio[5502]: ---------------------------- MPD announces state update: player Aug 23 08:52:25 volumio volumio[5502]: info: ControllerMpd::getState Aug 23 08:52:25 volumio volumio[5502]: verbose: ControllerMpd::sendMpdCommand status Aug 23 08:52:25 volumio volumio[5502]: info: ------------------------------ 2ms Aug 23 08:52:25 volumio volumio[5502]: info: sendMpdCommand status took 1 milliseconds Aug 23 08:52:25 volumio volumio[5502]: verbose: ControllerMpd::parseState Aug 23 08:52:25 volumio volumio[5502]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 08:52:25 volumio volumio[5502]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 08:52:25 volumio volumio[5502]: verbose: ControllerMpd::parseTrackInfo Aug 23 08:52:25 volumio volumio[5502]: info: ControllerMpd::pushState Aug 23 08:52:25 volumio volumio[5502]: info: CoreCommandRouter::servicePushState Aug 23 08:52:25 volumio volumio[5502]: info: CorePlayQueue::getTrack 0 Aug 23 08:52:25 volumio volumio[5502]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39041683,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RONNIE JAMES DIO - WE ROCK","artist":"RADIO 1 ROCK","album":null,"uri":"http://149.13.0.81/radio1rock128","trackType":""} Aug 23 08:52:25 volumio volumio[5502]: verbose: CURRENT POSITION 0 Aug 23 08:52:25 volumio volumio[5502]: info: CoreStateMachine::syncState stateService play Aug 23 08:52:25 volumio volumio[5502]: info: CoreStateMachine::syncState currentStatus play Aug 23 08:52:25 volumio volumio[5502]: info: Received an update from plugin. extracting info from payload Aug 23 08:52:25 volumio volumio[5502]: info: CoreStateMachine::pushState Aug 23 08:52:25 volumio volumio[5502]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 08:52:25 volumio volumio[5502]: info: CoreCommandRouter::volumioPushState Aug 23 08:52:25 volumio volumio[5502]: info: CoreStateMachine::pushState Aug 23 08:52:25 volumio volumio[5502]: info: CoreCommandRouter::volumioPushState Aug 23 08:52:25 volumio volumio[5502]: info: ------------------------------ 23ms Aug 23 08:52:25 volumio volumio[5502]: info: [LastFM] Split composite title into artist: RONNIE JAMES DIO and title: WE ROCK Aug 23 08:52:25 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:25 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:25 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:25 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:25 volumio volumio[5502]: info: Initializing connection to go-librespot Websocket Aug 23 08:52:25 volumio volumio[5502]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Aug 23 08:52:26 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:26 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:26 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:26 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:27 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:27 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:27 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:27 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:27 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:27 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117818. Aug 23 08:52:27 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:27 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:27 volumio go-librespot[854]: Librespot-go daemon starting... Aug 23 08:52:27 volumio go-librespot[854]: time="2024-08-23T08:52:27-04:00" level=info msg="generated new device id: e9c92de90c63ba078c77559c3d9f3b1e07d9c414" Aug 23 08:52:27 volumio go-librespot[854]: time="2024-08-23T08:52:27-04:00" level=debug msg="stored credentials found for maximtms" Aug 23 08:52:27 volumio go-librespot[854]: time="2024-08-23T08:52:27-04:00" level=debug msg="obtained new client token: AACR6Evqxeb38alZe/9jqELkBivoONvN1qeMbgAQZs3iTLSybAhWyxKO9Mf/OFmWubVF4zPccnRpyBoVIveL8t3ha+PvWMv/9OcNzVNbWoiR50M9G6oUKsKEo2ZtJxH13tIOJvJvZW3zv7tWOdmIbQZaypynQ0cgYM1/Y0ET8BjoZ+CmY+MRLU8fL4B4LT78FiGfkUEDneijjdqIboIokoDNIWlARiJ7joJSDMpYv9y3Emm3JL70vAVWdhdx9A==" Aug 23 08:52:27 volumio go-librespot[854]: time="2024-08-23T08:52:27-04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Aug 23 08:52:28 volumio go-librespot[854]: time="2024-08-23T08:52:28-04:00" level=debug msg="completed keyexchange" Aug 23 08:52:28 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:28 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:28 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:28 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:28 volumio volumio[5502]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 08:52:28 volumio volumio[5502]: TypeError: Cannot read property '@' of null Aug 23 08:52:28 volumio volumio[5502]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Aug 23 08:52:28 volumio volumio[5502]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Aug 23 08:52:28 volumio volumio[5502]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:304:18) Aug 23 08:52:28 volumio volumio[5502]: at Parser.emit (events.js:315:20) Aug 23 08:52:28 volumio volumio[5502]: at Parser.exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:314:16) Aug 23 08:52:28 volumio volumio[5502]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Aug 23 08:52:28 volumio volumio[5502]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Aug 23 08:52:28 volumio volumio[5502]: at IncomingMessage.emit (events.js:327:22) Aug 23 08:52:28 volumio volumio[5502]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 23 08:52:28 volumio volumio[5502]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 23 08:52:28 volumio volumio[5502]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 08:52:28 volumio go-librespot[854]: time="2024-08-23T08:52:28-04:00" level=debug msg="completed challenge" Aug 23 08:52:28 volumio go-librespot[854]: time="2024-08-23T08:52:28-04:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: TravelRestriction" Aug 23 08:52:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Aug 23 08:52:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Aug 23 08:52:29 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:29 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:29 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:29 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:30 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:30 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:30 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:30 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:31 volumio lircd[5973]: lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:31 volumio lircd[5973]: lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:31 volumio lircd-0.10.1[5973]: Error: could not get file information for /dev/lirc0 Aug 23 08:52:31 volumio lircd-0.10.1[5973]: default_init(): No such file or directory Aug 23 08:52:31 volumio sudo[895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 08:51 Aug 23 08:52:31 volumio sudo[895]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 08:52:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Aug 23 08:52:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 117819. Aug 23 08:52:31 volumio systemd[1]: Stopped go-librespot Daemon. Aug 23 08:52:31 volumio systemd[1]: Started go-librespot Daemon. Aug 23 08:52:32 volumio go-librespot[897]: Librespot-go daemon starting... 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="f8baf7ad070f376535a93fcc6590774528ddb153" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="0e3cf2fcfe1906c5090fab62b4cc2a5ff0069fd8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 30 Jan 2024 03:58:37 PM CET" VOLUMIO_VERSION="3.611" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1d6379ebb89c40061afa8aee78176887"