-- Logs begin at Mon 2024-04-29 11:14:02 PDT, end at Mon 2024-04-29 12:38:41 PDT. -- Apr 29 12:37:00 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:00 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:00 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:00 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686488. Apr 29 12:37:01 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:01 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:01 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:01 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:01 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:01 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:02 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:02 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Apr 29 12:37:02 volumio volumio[1225]: info: CoreStateMachine::pushState Apr 29 12:37:02 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 12:37:02 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 29 12:37:02 volumio volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 29 12:37:02 volumio volumio[1225]: info: MRS: Pushing multiroomSync output Apr 29 12:37:02 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:02 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:02 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:02 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:02 volumio go-librespot[1162]: Librespot-go daemon starting... Apr 29 12:37:03 volumio go-librespot[1162]: time="2024-04-29T12:37:03-07:00" level=info msg="generated new device id: 215aa683df090eccd49bfdf877f047f9d26b35b1" Apr 29 12:37:03 volumio go-librespot[1162]: time="2024-04-29T12:37:03-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:03 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:03 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:03 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:03 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:03 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:37:03 volumio vtcs[2290]: [2024-04-29 12:37:03] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 29 12:37:04 volumio go-librespot[1162]: time="2024-04-29T12:37:04-07:00" level=debug msg="obtained new client token: AACsCmM7YuOlxgEo+EMp3cRoyIUk57z+hZ3JIk3G3LKB8vuk0EWAiWFOwcxsX01mzkjWazxpYUC7NIGbPeBoyT+s04ptBOwIWI+Sa+c4RUmtIJXWsx86jlZtPR7lOUCxEdjROFX0EAi+hwsGzERIFJc+R+Rl1LuIGFp3SjLQNl6E4UgKN0+CK04R9Tkcl+oSUUs5get6Tw0F+nNkMLkAgIzz9ddY7bZnBJ+Dps6s3BEzF35s9Lijg2h4QVmExlY=" Apr 29 12:37:04 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:04 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:04 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:04 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:04 volumio go-librespot[1162]: time="2024-04-29T12:37:04-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:37:05 volumio go-librespot[1162]: time="2024-04-29T12:37:05-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:05 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:05 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:05 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:05 volumio go-librespot[1162]: time="2024-04-29T12:37:05-07:00" level=debug msg="completed challenge" Apr 29 12:37:05 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:05 volumio go-librespot[1162]: time="2024-04-29T12:37:05-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:05 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:06 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:06 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:06 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:06 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:07 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:07 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:07 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:07 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:07 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:08 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:08 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:08 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:08 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686489. Apr 29 12:37:09 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:09 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:09 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:09 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:09 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:09 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:10 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:10 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:10 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:10 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:11 volumio go-librespot[1212]: Librespot-go daemon starting... Apr 29 12:37:11 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:11 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:11 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:11 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:12 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:12 volumio go-librespot[1212]: time="2024-04-29T12:37:11-07:00" level=info msg="generated new device id: 757f16404fcb799e0a63acdfafd51d8e97e47f56" Apr 29 12:37:12 volumio go-librespot[1212]: time="2024-04-29T12:37:12-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:12 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:12 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:12 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:12 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:12 volumio go-librespot[1212]: time="2024-04-29T12:37:12-07:00" level=debug msg="new websocket client" Apr 29 12:37:13 volumio volumio[1225]: info: Connection to go-librespot Websocket established Apr 29 12:37:13 volumio go-librespot[1212]: time="2024-04-29T12:37:13-07:00" level=debug msg="obtained new client token: AACf3Mqg30FTEvqx7RsDIHFGapmqIvtvO/emEFBU9NSiR50aiMKm2SpVp6uuDkFKAAO2cARuQZXyaApN0LKov6jKDOcJ+ZVjYWG7kQGdGAeCxZTPF2aJ7gxxxeQqX0uECAo1b98tlgBGOslV0PkkJXHjvJZxnin/wJJg8Qb3pcb0R3eHU23NyYJKQkjyr2TKfVOCWQaeqYkEjG2SShMlhSJ5IKxwQofI/KrYKqI5vg7Q/y4OR4Uyj8mFYj0SnDU=" Apr 29 12:37:13 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:13 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:13 volumio go-librespot[1212]: time="2024-04-29T12:37:13-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 29 12:37:13 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:13 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:13 volumio go-librespot[1212]: time="2024-04-29T12:37:13-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:14 volumio go-librespot[1212]: time="2024-04-29T12:37:14-07:00" level=debug msg="completed challenge" Apr 29 12:37:14 volumio go-librespot[1212]: time="2024-04-29T12:37:14-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:14 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:14 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:14 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:14 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:15 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:15 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:15 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:15 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:15 volumio volumio[1225]: info: Connection to go-librespot Websocket closed Apr 29 12:37:16 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:16 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:16 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:16 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:17 volumio volumio[1225]: info: Getting Spotify volume Apr 29 12:37:17 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:17 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:17 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:17 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:17 volumio volumio[1225]: (node:1225) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:17 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 29 12:37:17 volumio volumio[1225]: (node:1225) 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: 268046) Apr 29 12:37:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686490. Apr 29 12:37:18 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:18 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:18 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 29 12:37:18 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:18 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:18 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:18 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:19 volumio go-librespot[1222]: Librespot-go daemon starting... Apr 29 12:37:19 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:19 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:19 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:19 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:19 volumio go-librespot[1222]: time="2024-04-29T12:37:19-07:00" level=info msg="generated new device id: bb2dd28623ab0c931c2149da89550eef162030f1" Apr 29 12:37:19 volumio go-librespot[1222]: time="2024-04-29T12:37:19-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:20 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:20 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:20 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:20 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:20 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:20 volumio go-librespot[1222]: time="2024-04-29T12:37:20-07:00" level=debug msg="obtained new client token: AAB4NCx24Tkb5ysnru8ydrGU5DKG1IFAJfYcCmACPyiX+R8Vbw8Tny0debC6CYC7C0oVCJlJufdbYogQZGD2g3GPTGOVlEuydbd5UMohVmHlGLOCrL365nE8v16jLEiQsk8mLPJlT0jrGx7vuCa4wchKgXnvHtY4ekwEnzNN2O/lFagBXFkaD/KrG4TTcGvqfc04mcqJQsgjk244Uhj8m+cm06Cwh4Ib3eqZNTmhQv0LILy07IRjgMxa5KEuqcI=" Apr 29 12:37:21 volumio go-librespot[1222]: time="2024-04-29T12:37:21-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 29 12:37:21 volumio go-librespot[1222]: time="2024-04-29T12:37:21-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:21 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:21 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:21 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:21 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:22 volumio go-librespot[1222]: time="2024-04-29T12:37:22-07:00" level=debug msg="completed challenge" Apr 29 12:37:22 volumio go-librespot[1222]: time="2024-04-29T12:37:22-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:22 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:22 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:22 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:22 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:22 volumio vtcs[2290]: [2024-04-29 12:37:22] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 29 12:37:23 volumio volumio[1225]: info: Pushing metadata Apr 29 12:37:23 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Apr 29 12:37:23 volumio volumio[1225]: info: CoreStateMachine::pushState Apr 29 12:37:23 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 29 12:37:23 volumio volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 29 12:37:23 volumio volumio[1225]: info: MRS: Pushing multiroomSync output Apr 29 12:37:23 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Apr 29 12:37:23 volumio volumio[1225]: info: CoreStateMachine::pushState Apr 29 12:37:23 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 29 12:37:23 volumio volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 29 12:37:23 volumio volumio[1225]: info: MRS: Pushing multiroomSync output Apr 29 12:37:23 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:23 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:23 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:23 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:24 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:24 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:24 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:24 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:24 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:25 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:25 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:25 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:25 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686491. Apr 29 12:37:25 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:26 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:37:26 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:26 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:37:26 volumio go-librespot[1241]: Librespot-go daemon starting... Apr 29 12:37:26 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:26 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:26 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:26 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:26 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:37:27 volumio go-librespot[1241]: time="2024-04-29T12:37:27-07:00" level=info msg="generated new device id: 3bf4af932385d130b4bfcf86d97a1b7d94a6dc79" Apr 29 12:37:27 volumio go-librespot[1241]: time="2024-04-29T12:37:27-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:27 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:27 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:27 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:27 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:27 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:27 volumio go-librespot[1241]: time="2024-04-29T12:37:27-07:00" level=debug msg="new websocket client" Apr 29 12:37:27 volumio volumio[1225]: info: Connection to go-librespot Websocket established Apr 29 12:37:28 volumio go-librespot[1241]: time="2024-04-29T12:37:28-07:00" level=debug msg="obtained new client token: AACQA69rdGqvDCfHXt3sM6ZVAyGQ9BQjPGDALnFPwI81Rz4uTC0GcfqqTlJh2bGUMFvhwXXADuR9t3H3GjFE3gDIdz0C9aGg47g7n7+IPxlzU8Uk80KqzoCsFijkz7sWtqKWNjadrBPkRjK8qq6vi0qoEffTdSSap4Jl/83ASU4MPSs6gqhI0wcWuvKXaUIosKYv8WPn31zgCwpmQpwxMKIlgfeNyuFG0GRVZsKkuEAOhkA4uVjA0aYdFbzx" Apr 29 12:37:28 volumio go-librespot[1241]: time="2024-04-29T12:37:28-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:37:28 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:28 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:28 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:28 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:28 volumio go-librespot[1241]: time="2024-04-29T12:37:28-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:29 volumio go-librespot[1241]: time="2024-04-29T12:37:29-07:00" level=debug msg="completed challenge" Apr 29 12:37:29 volumio go-librespot[1241]: time="2024-04-29T12:37:29-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:29 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:29 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:29 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:29 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:30 volumio volumio[1225]: info: Connection to go-librespot Websocket closed Apr 29 12:37:30 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:30 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:30 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:30 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:31 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:31 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:31 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:31 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:32 volumio volumio[1225]: info: Getting Spotify volume Apr 29 12:37:32 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:32 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:32 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:32 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:33 volumio volumio[1225]: info: Pushing metadata Apr 29 12:37:33 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Apr 29 12:37:33 volumio volumio[1225]: info: CoreStateMachine::pushState Apr 29 12:37:33 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 29 12:37:33 volumio volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 29 12:37:33 volumio volumio[1225]: info: MRS: Pushing multiroomSync output Apr 29 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:33 volumio volumio[1225]: info: CoreCommandRouter::servicePushState Apr 29 12:37:33 volumio volumio[1225]: info: CoreStateMachine::pushState Apr 29 12:37:33 volumio volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 29 12:37:33 volumio volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 29 12:37:33 volumio volumio[1225]: info: MRS: Pushing multiroomSync output Apr 29 12:37:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686492. Apr 29 12:37:33 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:33 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:33 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:33 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:33 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:33 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:34 volumio volumio[1225]: (node:1225) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:34 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 29 12:37:34 volumio volumio[1225]: (node:1225) 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: 268047) Apr 29 12:37:34 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:34 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:34 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:34 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:34 volumio go-librespot[1249]: Librespot-go daemon starting... Apr 29 12:37:35 volumio go-librespot[1249]: time="2024-04-29T12:37:35-07:00" level=info msg="generated new device id: 67ca41586ec037ae02ff1950171dcfde22e3103d" Apr 29 12:37:35 volumio go-librespot[1249]: time="2024-04-29T12:37:35-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:35 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:35 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:35 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:35 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:35 volumio vtcs[2290]: [2024-04-29 12:37:35] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 29 12:37:36 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:36 volumio vtcs[2290]: [2024-04-29 12:37:36.320] [tisoc] [warning] [PlaybackControllerImpl.cpp:501] Illegal state:0 Apr 29 12:37:36 volumio volumio[1225]: info: PlaybackInactive Apr 29 12:37:36 volumio volumio[1225]: info: Device Session is_active: true Apr 29 12:37:36 volumio volumio[1225]: info: DeviceInactive Apr 29 12:37:36 volumio volumio[1225]: info: Relinquishing Volumio State Apr 29 12:37:36 volumio volumio[1225]: verbose: UNSET VOLATILE: Service: tidalconnect Apr 29 12:37:36 volumio volumio[1225]: info: unSetVolatile called Apr 29 12:37:36 volumio volumio[1225]: info: Relinquishing Volumio State to another service Apr 29 12:37:36 volumio vtcs[2290]: [2024-04-29 12:37:36.640] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Apr 29 12:37:36 volumio go-librespot[1249]: time="2024-04-29T12:37:36-07:00" level=debug msg="obtained new client token: AACe8/3YIY/W/g71VMK5RKBVBvQjmwlAhgQcZLa+ruiZt/OFb1xyGcQnNHJKGOyZo1PT0uSM1ldc8ciXI4MpbqR7zZqJnuI0iuH0VjY0dSaWL9lqmzSHo05MbtMgvDsTjaU1Myzc9JHt9iTLQGJqfc+3docF0h6+C6l5Mt5x8bnagNBgwIQ7npxG3tWkDmsKwP1oSduzOGNQ5G9MZxHE4jVp7XqljDPlcH3DfD81uPoUyekP/h2LvH98qvPwASk=" Apr 29 12:37:36 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:36 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:36 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:36 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:36 volumio go-librespot[1249]: time="2024-04-29T12:37:36-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 29 12:37:37 volumio go-librespot[1249]: time="2024-04-29T12:37:37-07:00" level=debug msg="new websocket client" Apr 29 12:37:37 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 29 12:37:37 volumio go-librespot[1249]: time="2024-04-29T12:37:37-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:37 volumio volumio[1225]: info: Connection to go-librespot Websocket established Apr 29 12:37:37 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:37 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:37 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:37 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:37 volumio go-librespot[1249]: time="2024-04-29T12:37:37-07:00" level=debug msg="completed challenge" Apr 29 12:37:38 volumio go-librespot[1249]: time="2024-04-29T12:37:38-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:38 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:38 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:38 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:38 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:38 volumio volumio[1225]: info: Connection to go-librespot Websocket closed Apr 29 12:37:38 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:37:38 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:37:39 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:37:39 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:39 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:39 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:39 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:40 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:40 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:40 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:40 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:41 volumio volumio[1225]: info: Getting Spotify volume Apr 29 12:37:41 volumio volumio[1225]: (node:1225) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:41 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 29 12:37:41 volumio volumio[1225]: (node:1225) 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: 268048) Apr 29 12:37:41 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 29 12:37:41 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:41 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:41 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:41 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:41 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:41 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:41 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:37:41 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:37:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686493. Apr 29 12:37:41 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:41 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:42 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:37:42 volumio go-librespot[1261]: Librespot-go daemon starting... Apr 29 12:37:42 volumio go-librespot[1261]: time="2024-04-29T12:37:42-07:00" level=info msg="generated new device id: dc2f3109f2ce106a5f48871a3cfcbf6077383ab5" Apr 29 12:37:42 volumio go-librespot[1261]: time="2024-04-29T12:37:42-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:42 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:42 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:42 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:42 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:42 volumio go-librespot[1261]: time="2024-04-29T12:37:42-07:00" level=debug msg="obtained new client token: AAC64FTo8KCKFxbaim0phOjq48RM7fysO1bt6VhJNLsC7kQjCDgi2vNX18E+ri8S1fHjTVJytBURMX502dwBXUaOkMUFYUq1noI2t5qFVZ1jbeqBdGoz60q3D+Nx3XOAA9A9eqklYqoC/2Z+arVR7Srcow2vkJqyjWaLP6MWnpgnCJrsaed1jegrY9a//diTdoqltzR6CT1zqY3kCodecS3FvVWTHcHaK+h0hQug9D3xJarlGTxd8w3EAOcT73E=" Apr 29 12:37:43 volumio go-librespot[1261]: time="2024-04-29T12:37:43-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:37:43 volumio go-librespot[1261]: time="2024-04-29T12:37:43-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:43 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:43 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:43 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:43 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:43 volumio go-librespot[1261]: time="2024-04-29T12:37:43-07:00" level=debug msg="completed challenge" Apr 29 12:37:43 volumio go-librespot[1261]: time="2024-04-29T12:37:43-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:44 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:44 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:44 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:44 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:45 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:45 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:45 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:45 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:45 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:45 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:46 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:46 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:46 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:46 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686494. Apr 29 12:37:47 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:47 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:47 volumio go-librespot[1271]: Librespot-go daemon starting... Apr 29 12:37:47 volumio go-librespot[1271]: time="2024-04-29T12:37:47-07:00" level=info msg="generated new device id: 8b266b11bdaa37225b3e536776a42d72791aa525" Apr 29 12:37:47 volumio go-librespot[1271]: time="2024-04-29T12:37:47-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:47 volumio go-librespot[1271]: time="2024-04-29T12:37:47-07:00" level=debug msg="obtained new client token: AAB3b9m8rNi7DahX85M5LCahrkHmyrTmCbSvcajuT2xEuT3psKALfGDI2wmEwgYUQYcXpxpOuSMWkQd7MRA8/xxgHA07C8QwfGoaND1tYRlg9efHacN64taBhnEhE3K02yDGdblQPugzGQWig1oII6LQtyLt/VGPy/rWsvEYIs6kVXcBYt9NGS7FuaKM/QDiy4V5keM+U8TpOjBVGDW0jGUJ+2xHzqF2zuAt9EyEpEK3lDqNHjAgAoCkuNWwx4Q=" Apr 29 12:37:47 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:47 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:47 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:47 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:47 volumio go-librespot[1271]: time="2024-04-29T12:37:47-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 29 12:37:48 volumio go-librespot[1271]: time="2024-04-29T12:37:48-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:48 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:48 volumio go-librespot[1271]: time="2024-04-29T12:37:48-07:00" level=debug msg="new websocket client" Apr 29 12:37:48 volumio volumio[1225]: info: Connection to go-librespot Websocket established Apr 29 12:37:48 volumio go-librespot[1271]: time="2024-04-29T12:37:48-07:00" level=debug msg="completed challenge" Apr 29 12:37:48 volumio go-librespot[1271]: time="2024-04-29T12:37:48-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:48 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:48 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:48 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:48 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:48 volumio volumio[1225]: info: Connection to go-librespot Websocket closed Apr 29 12:37:49 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:49 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:49 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:49 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:50 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:50 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:50 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:50 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:51 volumio volumio[1225]: info: Getting Spotify volume Apr 29 12:37:51 volumio volumio[1225]: (node:1225) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:51 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 29 12:37:51 volumio volumio[1225]: (node:1225) 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: 268049) Apr 29 12:37:51 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 29 12:37:51 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:37:51 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:37:51 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:37:51 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:51 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:51 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:51 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:51 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:51 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686495. Apr 29 12:37:51 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:51 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:51 volumio go-librespot[1286]: Librespot-go daemon starting... Apr 29 12:37:51 volumio go-librespot[1286]: time="2024-04-29T12:37:51-07:00" level=info msg="generated new device id: f5e662a75abbc521f8bea5012de63867698025b2" Apr 29 12:37:51 volumio go-librespot[1286]: time="2024-04-29T12:37:51-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:52 volumio go-librespot[1286]: time="2024-04-29T12:37:52-07:00" level=debug msg="obtained new client token: AAAp+k16sseBq8whDm9IRGRxzirzmnb64aPmcOK80NvzwBZ1eWbxeYd10WNC5gZomzBNbDoO7rMD6QKK5Z/z2LRBduCED5tTamnt/5fg1OgSpU1lzfGLnXuxEq7/qsSoofXmCF0K7O1rNAXtFFhdrI8xlTiofVC1BuYJFGitRvIsuE5lR07O6zsQJJCX0ka3RzNBzXfh5Y96/nJnCBINjPOG8faR7dn1Jv5+7ouhNwj0HxnHGsd9YN4yIGt7" Apr 29 12:37:52 volumio go-librespot[1286]: time="2024-04-29T12:37:52-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:37:52 volumio go-librespot[1286]: time="2024-04-29T12:37:52-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:52 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:52 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:52 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:52 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:53 volumio go-librespot[1286]: time="2024-04-29T12:37:53-07:00" level=debug msg="completed challenge" Apr 29 12:37:53 volumio go-librespot[1286]: time="2024-04-29T12:37:53-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:53 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:53 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:53 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:53 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:54 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:54 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:54 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:54 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:54 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:54 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:55 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:55 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:55 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:55 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:37:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686496. Apr 29 12:37:56 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:37:56 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:37:56 volumio go-librespot[1298]: Librespot-go daemon starting... Apr 29 12:37:56 volumio go-librespot[1298]: time="2024-04-29T12:37:56-07:00" level=info msg="generated new device id: 1bb39073440c144a379a44b28c5f2ead5aa7dde1" Apr 29 12:37:56 volumio go-librespot[1298]: time="2024-04-29T12:37:56-07:00" level=debug msg="stored credentials not found" Apr 29 12:37:56 volumio go-librespot[1298]: time="2024-04-29T12:37:56-07:00" level=debug msg="obtained new client token: AACpV3fAmm2UFKhkNT6JGpxOm29N6iKDGGE83C6M4MqM8dOIjO1/o/wikK1LbRKxEfmreS7XYmlaTnGogTxzCh5qlmvhXExOtpY4xWPW67BkXbPFgRUjkMmZaGipWzKrG4uM70MzgvyLhaPhVxdxaMC8piroYygitOekvAs1hsC5rYY3ZDWYioMx1+O97evaT2zocbx+wLnGkgjELeNaHK/zYDEH9emya/kbHCNoFp78njS38wChDrI38Wje7GA=" Apr 29 12:37:56 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:56 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:56 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:56 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:56 volumio go-librespot[1298]: time="2024-04-29T12:37:56-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:37:57 volumio go-librespot[1298]: time="2024-04-29T12:37:57-07:00" level=debug msg="completed keyexchange" Apr 29 12:37:57 volumio go-librespot[1298]: time="2024-04-29T12:37:57-07:00" level=debug msg="completed challenge" Apr 29 12:37:57 volumio go-librespot[1298]: time="2024-04-29T12:37:57-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:37:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:37:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:37:57 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:57 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:57 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:57 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:57 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:37:57 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:37:58 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:58 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:58 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:58 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:59 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:59 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:37:59 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:37:59 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:00 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:00 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:00 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:00 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:00 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:00 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:00 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:00 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686497. Apr 29 12:38:00 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:00 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:00 volumio go-librespot[1312]: Librespot-go daemon starting... Apr 29 12:38:00 volumio go-librespot[1312]: time="2024-04-29T12:38:00-07:00" level=info msg="generated new device id: 9ef02180d14960fec929c2daf1b11ebf6439a85a" Apr 29 12:38:00 volumio go-librespot[1312]: time="2024-04-29T12:38:00-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:01 volumio go-librespot[1312]: time="2024-04-29T12:38:01-07:00" level=debug msg="obtained new client token: AACl3tYeLd0679scs8KAQishlhHUzy0z6B4Qo3wBXoTfIAXsxMG90dyU/KpsJTybRkH25Pw0gHjZkjD5li6WlELGIBmSn39tc/4g6WVonWo2xG1DLIQ2fRYaGBvp526gi/sPiWUCP21D1WvD+UsQZ79doMUkpVBDwjMSfYMBq9yDzM1798nItPf3MJxyw1TcmXLKbQaM3iriz0NJn9VpM3ZBG5m0IOBivgmpIzVqQ7wRm48uoqyynwXoDChF" Apr 29 12:38:01 volumio go-librespot[1312]: time="2024-04-29T12:38:01-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:01 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:01 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:01 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:01 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:01 volumio go-librespot[1312]: time="2024-04-29T12:38:01-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:02 volumio go-librespot[1312]: time="2024-04-29T12:38:02-07:00" level=debug msg="completed challenge" Apr 29 12:38:02 volumio go-librespot[1312]: time="2024-04-29T12:38:02-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:02 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:02 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:02 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:02 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:03 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:03 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:03 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:03 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:03 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:03 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:04 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:04 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:04 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:04 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686498. Apr 29 12:38:05 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:05 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:05 volumio go-librespot[1362]: Librespot-go daemon starting... Apr 29 12:38:05 volumio go-librespot[1362]: time="2024-04-29T12:38:05-07:00" level=info msg="generated new device id: 17d973b7a4d5480d4a5e87056d092cef589d996c" Apr 29 12:38:05 volumio go-librespot[1362]: time="2024-04-29T12:38:05-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:05 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:05 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:05 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:05 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:05 volumio go-librespot[1362]: time="2024-04-29T12:38:05-07:00" level=debug msg="obtained new client token: AADqiO4T1njKwalZDSFgZY2oteQAY63dLGDLcmVwEJFKZSqb5D4/rK8G3BiuTBWSSKtfwGDBfOzPWYtEzEB+TDd7rhTF9G/Cu3mfMw6k8eiGb+oppUBYdIdc76CDQNantLhYE45nOGgkvfcBaBEvF+L+/NeOs23mqoe9FnX7OyAI5rYBO9XgRuGoucA1IQGWl1kjI3j3XCEUeHmvAN/Z6x37cqbXfqBUIS2AabFA4AEYLTrMPGCAiz1Ipkdu63w=" Apr 29 12:38:06 volumio go-librespot[1362]: time="2024-04-29T12:38:06-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 29 12:38:06 volumio go-librespot[1362]: time="2024-04-29T12:38:06-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:06 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:06 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:06 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:06 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:06 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:06 volumio go-librespot[1362]: time="2024-04-29T12:38:06-07:00" level=debug msg="new websocket client" Apr 29 12:38:06 volumio volumio[1225]: info: Connection to go-librespot Websocket established Apr 29 12:38:06 volumio go-librespot[1362]: time="2024-04-29T12:38:06-07:00" level=debug msg="completed challenge" Apr 29 12:38:07 volumio go-librespot[1362]: time="2024-04-29T12:38:07-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:07 volumio volumio[1225]: info: Connection to go-librespot Websocket closed Apr 29 12:38:07 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:07 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:07 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:07 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:08 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:08 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:08 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:08 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:09 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:09 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:09 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:09 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:09 volumio volumio[1225]: info: Getting Spotify volume Apr 29 12:38:09 volumio volumio[1225]: (node:1225) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:09 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 29 12:38:09 volumio volumio[1225]: (node:1225) 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: 268050) Apr 29 12:38:09 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 29 12:38:09 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:38:09 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:09 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:38:10 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:10 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686499. Apr 29 12:38:10 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:10 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:10 volumio go-librespot[1370]: Librespot-go daemon starting... Apr 29 12:38:10 volumio go-librespot[1370]: time="2024-04-29T12:38:10-07:00" level=info msg="generated new device id: 67f11107ca99cdce29c02beea9e7f9c0b80eea83" Apr 29 12:38:10 volumio go-librespot[1370]: time="2024-04-29T12:38:10-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:10 volumio go-librespot[1370]: time="2024-04-29T12:38:10-07:00" level=debug msg="obtained new client token: AADVedB8VkPiq7PDFOqnEHfgTzLCWf3STfYiUcAKAfTV9K0VvYR+NRsGWJuT2bF2gS9famOMdHKgEvbvBGwotKtWVuGPAnXikO94x21rlDYLBwdVwrlCjNqc0dohLbHqz9NtTdFXXgYu6wc/PncHNoICZnag/dAKTnBxZCFjL/Zhhq8L/+K0evzcb1ZmgCNT6X5bVH1itVboGW9+7P6DmLzAmXDFThJzgb8iOSRJUmIePrelXHP5L1BM7NoJho0=" Apr 29 12:38:10 volumio go-librespot[1370]: time="2024-04-29T12:38:10-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:10 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:10 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:10 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:10 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:11 volumio go-librespot[1370]: time="2024-04-29T12:38:11-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:11 volumio volumio[1225]: verbose: New Socket.io Connection to 192.168.86.20 from 192.168.86.24 UA: unknown Total Clients: 7 Apr 29 12:38:11 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:38:11 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:11 volumio go-librespot[1370]: time="2024-04-29T12:38:11-07:00" level=debug msg="completed challenge" Apr 29 12:38:11 volumio go-librespot[1370]: time="2024-04-29T12:38:11-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:11 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:11 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:11 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:11 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:11 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:11 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:12 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:12 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:12 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:12 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:13 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:13 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:13 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:13 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:13 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:13 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:14 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:14 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:14 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:14 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686500. Apr 29 12:38:14 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:14 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:14 volumio go-librespot[1378]: Librespot-go daemon starting... Apr 29 12:38:14 volumio go-librespot[1378]: time="2024-04-29T12:38:14-07:00" level=info msg="generated new device id: e903f720c9edca045c112dbbd933624bb31290ff" Apr 29 12:38:14 volumio go-librespot[1378]: time="2024-04-29T12:38:14-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:15 volumio go-librespot[1378]: time="2024-04-29T12:38:15-07:00" level=debug msg="obtained new client token: AAAT0UwkakE4RAq1Rg4h16L4cuaGcLPaz2z4f2lKCHYef9WXx7eidB3G3tzOTHsxg44Hnct56qMbsyWaBNJAytHfgAqSHIIQpTB+EH0C+aYbg2tdliC9M5LDh2jHEVynuFLgrVEFyH8WiI/lyDbBmf3cqS+Qc0NexbpXuiywYzym88V1FAVj3HFPTark5rJhHjRvAtAw9kmmGvN3T6LS1r9HrY9UdrxiM3ceszvfipcSz779KuxhOniIPe68" Apr 29 12:38:15 volumio go-librespot[1378]: time="2024-04-29T12:38:15-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:15 volumio go-librespot[1378]: time="2024-04-29T12:38:15-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:15 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:15 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:15 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:15 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:16 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:16 volumio go-librespot[1378]: time="2024-04-29T12:38:16-07:00" level=debug msg="new websocket client" Apr 29 12:38:16 volumio volumio[1225]: info: Connection to go-librespot Websocket established Apr 29 12:38:16 volumio go-librespot[1378]: time="2024-04-29T12:38:16-07:00" level=debug msg="completed challenge" Apr 29 12:38:16 volumio go-librespot[1378]: time="2024-04-29T12:38:16-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:16 volumio volumio[1225]: info: Connection to go-librespot Websocket closed Apr 29 12:38:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:16 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:16 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:16 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:16 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:17 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 29 12:38:17 volumio sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 29 12:38:17 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 12:38:17 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 12:38:17 volumio sudo[1420]: pam_unix(sudo:session): session closed for user root Apr 29 12:38:17 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Apr 29 12:38:17 volumio volumio[1225]: verbose: New Socket.io Connection to 192.168.86.20 from 192.168.86.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8 Apr 29 12:38:17 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:17 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:17 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:17 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:17 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 29 12:38:17 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 12:38:17 volumio sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 29 12:38:18 volumio sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 12:38:18 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Apr 29 12:38:18 volumio sudo[1494]: pam_unix(sudo:session): session closed for user root Apr 29 12:38:18 volumio volumio[1225]: verbose: New Socket.io Connection to 192.168.86.20 from 192.168.86.24 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 Apr 29 12:38:18 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:38:18 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:18 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 29 12:38:18 volumio volumio[1225]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 29 12:38:18 volumio volumio[1225]: info: Listing playlists Apr 29 12:38:18 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 29 12:38:18 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 29 12:38:18 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 29 12:38:18 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 29 12:38:18 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:18 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:18 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:18 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:19 volumio volumio[1225]: info: Getting Spotify volume Apr 29 12:38:19 volumio volumio[1225]: (node:1225) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:19 volumio volumio[1225]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) Apr 29 12:38:19 volumio volumio[1225]: (node:1225) 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: 268051) Apr 29 12:38:19 volumio volumio[1225]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Apr 29 12:38:19 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:38:19 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:19 volumio volumio[1225]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Apr 29 12:38:19 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:19 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686501. Apr 29 12:38:19 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:19 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:19 volumio go-librespot[1515]: Librespot-go daemon starting... Apr 29 12:38:19 volumio go-librespot[1515]: time="2024-04-29T12:38:19-07:00" level=info msg="generated new device id: 419132d1b61d0c006365d1cd188a09f10f167c90" Apr 29 12:38:19 volumio go-librespot[1515]: time="2024-04-29T12:38:19-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:19 volumio go-librespot[1515]: time="2024-04-29T12:38:19-07:00" level=debug msg="obtained new client token: AAA6i4PWFv34UhZzvy2rQmi8nIsV/fDzBY6CY/YZ2mQVqsUzmaxKHKy3jdRQwL7KTXKhKPJ66ZobD3oAvY9wG0joEhCHxyWfd7b/Bnom8FSFv1JJoCh7IYDunRIc1m+wSdg2Eqi9LnVPNQaLrSwpIGbElgTfAzJ7KCThZWA1sjol7nRZ3RdYoxN2CK9jj6DfUUjC/1xFJADM62dJu5ZjgvDJINm2BJ9CCQOu5QRFmtjhhTvbhONWN4L1/JiupaI=" Apr 29 12:38:19 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:19 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:19 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:19 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:19 volumio go-librespot[1515]: time="2024-04-29T12:38:19-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:20 volumio go-librespot[1515]: time="2024-04-29T12:38:20-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:20 volumio go-librespot[1515]: time="2024-04-29T12:38:20-07:00" level=debug msg="completed challenge" Apr 29 12:38:20 volumio go-librespot[1515]: time="2024-04-29T12:38:20-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:20 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:20 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:20 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:20 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:21 volumio volumio[1225]: info: CoreCommandRouter::volumioPlay Apr 29 12:38:21 volumio volumio[1225]: info: CoreStateMachine::play index undefined Apr 29 12:38:21 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 12:38:21 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:21 volumio volumio[1225]: info: CoreStateMachine::startPlaybackTimer Apr 29 12:38:21 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:21 volumio volumio[1225]: info: CoreCommandRouter::volumioGetVisibleSources Apr 29 12:38:21 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 29 12:38:21 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 29 12:38:21 volumio volumio[1225]: info: Spotify Resume Apr 29 12:38:21 volumio volumio[1225]: info: Sending Spotify command to local API: /player/resume Apr 29 12:38:21 volumio volumio[1225]: error: Failed to send command to Spotify local API: /player/resume: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:21 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:21 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:21 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:21 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:22 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:22 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:22 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:22 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:22 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:22 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:23 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:23 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:23 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:23 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686502. Apr 29 12:38:23 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:23 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:23 volumio go-librespot[1533]: Librespot-go daemon starting... Apr 29 12:38:23 volumio go-librespot[1533]: time="2024-04-29T12:38:23-07:00" level=info msg="generated new device id: f41e3c94f19ab206a37ace8993142f740d352444" Apr 29 12:38:23 volumio go-librespot[1533]: time="2024-04-29T12:38:23-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:24 volumio go-librespot[1533]: time="2024-04-29T12:38:24-07:00" level=debug msg="obtained new client token: AABibuCy9EjTwYM6VCjhAAEkOzAyye1FWCFc/g1d8JJDIwxTIziU0tyLkYq02ms+n0oPPzl1qzIhZSmvSLJQ8bl67XBtt5nEHxMGZ2Mk1Nkm/fpLs5ByKjmi5wyL6aZ73gRnZnqXN8PKRGkfmL6xDxxnVurIuJzNBugHxXaqYDweT7desxa6cJOtSzpkIcCMlfpaYvQyEJKlmbznoqKvDOw2XRb8HbYvBD1NfPVbWWVPqfoGQTPvfE5/wnUr" Apr 29 12:38:24 volumio go-librespot[1533]: time="2024-04-29T12:38:24-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:24 volumio go-librespot[1533]: time="2024-04-29T12:38:24-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:24 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:24 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:24 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:24 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:25 volumio go-librespot[1533]: time="2024-04-29T12:38:25-07:00" level=debug msg="completed challenge" Apr 29 12:38:25 volumio go-librespot[1533]: time="2024-04-29T12:38:25-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:25 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:25 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:25 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:25 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:25 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:25 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:26 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:26 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:26 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:26 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:27 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:27 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:27 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:27 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:28 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:28 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686503. Apr 29 12:38:28 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:28 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:28 volumio go-librespot[1577]: Librespot-go daemon starting... Apr 29 12:38:28 volumio go-librespot[1577]: time="2024-04-29T12:38:28-07:00" level=info msg="generated new device id: 3cf35dde4d138604b4e212fcd39a4815c78d74c9" Apr 29 12:38:28 volumio go-librespot[1577]: time="2024-04-29T12:38:28-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:28 volumio go-librespot[1577]: time="2024-04-29T12:38:28-07:00" level=debug msg="obtained new client token: AAAWaMvUfgePcH9UCtaPsSb0KX+s0UC//2S8Rvf9EgcGZYJGCfSC5hNBImpoxnERrwbQD27bjbylj+vCbiW1WcpvyAYAkL9g5uzTVnsZ4fD2PNwNJmmfuJwl8u5TQSAyZPJ2Od+QQCuTZB1nQXPl0asVwZa+BDXqVoj0h118t16OQ0H7Xjnhf08XL0VGDTViuFKBKP3y3nt4FImvtZ2XPLJaET1BEfkc4+Aq/f9Vdt2YtFE0mFE1kNJLOgYqZRw=" Apr 29 12:38:28 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:28 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:28 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:28 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:28 volumio go-librespot[1577]: time="2024-04-29T12:38:28-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:29 volumio go-librespot[1577]: time="2024-04-29T12:38:29-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:29 volumio go-librespot[1577]: time="2024-04-29T12:38:29-07:00" level=debug msg="completed challenge" Apr 29 12:38:29 volumio go-librespot[1577]: time="2024-04-29T12:38:29-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:29 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:29 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:29 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:29 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:30 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:30 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:30 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:30 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:31 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:31 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:31 volumio volumio[1225]: info: CoreCommandRouter::volumioPlay Apr 29 12:38:31 volumio volumio[1225]: info: CoreStateMachine::play index undefined Apr 29 12:38:31 volumio volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 12:38:31 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:31 volumio volumio[1225]: info: CoreStateMachine::startPlaybackTimer Apr 29 12:38:31 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:31 volumio volumio[1225]: info: CoreCommandRouter::volumioGetVisibleSources Apr 29 12:38:31 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 29 12:38:31 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 29 12:38:31 volumio volumio[1225]: info: Spotify Resume Apr 29 12:38:31 volumio volumio[1225]: info: Sending Spotify command to local API: /player/resume Apr 29 12:38:31 volumio volumio[1225]: error: Failed to send command to Spotify local API: /player/resume: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:31 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:31 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:31 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:31 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:32 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:32 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:32 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:32 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686504. Apr 29 12:38:32 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:32 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:32 volumio go-librespot[1589]: Librespot-go daemon starting... Apr 29 12:38:32 volumio go-librespot[1589]: time="2024-04-29T12:38:32-07:00" level=info msg="generated new device id: d28ae2df98d18ae067066beb41a6a4e2f7c39d05" Apr 29 12:38:32 volumio go-librespot[1589]: time="2024-04-29T12:38:32-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:33 volumio go-librespot[1589]: time="2024-04-29T12:38:33-07:00" level=debug msg="obtained new client token: AACTPOkOmi09rEeIMLOYT+ztGJXOc+9y80CoqaImJ5m4E2yhsjWBcQhOt1x0kXJPVq1RBTXUxdA3vquJ1j5wCOzxBoATth/PK+/VzMJ4o38LKw75A0EbJ2KxRpucMogd9KmssthjRHRwuoN9+Duo+/7lS1zEcv86Q9qj0X77VllNWS89Nb36L44kWar409/hXSh+LI9zIcbiFI5mwwYPl83Vc0EjgyT+Xb4g1odVv5CZfiLml9fXYBjCLczB" Apr 29 12:38:33 volumio go-librespot[1589]: time="2024-04-29T12:38:33-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:33 volumio go-librespot[1589]: time="2024-04-29T12:38:33-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:33 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:33 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:33 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:33 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:34 volumio go-librespot[1589]: time="2024-04-29T12:38:34-07:00" level=debug msg="completed challenge" Apr 29 12:38:34 volumio go-librespot[1589]: time="2024-04-29T12:38:34-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:34 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:34 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:34 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:34 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:34 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:34 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:35 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:35 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:35 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:35 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 29 12:38:36 volumio volumio[1225]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 29 12:38:36 volumio volumio[1225]: info: Received Get System Version Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 12:38:36 volumio volumio[1225]: info: Received Get System Info Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 12:38:36 volumio volumio[1225]: info: Discovery: Getting this device information Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 29 12:38:36 volumio volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 29 12:38:36 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 12:38:36 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:36 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:36 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:36 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:37 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:37 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Apr 29 12:38:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 686505. Apr 29 12:38:37 volumio systemd[1]: Stopped go-librespot Daemon. Apr 29 12:38:37 volumio systemd[1]: Started go-librespot Daemon. Apr 29 12:38:37 volumio go-librespot[1599]: Librespot-go daemon starting... Apr 29 12:38:37 volumio go-librespot[1599]: time="2024-04-29T12:38:37-07:00" level=info msg="generated new device id: 6c6280ea8ad91421f014526b793a76b1b5707c92" Apr 29 12:38:37 volumio go-librespot[1599]: time="2024-04-29T12:38:37-07:00" level=debug msg="stored credentials not found" Apr 29 12:38:37 volumio go-librespot[1599]: time="2024-04-29T12:38:37-07:00" level=debug msg="obtained new client token: AADCj+cSbUSL4GNkbyC+28ER/rWbihObQvwDkcuw5whqEFG65SDxJFP2K7RXR111hRsiZlBQkVn4Nc9/r0NNIp7AVZD+FxLQIaQOXnl1cfpr27HqeY24rrFnrezSOs/Is3cKdYIcWEXoughA0GYRA0ltM/fv9QwU6VnFYgkZ3GiibMSNaqBLsn9AwaxejpqJkoXeuM6TpwD/49MgnBkheWWUKuPB6T1kBR7oe7k3q15itm2tb4bWzRbqxFHVuzo=" Apr 29 12:38:37 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:37 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:37 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:37 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:37 volumio go-librespot[1599]: time="2024-04-29T12:38:37-07:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Apr 29 12:38:38 volumio go-librespot[1599]: time="2024-04-29T12:38:38-07:00" level=debug msg="completed keyexchange" Apr 29 12:38:38 volumio go-librespot[1599]: time="2024-04-29T12:38:38-07:00" level=debug msg="completed challenge" Apr 29 12:38:38 volumio go-librespot[1599]: time="2024-04-29T12:38:38-07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with username and spotify token: failed authenticating: failed login: BadCredentials" Apr 29 12:38:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 29 12:38:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 29 12:38:38 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:38 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:38 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:38 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:39 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:39 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:39 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:39 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:40 volumio volumio[1225]: info: Initializing connection to go-librespot Websocket Apr 29 12:38:40 volumio volumio[1225]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 29 12:38:40 volumio volumio[1225]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Apr 29 12:38:40 volumio volumio[1225]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Apr 29 12:38:40 volumio volumio[1225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 12:38:40 volumio volumio[1225]: TypeError: Cannot read property 'then' of undefined Apr 29 12:38:40 volumio volumio[1225]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Apr 29 12:38:40 volumio volumio[1225]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2249:30) Apr 29 12:38:40 volumio volumio[1225]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1411:26) Apr 29 12:38:40 volumio volumio[1225]: at Socket.emit (events.js:400:28) Apr 29 12:38:40 volumio volumio[1225]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 29 12:38:40 volumio volumio[1225]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 29 12:38:40 volumio volumio[1225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 12:38:40 volumio lircd[1857]: lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:40 volumio lircd[1857]: lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:40 volumio lircd-0.10.1[1857]: Error: could not get file information for /dev/lirc0 Apr 29 12:38:40 volumio lircd-0.10.1[1857]: default_init(): No such file or directory Apr 29 12:38:41 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 12:37 Apr 29 12:38:41 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"