-- Logs begin at Thu 2019-02-14 10:12:00 GMT, end at Fri 2025-01-17 11:33:53 GMT. -- Jan 17 11:32:02 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:02 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 27. Jan 17 11:32:02 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:02 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:02 volumio-office go-librespot[1802]: Librespot-go daemon starting... Jan 17 11:32:02 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:02 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:02 volumio-office go-librespot[1802]: time="2025-01-17T11:32:02Z" level=info msg="generated new device id: 2740b0666223a3ec3481779a48b9c03c3ef75afa" Jan 17 11:32:02 volumio-office go-librespot[1802]: time="2025-01-17T11:32:02Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:03 volumio-office go-librespot[1802]: time="2025-01-17T11:32:03Z" level=debug msg="obtained new client token: AAATc2B0J1RgbNWDzcq3hjNIr4U5RjoKzHRyYyrSr6pi6N1tZJNBbHMth8nRvn/+KXTAmTGgfME9RRwb2gY5SNhxk9L5aiVd9mC21x0hK0rItZzOiW2wp9nz1kuF+nDzvJSL9ij1wKdWtYcupqRvogOX85+08u2AaAdeH/xc04zd213TBKYCpbBpBtAnt+8+NGl0WeVA8SmnnQocvNT5y2Sd+xA3bwEbeYsbm6d4tr0uYYfsi/E/gz3aUUg=" Jan 17 11:32:03 volumio-office go-librespot[1802]: time="2025-01-17T11:32:03Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:03 volumio-office go-librespot[1802]: time="2025-01-17T11:32:03Z" level=debug msg="completed keyexchange" Jan 17 11:32:03 volumio-office go-librespot[1802]: time="2025-01-17T11:32:03Z" level=debug msg="completed challenge" Jan 17 11:32:03 volumio-office go-librespot[1802]: time="2025-01-17T11:32:03Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:03 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:03 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:04 volumio-office volumio[898]: 38 Jan 17 11:32:04 volumio-office volumio[898]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 17 11:32:04 volumio-office volumio[898]: Dload Upload Total Spent Left Speed Jan 17 11:32:04 volumio-office volumio[677]: info: Jan 17 11:32:04 volumio-office volumio[677]: ---------------------------- Client requests Volume 38 Jan 17 11:32:04 volumio-office volumio[677]: info: VolumeController::SetAlsaVolume38 Jan 17 11:32:04 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:32:04 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:04 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:32:04 volumio-office volumio[677]: info: CoreCommandRouter::volumioPushState Jan 17 11:32:04 volumio-office volumio[898]: [158B blob data] Jan 17 11:32:04 volumio-office volumio[898]: {"time":1737113524403,"response":"volume Success"}38 Jan 17 11:32:04 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Jan 17 11:32:04 volumio-office volumio[677]: SPOTIFY: SPOTIFY VOLUME 47 Jan 17 11:32:04 volumio-office volumio[677]: SPOTIFY: VOLUMIO VOLUME 38 Jan 17 11:32:04 volumio-office volumio[677]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:32:04 volumio-office volumio[677]: info: Setting Spotify Volume from Volumio: 38 Jan 17 11:32:05 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:05 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:06 volumio-office volumio[677]: SPOTIFY: SETTING SPOTIFY VOLUME 38 Jan 17 11:32:06 volumio-office volumio[677]: info: Sending Spotify command with payload to local API: /player/volume Jan 17 11:32:06 volumio-office volumio[677]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:06 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:06 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:07 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:07 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 28. Jan 17 11:32:07 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:07 volumio-office volumio[898]: 28 Jan 17 11:32:07 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:07 volumio-office go-librespot[1856]: Librespot-go daemon starting... Jan 17 11:32:07 volumio-office go-librespot[1856]: time="2025-01-17T11:32:07Z" level=info msg="generated new device id: 87999917c003c61d15bd55bdf45e2bcc8b8da47f" Jan 17 11:32:07 volumio-office go-librespot[1856]: time="2025-01-17T11:32:07Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:07 volumio-office volumio[898]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 17 11:32:07 volumio-office volumio[898]: Dload Upload Total Spent Left Speed Jan 17 11:32:07 volumio-office volumio[677]: info: Jan 17 11:32:07 volumio-office volumio[677]: ---------------------------- Client requests Volume 28 Jan 17 11:32:07 volumio-office volumio[677]: info: VolumeController::SetAlsaVolume28 Jan 17 11:32:07 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:32:07 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:07 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 11:32:07 volumio-office volumio[677]: info: CoreCommandRouter::volumioPushState Jan 17 11:32:07 volumio-office volumio[898]: [158B blob data] Jan 17 11:32:07 volumio-office volumio[898]: {"time":1737113527329,"response":"volume Success"}28 Jan 17 11:32:07 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Jan 17 11:32:07 volumio-office volumio[677]: SPOTIFY: SPOTIFY VOLUME 38 Jan 17 11:32:07 volumio-office volumio[677]: SPOTIFY: VOLUMIO VOLUME 28 Jan 17 11:32:07 volumio-office volumio[677]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 17 11:32:07 volumio-office volumio[677]: info: Setting Spotify Volume from Volumio: 28 Jan 17 11:32:07 volumio-office go-librespot[1856]: time="2025-01-17T11:32:07Z" level=debug msg="obtained new client token: AAD4xOi6E64GV3U4kGaJ9HpOIyfLgIOSFqEDS+p9MTpFBRa0UwLtnrYLExGUqQbqTRwwk8eA7eHjY7zcWAK+COB1QtI1HDxMbP/nJZdLLG1CA2wYjvX3eNizo1TWtkhyNj3409g2VjXYa1/0EF2iHvfTyJSyv3th6DVtnXQnHEYVvPGVzdLsRG/ET/eUUnDb9lPcAPBnb+TqS/KcLW/PcqdHQudPA3mzGFIxNNfRNCmUUJ9j4c+5wCmnUNPDDA==" Jan 17 11:32:07 volumio-office go-librespot[1856]: time="2025-01-17T11:32:07Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:07 volumio-office go-librespot[1856]: time="2025-01-17T11:32:07Z" level=debug msg="completed keyexchange" Jan 17 11:32:08 volumio-office go-librespot[1856]: time="2025-01-17T11:32:08Z" level=debug msg="completed challenge" Jan 17 11:32:08 volumio-office go-librespot[1856]: time="2025-01-17T11:32:08Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:08 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:08 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:08 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:08 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:08 volumio-office volumio[677]: SPOTIFY: SETTING SPOTIFY VOLUME 28 Jan 17 11:32:08 volumio-office volumio[677]: info: Sending Spotify command with payload to local API: /player/volume Jan 17 11:32:08 volumio-office volumio[677]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:11 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:11 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 29. Jan 17 11:32:11 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:11 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:11 volumio-office go-librespot[1880]: Librespot-go daemon starting... Jan 17 11:32:11 volumio-office go-librespot[1880]: time="2025-01-17T11:32:11Z" level=info msg="generated new device id: 185a7696a0eba550f403138436dba5ad515c6f1c" Jan 17 11:32:11 volumio-office go-librespot[1880]: time="2025-01-17T11:32:11Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:11 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:11 volumio-office go-librespot[1880]: time="2025-01-17T11:32:11Z" level=debug msg="new websocket client" Jan 17 11:32:11 volumio-office volumio[677]: info: Connection to go-librespot Websocket established Jan 17 11:32:12 volumio-office go-librespot[1880]: time="2025-01-17T11:32:12Z" level=debug msg="obtained new client token: AABbOnZSdv4dpeOtPvZfSTJx5BrX7iDm3qfxSGJgqhXHCawPPH6Jeg2mE+nYS0AxYQK0fwG3GBzkIYmv95AVUUAYgWCvzL6zp6rHp5RFsaUV3SLYDyWay/74XmErMinkKCCRLLnOhsDPXKidfC5qIgsThPPHrKVOmGxGZpSgxwV+oSPK7k5UDbaiNfGQY9k9Nb/LYhmvuMaIGr7Csskhe0ujTdFxN24BH0ayabfqDe2PyDQI23FTe0E7z4M=" Jan 17 11:32:12 volumio-office go-librespot[1880]: time="2025-01-17T11:32:12Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:32:12 volumio-office go-librespot[1880]: time="2025-01-17T11:32:12Z" level=debug msg="completed keyexchange" Jan 17 11:32:12 volumio-office go-librespot[1880]: time="2025-01-17T11:32:12Z" level=debug msg="completed challenge" Jan 17 11:32:12 volumio-office go-librespot[1880]: time="2025-01-17T11:32:12Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:12 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:12 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:12 volumio-office volumio[677]: info: Connection to go-librespot Websocket closed Jan 17 11:32:14 volumio-office volumio[677]: info: Getting Spotify volume Jan 17 11:32:14 volumio-office volumio[677]: (node:677) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:14 volumio-office volumio[677]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 17 11:32:14 volumio-office volumio[677]: (node:677) 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: 7) Jan 17 11:32:14 volumio-office volumio[677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 17 11:32:14 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:14 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:14 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Jan 17 11:32:15 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:15 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:16 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:16 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 30. Jan 17 11:32:16 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:16 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:16 volumio-office go-librespot[1905]: Librespot-go daemon starting... Jan 17 11:32:16 volumio-office go-librespot[1905]: time="2025-01-17T11:32:16Z" level=info msg="generated new device id: 1e3ff165f1429578ce69fc6701b689467ba99ce4" Jan 17 11:32:16 volumio-office go-librespot[1905]: time="2025-01-17T11:32:16Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:16 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:16 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:16 volumio-office volumio[677]: info: Listing playlists Jan 17 11:32:16 volumio-office volumio[677]: info: Listing playlists Jan 17 11:32:16 volumio-office go-librespot[1905]: time="2025-01-17T11:32:16Z" level=debug msg="obtained new client token: AAC5/uFu1k3BZgvR6iX233Vz9fmldfOPtM4269d3y3Zcypl7fBSwkG0k+oM9saUkluY5OiWg6sDTrRLQjVg7/RJxOiQwKkx7crmGj7zwNgqWyyRHoBbghJ3MimuP97opnCi6vMxIE4amQjFE66NDKpYgHj46Rd3f8JXig7P5N/DchosC3XU4s0VH9PSBXX/y/MFGGj8T3F4BIE+CwZcT5qMr68uhVMqPuuZrPq9hGKAcWLNgSDSplpMM5a124g==" Jan 17 11:32:16 volumio-office go-librespot[1905]: time="2025-01-17T11:32:16Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:16 volumio-office go-librespot[1905]: time="2025-01-17T11:32:16Z" level=debug msg="completed keyexchange" Jan 17 11:32:17 volumio-office go-librespot[1905]: time="2025-01-17T11:32:17Z" level=debug msg="completed challenge" Jan 17 11:32:17 volumio-office go-librespot[1905]: time="2025-01-17T11:32:17Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:17 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:17 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:18 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:18 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:20 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:20 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 31. Jan 17 11:32:20 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:20 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:20 volumio-office go-librespot[1924]: Librespot-go daemon starting... Jan 17 11:32:20 volumio-office go-librespot[1924]: time="2025-01-17T11:32:20Z" level=info msg="generated new device id: da4af0e4126ee0649dd443826cbcccb389e4d199" Jan 17 11:32:20 volumio-office go-librespot[1924]: time="2025-01-17T11:32:20Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:21 volumio-office go-librespot[1924]: time="2025-01-17T11:32:21Z" level=debug msg="obtained new client token: AACoj/YtJWj/yEDDJDjaRa3sUwh4LyJI9Gs90FWx4HHkMUGpaT1EpT1O82GJ65a49AE/VUAJ4CVJfwiKQ/JlzZt/fIN8UiwioG7Zi58yjxGZXe7tYTwhts2fUsA/LE8/Bky2UxHDqPpt/kgHuaUfwVu0iZsl9eODeWkwKC8SjevEKBt3vbevqI8v5CO8RFEYkS+TNHbxDmkbUusIW/7VZfSTtwmZicL0uetx/37SPSojWsgCJp8dFKbQ6CA=" Jan 17 11:32:21 volumio-office go-librespot[1924]: time="2025-01-17T11:32:21Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:21 volumio-office go-librespot[1924]: time="2025-01-17T11:32:21Z" level=debug msg="completed keyexchange" Jan 17 11:32:21 volumio-office go-librespot[1924]: time="2025-01-17T11:32:21Z" level=debug msg="completed challenge" Jan 17 11:32:21 volumio-office go-librespot[1924]: time="2025-01-17T11:32:21Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:21 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:21 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:21 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:21 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:24 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:24 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:25 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:25 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 32. Jan 17 11:32:25 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:25 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:25 volumio-office go-librespot[1949]: Librespot-go daemon starting... Jan 17 11:32:25 volumio-office go-librespot[1949]: time="2025-01-17T11:32:25Z" level=info msg="generated new device id: b74bb5bd00cb5ec5513cb8fd1374813964f67be3" Jan 17 11:32:25 volumio-office go-librespot[1949]: time="2025-01-17T11:32:25Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:25 volumio-office go-librespot[1949]: time="2025-01-17T11:32:25Z" level=debug msg="obtained new client token: AAD1wJb7mdx24j2+ZGyg4/UwSopoBs0Fk6SZyQI4IiZ84OqR1akA9WjJ6/1vk9aBhO0F9hRLmBtEyb9RCTki8TwG7GNF/vOckrmVcTjfhOR7yI4b8PU6Ouh+VCKf7W2Ih817fl0/VYnK9WTvkjOEvBjwFxSRRutaVAGUrzXq2isIIujweYeUKxizC4zQT5hYedsSvd2ybuDNBFGO8r/Dg3npqE7vxBoJm1STnWg1zsqtey+z9YjvPb5MKABx7g==" Jan 17 11:32:25 volumio-office go-librespot[1949]: time="2025-01-17T11:32:25Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:25 volumio-office go-librespot[1949]: time="2025-01-17T11:32:25Z" level=debug msg="completed keyexchange" Jan 17 11:32:26 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:26 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:26 volumio-office go-librespot[1949]: time="2025-01-17T11:32:26Z" level=debug msg="completed challenge" Jan 17 11:32:26 volumio-office go-librespot[1949]: time="2025-01-17T11:32:26Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:26 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:26 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:27 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:27 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:29 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:29 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 33. Jan 17 11:32:29 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:29 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:29 volumio-office go-librespot[1958]: Librespot-go daemon starting... Jan 17 11:32:29 volumio-office go-librespot[1958]: time="2025-01-17T11:32:29Z" level=info msg="generated new device id: 126a4fdb91fe033bdb505dcb50bc31cc9646587a" Jan 17 11:32:29 volumio-office go-librespot[1958]: time="2025-01-17T11:32:29Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:30 volumio-office go-librespot[1958]: time="2025-01-17T11:32:30Z" level=debug msg="obtained new client token: AAC/nbz6RQGYuiHZURmExIoDhCHBm5Mt9qJnCT2qmxnjpQU3QXiZ2ZVMfCAJyoEEIdkjNgxv+p5c370FJ9UgXWLXQTXLk/fGDF7ExiYz0T8ydjNuVo5VG/D9OzDj8i2RxHsyeVb0JO8m/DrFt3FZyfswbbtu4dhzBLHKw/R750GS4Fsb3K8POrNBM/jFS132A4rtBZof7MvjrLWFA2WBgM4hfViJ0hRKD7PX2FDHdBd5ZOcE+cEPgQDYohU=" Jan 17 11:32:30 volumio-office go-librespot[1958]: time="2025-01-17T11:32:30Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:32:30 volumio-office go-librespot[1958]: time="2025-01-17T11:32:30Z" level=debug msg="completed keyexchange" Jan 17 11:32:30 volumio-office go-librespot[1958]: time="2025-01-17T11:32:30Z" level=debug msg="completed challenge" Jan 17 11:32:30 volumio-office go-librespot[1958]: time="2025-01-17T11:32:30Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:30 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:30 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:30 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:30 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:34 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:34 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:34 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:34 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 34. Jan 17 11:32:34 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:34 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:34 volumio-office go-librespot[1991]: Librespot-go daemon starting... Jan 17 11:32:34 volumio-office go-librespot[1991]: time="2025-01-17T11:32:34Z" level=info msg="generated new device id: ecb9dd954de1f67b7c681fa3a02c7f3735e5c485" Jan 17 11:32:34 volumio-office go-librespot[1991]: time="2025-01-17T11:32:34Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:34 volumio-office go-librespot[1991]: time="2025-01-17T11:32:34Z" level=debug msg="obtained new client token: AADz053RHZo99Oy/4dRsoHUi6WBXiyRxj3fSB3YAVPv4yYEatTFe5ymeAmVHqqC2FkO7CYQrGZysVaVnOX4QUdRszgszdUam5kOuY/OOq3PSiS/c3pyv2OM7QbT5ZIAu64MTE7a2UAEPeNYPX7AngLzJClv88P0ZTeCp+GuO/5kH92B9UmjJ9bBGIxS8HdkeH81EOjlBxPFDt8ujj1WekEyHGPeaHIwcGVkdTyubLI38VIggMBjv0wy4L5Xe2w==" Jan 17 11:32:34 volumio-office go-librespot[1991]: time="2025-01-17T11:32:34Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:32:34 volumio-office go-librespot[1991]: time="2025-01-17T11:32:34Z" level=debug msg="completed keyexchange" Jan 17 11:32:35 volumio-office go-librespot[1991]: time="2025-01-17T11:32:35Z" level=debug msg="completed challenge" Jan 17 11:32:35 volumio-office go-librespot[1991]: time="2025-01-17T11:32:35Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:35 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:35 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:36 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:36 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:36 volumio-office volumio[677]: info: Listing playlists Jan 17 11:32:36 volumio-office volumio[677]: info: Listing playlists Jan 17 11:32:37 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:37 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:38 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:38 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 35. Jan 17 11:32:38 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:38 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:38 volumio-office go-librespot[2004]: Librespot-go daemon starting... Jan 17 11:32:38 volumio-office go-librespot[2004]: time="2025-01-17T11:32:38Z" level=info msg="generated new device id: 6b313afc733d8ff059b57b2ae32fd61b15e74851" Jan 17 11:32:38 volumio-office go-librespot[2004]: time="2025-01-17T11:32:38Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:39 volumio-office go-librespot[2004]: time="2025-01-17T11:32:39Z" level=debug msg="obtained new client token: AABCp9apwVl3n/q6RRgh/Esvf6ftbBEINJWmf0z/g3z4iH/SH01oWQbtOabWBqfbQmE2/dHuleGUBSJXElmMNFFz5SK1W4/rKJ9H1f0i5T/Y9k6iTRaQcvEsDm7CqmM9HhADRRSJAzcl8EmpVjvq971KVqGf+3YWJHocupOs9x0qUd75PqdaPRsFAA1u9TaMJk47+7FzeToiClHRsAe/ylzfD1wnZPnmc68XZoExrefM2fQeEZN7jRKn7jM=" Jan 17 11:32:39 volumio-office go-librespot[2004]: time="2025-01-17T11:32:39Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:39 volumio-office go-librespot[2004]: time="2025-01-17T11:32:39Z" level=debug msg="completed keyexchange" Jan 17 11:32:39 volumio-office go-librespot[2004]: time="2025-01-17T11:32:39Z" level=debug msg="completed challenge" Jan 17 11:32:39 volumio-office go-librespot[2004]: time="2025-01-17T11:32:39Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:39 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:39 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:40 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:40 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:42 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:42 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 36. Jan 17 11:32:42 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:43 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:43 volumio-office go-librespot[2037]: Librespot-go daemon starting... Jan 17 11:32:43 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:43 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:43 volumio-office go-librespot[2037]: time="2025-01-17T11:32:43Z" level=info msg="generated new device id: 079f280c2555a884d8b07be6ce73d8ec90458ad7" Jan 17 11:32:43 volumio-office go-librespot[2037]: time="2025-01-17T11:32:43Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:43 volumio-office go-librespot[2037]: time="2025-01-17T11:32:43Z" level=debug msg="obtained new client token: AAArnHsr0cReGFQrLUZsQdDl1AAVd3HDULy1K/AsTCdNAowTx/sEe7+kUR5W7iIkTmEHvTASIRQ6ImzOeZEdPtZ0+rF/UZnbw9u1YJB+m68b2VFMqQo+dGT2hS4kmZ95Qxh/Vfg5BNwBGxKUZJedXSNPmhA8p3eZj6qbiKERXfs+HusmZNTDiWLiffIxGCDeKcPLr6p2tEdXpVtFoc9QO4TFVQlWxPIc+HlZq/9q+0RGQ0/m+V1NTY06ql582w==" Jan 17 11:32:43 volumio-office go-librespot[2037]: time="2025-01-17T11:32:43Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:32:43 volumio-office go-librespot[2037]: time="2025-01-17T11:32:43Z" level=debug msg="completed keyexchange" Jan 17 11:32:44 volumio-office go-librespot[2037]: time="2025-01-17T11:32:44Z" level=debug msg="completed challenge" Jan 17 11:32:44 volumio-office go-librespot[2037]: time="2025-01-17T11:32:44Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:44 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:44 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:46 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:46 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:46 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:46 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:47 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:47 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 37. Jan 17 11:32:47 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:47 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:47 volumio-office go-librespot[2049]: Librespot-go daemon starting... Jan 17 11:32:47 volumio-office go-librespot[2049]: time="2025-01-17T11:32:47Z" level=info msg="generated new device id: d028e62c9d49f82702a5f7f303a019a177f23ae5" Jan 17 11:32:47 volumio-office go-librespot[2049]: time="2025-01-17T11:32:47Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:47 volumio-office go-librespot[2049]: time="2025-01-17T11:32:47Z" level=debug msg="obtained new client token: AADvvjZQ2h7MfA1feSs3USP7J0v2RP/a/fLr6Oo+n8Zo0Q88x8NiWVvuI3kZbEjjdmX7U3BS+EeRQeh9onkRWOqih6ilDtbKJLb+Ko0bcRw+NbMklMhSIIhvx1TSSBUkLkBOSahJ6Wb5XT++p1wL+wvETLjYxHxag5pL6K1T6/BkWw05gBWQsghkMieQxqId+UVAxSw76A/a8JofIkzc1t0bz/6DG64zL+GV3Xb7nJzWP/0VvBEFcA8YNr5wRg==" Jan 17 11:32:48 volumio-office go-librespot[2049]: time="2025-01-17T11:32:48Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:48 volumio-office go-librespot[2049]: time="2025-01-17T11:32:48Z" level=debug msg="completed keyexchange" Jan 17 11:32:48 volumio-office go-librespot[2049]: time="2025-01-17T11:32:48Z" level=debug msg="completed challenge" Jan 17 11:32:48 volumio-office go-librespot[2049]: time="2025-01-17T11:32:48Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:48 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:48 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:49 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:49 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:51 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:51 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 38. Jan 17 11:32:51 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:51 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:51 volumio-office go-librespot[2069]: Librespot-go daemon starting... Jan 17 11:32:51 volumio-office go-librespot[2069]: time="2025-01-17T11:32:51Z" level=info msg="generated new device id: 684dc5b9ad0e14cb60faaf793ce68b08c1c1990f" Jan 17 11:32:51 volumio-office go-librespot[2069]: time="2025-01-17T11:32:51Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:52 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:52 volumio-office go-librespot[2069]: time="2025-01-17T11:32:52Z" level=debug msg="new websocket client" Jan 17 11:32:52 volumio-office volumio[677]: info: Connection to go-librespot Websocket established Jan 17 11:32:52 volumio-office go-librespot[2069]: time="2025-01-17T11:32:52Z" level=debug msg="obtained new client token: AABhht5Gs6DMS6hiALSSz5UuoKRqS1/MhziimiGJQehB8c/t7eS7xOKHttGJ/w5GeKj2Jwv8Ofe2Qpsb9ZW8slVrW6oR67QbYgHZ6JcGdrM6TeKTXcdq28/3XJvVNnxjGyPLII4LbN8H+VJNmDGfsEyxvC+N47sV1Zpqy3S+orZwLycnn76LMXJHltwab7aYXJodaw9F9yr9YNAjn8ugv9gMyCrt+jBk8r50Ixui+Mm6xrTd/HmXgrDcY0I=" Jan 17 11:32:52 volumio-office go-librespot[2069]: time="2025-01-17T11:32:52Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:32:52 volumio-office go-librespot[2069]: time="2025-01-17T11:32:52Z" level=debug msg="completed keyexchange" Jan 17 11:32:52 volumio-office go-librespot[2069]: time="2025-01-17T11:32:52Z" level=debug msg="completed challenge" Jan 17 11:32:52 volumio-office go-librespot[2069]: time="2025-01-17T11:32:52Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:52 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:52 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:52 volumio-office volumio[677]: info: Connection to go-librespot Websocket closed Jan 17 11:32:55 volumio-office volumio[677]: info: Getting Spotify volume Jan 17 11:32:55 volumio-office volumio[677]: (node:677) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:55 volumio-office volumio[677]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 17 11:32:55 volumio-office volumio[677]: (node:677) 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: 8) Jan 17 11:32:55 volumio-office volumio[677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 17 11:32:55 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:55 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:55 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Jan 17 11:32:55 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:55 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:32:56 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:32:56 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 39. Jan 17 11:32:56 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:32:56 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:32:56 volumio-office go-librespot[2097]: Librespot-go daemon starting... Jan 17 11:32:56 volumio-office go-librespot[2097]: time="2025-01-17T11:32:56Z" level=info msg="generated new device id: af8653a5c6932f89d40acf6a0f85ef501c6913f3" Jan 17 11:32:56 volumio-office go-librespot[2097]: time="2025-01-17T11:32:56Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:32:56 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:32:56 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:32:56 volumio-office volumio[677]: info: Listing playlists Jan 17 11:32:56 volumio-office volumio[677]: info: Listing playlists Jan 17 11:32:56 volumio-office go-librespot[2097]: time="2025-01-17T11:32:56Z" level=debug msg="obtained new client token: AAANWSgcu2a04F8cf+zEaQTkxoKqbsfy+bcMXh6s5X+YRCXkk75cbgpSVKG05sgZ6MpfmGeCzVYAefIftDoBc6pCvbp7pOLjVBALmwLCgbCPbH21Zp/sUAOlf1dOiVAf2a5y0J3hJTXsEebRKdPhpkmk5Z7610Et+UATndbD6jpf0Wa2PG13B1A6HIdDTokYOLcWWpAmHPvo7ynOvaev6Ifxrkskw7V39LeBmzCx9VDiRyUsgUz6jYpMeHU4tQ==" Jan 17 11:32:56 volumio-office go-librespot[2097]: time="2025-01-17T11:32:56Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:32:56 volumio-office go-librespot[2097]: time="2025-01-17T11:32:56Z" level=debug msg="completed keyexchange" Jan 17 11:32:57 volumio-office go-librespot[2097]: time="2025-01-17T11:32:57Z" level=debug msg="completed challenge" Jan 17 11:32:57 volumio-office go-librespot[2097]: time="2025-01-17T11:32:57Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:32:57 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:32:57 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:32:58 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:32:58 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:00 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:00 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 40. Jan 17 11:33:00 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:00 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:00 volumio-office go-librespot[2117]: Librespot-go daemon starting... Jan 17 11:33:00 volumio-office go-librespot[2117]: time="2025-01-17T11:33:00Z" level=info msg="generated new device id: 5cd4bbd0e444aa0872c19ecebdcc78a98750bd53" Jan 17 11:33:00 volumio-office go-librespot[2117]: time="2025-01-17T11:33:00Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:01 volumio-office go-librespot[2117]: time="2025-01-17T11:33:01Z" level=debug msg="obtained new client token: AADC/CW0ERJA5eQfu241IlpHRbuEIoIoft0Z3zBuHrTbS47s1rhHmWy6VBg5QGy1f7SdNK8HM2Od5QXt0vy2dVI8oIx/4wZoIkLEYv2weEjZHNTbdiBjBMCAzVZ5FMzAuGFBehB2dcbQbijE2pFucXmnrXRgTqY0KAyosNhfdtduKn7+q8r3vinSkU6y2JHmP1UuCPxn+7/f6D96lN+5uxAwT+veGIn3DPjUU1muGxdKXzGBGFh6w7weXtI=" Jan 17 11:33:01 volumio-office go-librespot[2117]: time="2025-01-17T11:33:01Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:01 volumio-office go-librespot[2117]: time="2025-01-17T11:33:01Z" level=debug msg="completed keyexchange" Jan 17 11:33:01 volumio-office go-librespot[2117]: time="2025-01-17T11:33:01Z" level=debug msg="completed challenge" Jan 17 11:33:01 volumio-office go-librespot[2117]: time="2025-01-17T11:33:01Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:01 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:01 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:01 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:01 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:04 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:04 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 41. Jan 17 11:33:04 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:04 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:05 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:05 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:05 volumio-office go-librespot[2180]: Librespot-go daemon starting... Jan 17 11:33:05 volumio-office go-librespot[2180]: time="2025-01-17T11:33:05Z" level=info msg="generated new device id: be68326942200ecef6c846bfbe4e9b90c9610d41" Jan 17 11:33:05 volumio-office go-librespot[2180]: time="2025-01-17T11:33:05Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:05 volumio-office go-librespot[2180]: time="2025-01-17T11:33:05Z" level=debug msg="obtained new client token: AAAN17R+StEb8ev3W06uy0J5k6j1bzOPeQjZNTXuWhQ1DlEsdO+vI5YClawfhJ6iR/aXmaLogpQyGot4Hgnr2lnH4fD8Q7FqKcKn5wFOqYPVAHWZDFpPYNij0fFqERa0YV/mAkzvP9FdqC2Bf9U6kRwx8ONQQcI9sHB7YlcbtKy0r7yvWVZ7SvZLa9P3Dk2Nwzujnh70OGPQ9E1f7wVqH/+IT4bblEocEyZE6fLlKFbo97wEHTv+oqBCijERLw==" Jan 17 11:33:05 volumio-office go-librespot[2180]: time="2025-01-17T11:33:05Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:33:05 volumio-office go-librespot[2180]: time="2025-01-17T11:33:05Z" level=debug msg="completed keyexchange" Jan 17 11:33:06 volumio-office go-librespot[2180]: time="2025-01-17T11:33:06Z" level=debug msg="completed challenge" Jan 17 11:33:06 volumio-office go-librespot[2180]: time="2025-01-17T11:33:06Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:06 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:06 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:06 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:06 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:07 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:07 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:09 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:09 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 42. Jan 17 11:33:09 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:09 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:09 volumio-office go-librespot[2189]: Librespot-go daemon starting... Jan 17 11:33:09 volumio-office go-librespot[2189]: time="2025-01-17T11:33:09Z" level=info msg="generated new device id: 3ce36f7d10d626770cb30ffd89edba0c9016a488" Jan 17 11:33:09 volumio-office go-librespot[2189]: time="2025-01-17T11:33:09Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:09 volumio-office go-librespot[2189]: time="2025-01-17T11:33:09Z" level=debug msg="obtained new client token: AADu6Z6+xO/QUcTb1+kdIcjI2S0STDJVlcCS3UCtzs0b1jDf7K+/rG7fCVeYEunvgli4jNVEEKQ7iWDiJWNHFAnBVPoeP2kklTAMgebnaoxbpRTMeJRL4G+TAakrQW9/DWfq19UNoOXcB73Bzq6Ya/gLWyGooypgUKoTXGX/87GYD3r37vdvSqxXTtNmdTn2jnmvg5sFEAvELfliEKPl3piAS5O9iifrEa7Mar/f/V2wYlhwcDmu9CE5vIIx0w==" Jan 17 11:33:10 volumio-office go-librespot[2189]: time="2025-01-17T11:33:10Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:33:10 volumio-office go-librespot[2189]: time="2025-01-17T11:33:10Z" level=debug msg="completed keyexchange" Jan 17 11:33:10 volumio-office go-librespot[2189]: time="2025-01-17T11:33:10Z" level=debug msg="completed challenge" Jan 17 11:33:10 volumio-office go-librespot[2189]: time="2025-01-17T11:33:10Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:10 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:10 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:10 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:11 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:13 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:13 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 43. Jan 17 11:33:13 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:14 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:14 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:14 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:14 volumio-office go-librespot[2225]: Librespot-go daemon starting... Jan 17 11:33:14 volumio-office go-librespot[2225]: time="2025-01-17T11:33:14Z" level=info msg="generated new device id: 34851c1f7e9efe3dc67c6ea3603dc987577d48c0" Jan 17 11:33:14 volumio-office go-librespot[2225]: time="2025-01-17T11:33:14Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:14 volumio-office go-librespot[2225]: time="2025-01-17T11:33:14Z" level=debug msg="obtained new client token: AAAWSvzP51tnYTbRRiDmJv2JZ04Jlub4W9usNisWNZQSHY+tjmkHCIvhZMn5WoD3mHATMfmdTwilIbwpOegGvI9abimecbvzMvTyEA8dZmCjRwRm/vOg0vljRwkdEKuluFyY750MBPRvqmXHkc0Vyc5+T6SIo0mCwSj5OGF/VJefxOIE2klGa8/zAdtq6Imp0nfyNMGKX0bEVkwMInP2xb/IuaO9g92I+hknXX0I+svs8RBF6lkxAPObxu5JXA==" Jan 17 11:33:14 volumio-office go-librespot[2225]: time="2025-01-17T11:33:14Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:14 volumio-office go-librespot[2225]: time="2025-01-17T11:33:14Z" level=debug msg="completed keyexchange" Jan 17 11:33:15 volumio-office go-librespot[2225]: time="2025-01-17T11:33:15Z" level=debug msg="completed challenge" Jan 17 11:33:15 volumio-office go-librespot[2225]: time="2025-01-17T11:33:15Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:15 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:15 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:16 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:16 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:16 volumio-office volumio[677]: info: Listing playlists Jan 17 11:33:16 volumio-office volumio[677]: info: Listing playlists Jan 17 11:33:17 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:17 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:18 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:18 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 44. Jan 17 11:33:18 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:18 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:18 volumio-office go-librespot[2236]: Librespot-go daemon starting... Jan 17 11:33:18 volumio-office go-librespot[2236]: time="2025-01-17T11:33:18Z" level=info msg="generated new device id: bf9fa3799b4abc949c9b85dc4cc84805f1768cd8" Jan 17 11:33:18 volumio-office go-librespot[2236]: time="2025-01-17T11:33:18Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:18 volumio-office go-librespot[2236]: time="2025-01-17T11:33:18Z" level=debug msg="obtained new client token: AAA38J1SKgHHKPrxdRq3A5MI6timXGLtnjQdX1Lr7ge9YsMblq5X5R7uPq8sgdcDeAx5m8zGFahGbri0CqoSRz5XOdIZbe6f7zb/caJRrgg7fiKrhPF/ORPhfEV14SyjPElyWjqDx+J0IkB8nRi5gJL2dvZ16UACSgWxibZvXU/zyQvmWAYHMfJQ2lWDf/KiZC8ICRzFgsKhRW3EGT0S5wl2ngZVT3wUEUUOXb856RYzxso/CiQa5g5n7Q3qkA==" Jan 17 11:33:19 volumio-office go-librespot[2236]: time="2025-01-17T11:33:19Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:19 volumio-office go-librespot[2236]: time="2025-01-17T11:33:19Z" level=debug msg="completed keyexchange" Jan 17 11:33:19 volumio-office go-librespot[2236]: time="2025-01-17T11:33:19Z" level=debug msg="completed challenge" Jan 17 11:33:19 volumio-office go-librespot[2236]: time="2025-01-17T11:33:19Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:19 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:19 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:20 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:20 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:22 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:22 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 45. Jan 17 11:33:22 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:22 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:22 volumio-office go-librespot[2269]: Librespot-go daemon starting... Jan 17 11:33:22 volumio-office go-librespot[2269]: time="2025-01-17T11:33:22Z" level=info msg="generated new device id: 886fc544fff62502a14c29b7f2400af9f0f4dfed" Jan 17 11:33:22 volumio-office go-librespot[2269]: time="2025-01-17T11:33:22Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:23 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:23 volumio-office go-librespot[2269]: time="2025-01-17T11:33:23Z" level=debug msg="new websocket client" Jan 17 11:33:23 volumio-office volumio[677]: info: Connection to go-librespot Websocket established Jan 17 11:33:23 volumio-office go-librespot[2269]: time="2025-01-17T11:33:23Z" level=debug msg="obtained new client token: AAC4XdHJ6l6y+igdsYOVAbYCxmLNHz8SrjcS9BhYDxCOyBk+gS7TVdAvz8qMANZUxt187IGOlFBJfwX0vLoj5DCKHmhaAei27GiTXmbAaV0rf9EttHx81txdFjN1zaLxkI1xIgc+Qb2c4Q0JJILip+zly6WqQ/dVzsTOLTRX0Zv4t6qqweXLMNbSCUgPJB2sbso+p+hFvitSKlQXIIr6YgwpZ8hA2MI3CjjOX+9tGdFerTWsIrt4hAZPOeI=" Jan 17 11:33:23 volumio-office go-librespot[2269]: time="2025-01-17T11:33:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:23 volumio-office go-librespot[2269]: time="2025-01-17T11:33:23Z" level=debug msg="completed keyexchange" Jan 17 11:33:23 volumio-office go-librespot[2269]: time="2025-01-17T11:33:23Z" level=debug msg="completed challenge" Jan 17 11:33:23 volumio-office go-librespot[2269]: time="2025-01-17T11:33:23Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:23 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:23 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:23 volumio-office volumio[677]: info: Connection to go-librespot Websocket closed Jan 17 11:33:26 volumio-office volumio[677]: info: Getting Spotify volume Jan 17 11:33:26 volumio-office volumio[677]: (node:677) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:26 volumio-office volumio[677]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 17 11:33:26 volumio-office volumio[677]: (node:677) 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: 9) Jan 17 11:33:26 volumio-office volumio[677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 17 11:33:26 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:26 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:26 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Jan 17 11:33:26 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:26 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:26 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:26 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:26 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:26 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 46. Jan 17 11:33:26 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:26 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:26 volumio-office go-librespot[2280]: Librespot-go daemon starting... Jan 17 11:33:27 volumio-office go-librespot[2280]: time="2025-01-17T11:33:27Z" level=info msg="generated new device id: 974074536e590188337dedae9206a640dd7f94ab" Jan 17 11:33:27 volumio-office go-librespot[2280]: time="2025-01-17T11:33:27Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:27 volumio-office go-librespot[2280]: time="2025-01-17T11:33:27Z" level=debug msg="obtained new client token: AAA4vY73etCIg8bY4eh/WyfRzd8cKZ9w+QHdgftI1cJVFTf1sZe3XfAteU5uPcnkpDCk2orNyawb+6awZaPmfTRz/D7jCSJgi2DZD1d4aPh+l8NFOVfQVsVZaWsUV25uzP9Lckk7cMkGEl6A5LLopj0DfV9B+Ftlzgj0/PftUhleGbFsmXKG/GlI+mFbnvT4Z6pFNuIEkCWHRNswDdjIceLHG3rEo/gQQXB827uL+RWry/U3NCNPaZe/UbhXvg==" Jan 17 11:33:27 volumio-office go-librespot[2280]: time="2025-01-17T11:33:27Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:27 volumio-office go-librespot[2280]: time="2025-01-17T11:33:27Z" level=debug msg="completed keyexchange" Jan 17 11:33:28 volumio-office go-librespot[2280]: time="2025-01-17T11:33:28Z" level=debug msg="completed challenge" Jan 17 11:33:28 volumio-office go-librespot[2280]: time="2025-01-17T11:33:28Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:28 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:28 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:29 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:29 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:31 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:31 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 47. Jan 17 11:33:31 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:31 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:31 volumio-office go-librespot[2300]: Librespot-go daemon starting... Jan 17 11:33:31 volumio-office go-librespot[2300]: time="2025-01-17T11:33:31Z" level=info msg="generated new device id: 8a698d83cc608e46529d3c19d4ab4729de43d2cf" Jan 17 11:33:31 volumio-office go-librespot[2300]: time="2025-01-17T11:33:31Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:31 volumio-office go-librespot[2300]: time="2025-01-17T11:33:31Z" level=debug msg="obtained new client token: AADQaVlBnRWgCA+MY97gBXtb++CsSjGoHAPqeZMUF78ZTUBjX31OsbmrOvQjKIDWFePgwFwDuTI52og/vMpILqSX+AqMPiQ0DbBWHhgCM5nD1zgigH/R+JS3vVqUujFT9WCuaEBEPohEaZilCXDQQoLbY23ahoVyuf9ySV/WQwj1CFEAR9iQvaotuVmYdBILiJ7RDJpfti0MrY3M4s224EmDnj59vb3nKTH3HzoeIp6rKKN5B/sA9uzEvLAxMQ==" Jan 17 11:33:31 volumio-office go-librespot[2300]: time="2025-01-17T11:33:31Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:31 volumio-office go-librespot[2300]: time="2025-01-17T11:33:31Z" level=debug msg="completed keyexchange" Jan 17 11:33:32 volumio-office go-librespot[2300]: time="2025-01-17T11:33:32Z" level=debug msg="completed challenge" Jan 17 11:33:32 volumio-office go-librespot[2300]: time="2025-01-17T11:33:32Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:32 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:32 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:32 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:32 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:35 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:35 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 48. Jan 17 11:33:35 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:35 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:35 volumio-office go-librespot[2325]: Librespot-go daemon starting... Jan 17 11:33:35 volumio-office go-librespot[2325]: time="2025-01-17T11:33:35Z" level=info msg="generated new device id: 0457d5f9f096e2530aed2ee75ecf0e25984b74f4" Jan 17 11:33:35 volumio-office go-librespot[2325]: time="2025-01-17T11:33:35Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:35 volumio-office go-librespot[2325]: time="2025-01-17T11:33:35Z" level=debug msg="obtained new client token: AADyAbGRDSroLxc9KPDsP8a+9ZSvLJJ8b3ViVuWV8D0GVSEDEHBXk8idpD05IYVnvnCDHxiUupU0gGbCZtes4cs58zy7o5f45+yt8h5fr5LR6WI6R5n08mCCwMCHHSwA9k1N0KPOMgCv4uhAPQBg8VjfVhltROauR2SnQqGTPnrzwuesJY4zoFCNFBcVZf88lW/8WZWInIHdy5AQs1FBYMR/5cfauKM31DzYroR8tN//pDaJiuocsSWQypBLQA==" Jan 17 11:33:35 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:35 volumio-office go-librespot[2325]: time="2025-01-17T11:33:35Z" level=debug msg="new websocket client" Jan 17 11:33:35 volumio-office volumio[677]: info: Connection to go-librespot Websocket established Jan 17 11:33:36 volumio-office go-librespot[2325]: time="2025-01-17T11:33:36Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:33:36 volumio-office go-librespot[2325]: time="2025-01-17T11:33:36Z" level=debug msg="completed keyexchange" Jan 17 11:33:36 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:36 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:36 volumio-office volumio[677]: info: Listing playlists Jan 17 11:33:36 volumio-office volumio[677]: info: Listing playlists Jan 17 11:33:36 volumio-office go-librespot[2325]: time="2025-01-17T11:33:36Z" level=debug msg="completed challenge" Jan 17 11:33:36 volumio-office go-librespot[2325]: time="2025-01-17T11:33:36Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:36 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:36 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:36 volumio-office volumio[677]: info: Connection to go-librespot Websocket closed Jan 17 11:33:38 volumio-office volumio[677]: info: Getting Spotify volume Jan 17 11:33:38 volumio-office volumio[677]: (node:677) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:38 volumio-office volumio[677]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Jan 17 11:33:38 volumio-office volumio[677]: (node:677) 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: 10) Jan 17 11:33:38 volumio-office volumio[677]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 17 11:33:39 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:39 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:39 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Jan 17 11:33:39 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:39 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:39 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:39 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 49. Jan 17 11:33:39 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:39 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:39 volumio-office go-librespot[2344]: Librespot-go daemon starting... Jan 17 11:33:40 volumio-office go-librespot[2344]: time="2025-01-17T11:33:40Z" level=info msg="generated new device id: b2835b2a70a31bbfe6f67bcf9d117d22f8afd2ce" Jan 17 11:33:40 volumio-office go-librespot[2344]: time="2025-01-17T11:33:40Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:40 volumio-office go-librespot[2344]: time="2025-01-17T11:33:40Z" level=debug msg="obtained new client token: AAAn5MFRJyezOIowGB3zJU5ZsSFvvFJ+gi6/VsWT6/3V7fqf15Cc8kOLyKqOxTgeWQGitRvdGsK9x8irKs5RzbiHAHOGVGZPfwNlNFu4IwM2nsyXeGZvgbFmb4mXkQk6+eCMIlfA+MAhjoxkyfcobxiL1GPYOdFPsKrjPMlPSXjPOI5m3GqUGsdl5f1SVbqtlcwWdqthMYfdyYt2X41poQj3uaBd1JXDTD1C697yBND+adjF46+VzvuwRdPboQ==" Jan 17 11:33:40 volumio-office go-librespot[2344]: time="2025-01-17T11:33:40Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:33:40 volumio-office go-librespot[2344]: time="2025-01-17T11:33:40Z" level=debug msg="completed keyexchange" Jan 17 11:33:41 volumio-office go-librespot[2344]: time="2025-01-17T11:33:41Z" level=debug msg="completed challenge" Jan 17 11:33:41 volumio-office go-librespot[2344]: time="2025-01-17T11:33:41Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:41 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:41 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:42 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:42 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:44 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:44 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 50. Jan 17 11:33:44 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:44 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:44 volumio-office go-librespot[2366]: Librespot-go daemon starting... Jan 17 11:33:44 volumio-office go-librespot[2366]: time="2025-01-17T11:33:44Z" level=info msg="generated new device id: a3c1cb4156d8ad04eb1b44eb629dc734d2ea9a96" Jan 17 11:33:44 volumio-office go-librespot[2366]: time="2025-01-17T11:33:44Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:44 volumio-office go-librespot[2366]: time="2025-01-17T11:33:44Z" level=debug msg="obtained new client token: AADjcerdSyK8+T4Hu0cz0g/oOLWGFbcS+DcrsdNZGzVWw6p6hJ4GMIxY1MmvqQC9ZfviLWHZhoktBFNXyDJtjOdXaWh7MvvqI9qaP+wlYbugMnwSWSnUOuTLSjzPiit96OHOUJuV17GhRhI3COFKS13PBze7XNiY4FkP3FG28RMT01+xBAa9G6jP79RvQrfs2ZJf2dGUssu6dbkhRa9pfEOEUEFjOImj5kaJdfANsh0YcgP2Ip7VhmlHsrVwRA==" Jan 17 11:33:44 volumio-office go-librespot[2366]: time="2025-01-17T11:33:44Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:44 volumio-office go-librespot[2366]: time="2025-01-17T11:33:44Z" level=debug msg="completed keyexchange" Jan 17 11:33:45 volumio-office go-librespot[2366]: time="2025-01-17T11:33:45Z" level=debug msg="completed challenge" Jan 17 11:33:45 volumio-office go-librespot[2366]: time="2025-01-17T11:33:45Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:45 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:45 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:45 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:45 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:46 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:46 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:48 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:48 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 51. Jan 17 11:33:48 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:48 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:48 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:48 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:48 volumio-office go-librespot[2377]: Librespot-go daemon starting... Jan 17 11:33:48 volumio-office go-librespot[2377]: time="2025-01-17T11:33:48Z" level=info msg="generated new device id: abacb6516512b32f2157e9d36d508d3db88cfece" Jan 17 11:33:48 volumio-office go-librespot[2377]: time="2025-01-17T11:33:48Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:48 volumio-office volumio[677]: info: Clearing queue after UPNP request Jan 17 11:33:49 volumio-office go-librespot[2377]: time="2025-01-17T11:33:49Z" level=debug msg="obtained new client token: AABmBHnwL7OiRd2XcJW4jFVfYLeAch3MjG25IJCK8mYOm5cvG7KfXLr11Nn5NlZ0ebIL8FbCakRrPEjHNSKvSI4QVwdvutkX/eySlnUTHTg79Y8bkjlUxkmvCNTVtBOHFnBwDPKLbjVxq9uONDO0JMR5Pef5Qe+hBJe0O1MRmpnJzWwtH9vW83LrUsu6Smk/EzSkL8CWLwCqRepjzONXEzk4gn9ts8htAOxsGltjkHqfpvC5epVCVtq2nlw=" Jan 17 11:33:49 volumio-office volumio[677]: info: CoreStateMachine::ClearQueue Jan 17 11:33:49 volumio-office volumio[677]: info: CoreStateMachine::stop Jan 17 11:33:49 volumio-office volumio[677]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 11:33:49 volumio-office volumio[677]: info: CorePlayQueue::clearPlayQueue Jan 17 11:33:49 volumio-office volumio[677]: info: CorePlayQueue::saveQueue Jan 17 11:33:49 volumio-office volumio[677]: info: CoreCommandRouter::volumioPushState Jan 17 11:33:49 volumio-office go-librespot[2377]: time="2025-01-17T11:33:49Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 17 11:33:49 volumio-office volumio[677]: info: CoreCommandRouter::volumioPushQueue Jan 17 11:33:49 volumio-office volumio[677]: error: Upnp client error: Error: This socket has been ended by the other party Jan 17 11:33:49 volumio-office volumio[677]: SPOTIFY: RECEIVED VOLUMIO VOLUME 28 Jan 17 11:33:49 volumio-office go-librespot[2377]: time="2025-01-17T11:33:49Z" level=debug msg="completed keyexchange" Jan 17 11:33:49 volumio-office go-librespot[2377]: time="2025-01-17T11:33:49Z" level=debug msg="completed challenge" Jan 17 11:33:49 volumio-office go-librespot[2377]: time="2025-01-17T11:33:49Z" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: failed login: BadCredentials" Jan 17 11:33:49 volumio-office systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jan 17 11:33:49 volumio-office systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 17 11:33:50 volumio-office volumio[677]: info: Starting UPNP Playback Jan 17 11:33:50 volumio-office volumio[677]: info: Preparing playback through UPNP Jan 17 11:33:50 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:50 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:50 volumio-office volumio[677]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 19ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 13ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 11ms Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 10ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 8ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 6ms Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 18ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 15ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 13ms Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 10ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 8ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 5ms Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 10ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 7ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 6ms Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: Jan 17 11:33:51 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:51 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 9ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 7ms Jan 17 11:33:51 volumio-office volumio[677]: info: ------------------------------ 6ms Jan 17 11:33:51 volumio-office volumio[677]: info: Initializing connection to go-librespot Websocket Jan 17 11:33:51 volumio-office volumio[677]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces state update: player Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::getState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand status Jan 17 11:33:52 volumio-office kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces state update: player Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::getState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand status Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces state update: player Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::getState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand status Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:52 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces state update: player Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand status took 20 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::getState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand status Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand status took 17 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand status took 11 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:52 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces state update: player Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::getState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand status Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces system playlist update Jan 17 11:33:52 volumio-office volumio[677]: info: Ignoring MPD Status Update Jan 17 11:33:52 volumio-office volumio[677]: info: Jan 17 11:33:52 volumio-office volumio[677]: ---------------------------- MPD announces state update: player Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::getState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand status Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 49ms Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand status took 43 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand playlistinfo took 37 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand playlistinfo took 34 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 27ms Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand status took 22 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseTrackInfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseTrackInfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseTrackInfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::servicePushState Jan 17 11:33:52 volumio-office volumio[677]: verbose: In UPNP mode Jan 17 11:33:52 volumio-office volumio[677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","artist":"Dynoro/Gigi D'Agostino - In My Mind","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","trackType":""} Jan 17 11:33:52 volumio-office volumio[677]: verbose: CURRENT POSITION 0 Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState stateService play Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState currentStatus stop Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::servicePushState Jan 17 11:33:52 volumio-office volumio[677]: verbose: In UPNP mode Jan 17 11:33:52 volumio-office volumio[677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","artist":"Dynoro/Gigi D'Agostino - In My Mind","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","trackType":""} Jan 17 11:33:52 volumio-office volumio[677]: verbose: CURRENT POSITION 0 Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState stateService play Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState currentStatus play Jan 17 11:33:52 volumio-office volumio[677]: info: Received an update from plugin. extracting info from payload Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::servicePushState Jan 17 11:33:52 volumio-office volumio[677]: verbose: In UPNP mode Jan 17 11:33:52 volumio-office volumio[677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","artist":"Dynoro/Gigi D'Agostino - In My Mind","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","trackType":""} Jan 17 11:33:52 volumio-office volumio[677]: verbose: CURRENT POSITION 0 Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState stateService play Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState currentStatus play Jan 17 11:33:52 volumio-office volumio[677]: info: Received an update from plugin. extracting info from payload Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 143ms Jan 17 11:33:52 volumio-office volumio[677]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 17 11:33:52 volumio-office volumio[677]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 134ms Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 130ms Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 90ms Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand status took 85 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand playlistinfo took 70 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand playlistinfo took 59 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseState Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseTrackInfo Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseTrackInfo Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::servicePushState Jan 17 11:33:52 volumio-office volumio[677]: verbose: In UPNP mode Jan 17 11:33:52 volumio-office volumio[677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","artist":"Dynoro/Gigi D'Agostino - In My Mind","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","trackType":""} Jan 17 11:33:52 volumio-office volumio[677]: verbose: CURRENT POSITION 0 Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState stateService play Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState currentStatus play Jan 17 11:33:52 volumio-office volumio[677]: info: Received an update from plugin. extracting info from payload Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::servicePushState Jan 17 11:33:52 volumio-office volumio[677]: verbose: In UPNP mode Jan 17 11:33:52 volumio-office volumio[677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","artist":"Dynoro/Gigi D'Agostino - In My Mind","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","trackType":""} Jan 17 11:33:52 volumio-office volumio[677]: verbose: CURRENT POSITION 0 Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState stateService play Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState currentStatus play Jan 17 11:33:52 volumio-office volumio[677]: info: Received an update from plugin. extracting info from payload Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:52 volumio-office volumio[677]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 17 11:33:52 volumio-office volumio[677]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 158ms Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 129ms Jan 17 11:33:52 volumio-office volumio[677]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 17 11:33:52 volumio-office volumio[677]: verbose: ControllerMpd::parseTrackInfo Jan 17 11:33:52 volumio-office volumio[677]: info: ControllerMpd::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::servicePushState Jan 17 11:33:52 volumio-office volumio[677]: verbose: In UPNP mode Jan 17 11:33:52 volumio-office volumio[677]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","artist":"Dynoro/Gigi D'Agostino - In My Mind","album":null,"uri":"http://172.21.0.240:8097/single/uuid:7285956e-61b5-906b-23e8-b827eb970ebd/5c146113d1d547bca431173ccb086f33.flac?ts=1737113628","trackType":""} Jan 17 11:33:52 volumio-office volumio[677]: verbose: CURRENT POSITION 0 Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState stateService play Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::syncState currentStatus play Jan 17 11:33:52 volumio-office volumio[677]: info: Received an update from plugin. extracting info from payload Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 17 11:33:52 volumio-office volumio[677]: info: CoreStateMachine::pushState Jan 17 11:33:52 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:52 volumio-office volumio[677]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Jan 17 11:33:52 volumio-office volumio[677]: info: ------------------------------ 137ms Jan 17 11:33:52 volumio-office volumio[677]: info: Starting UPNP Playback Jan 17 11:33:52 volumio-office volumio[677]: info: Preparing playback through UPNP Jan 17 11:33:52 volumio-office volumio[677]: info: Consume mode Jan 17 11:33:52 volumio-office volumio[677]: info: CoreCommandRouter::volumioGetState Jan 17 11:33:52 volumio-office volumio[677]: info: CorePlayQueue::getTrack 0 Jan 17 11:33:52 volumio-office volumio[677]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 11:33:52 volumio-office volumio[677]: TypeError: Cannot read property 'name' of undefined Jan 17 11:33:52 volumio-office volumio[677]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Jan 17 11:33:52 volumio-office volumio[677]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Jan 17 11:33:52 volumio-office volumio[677]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Jan 17 11:33:52 volumio-office volumio[677]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Jan 17 11:33:52 volumio-office volumio[677]: at Socket.emit (events.js:315:20) Jan 17 11:33:52 volumio-office volumio[677]: at addChunk (internal/streams/readable.js:309:12) Jan 17 11:33:52 volumio-office volumio[677]: at readableAddChunk (internal/streams/readable.js:280:11) Jan 17 11:33:52 volumio-office volumio[677]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jan 17 11:33:52 volumio-office volumio[677]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Jan 17 11:33:52 volumio-office volumio[677]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 11:33:53 volumio-office systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jan 17 11:33:53 volumio-office systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 52. Jan 17 11:33:53 volumio-office systemd[1]: Stopped go-librespot Daemon. Jan 17 11:33:53 volumio-office systemd[1]: Started go-librespot Daemon. Jan 17 11:33:53 volumio-office go-librespot[2429]: Librespot-go daemon starting... Jan 17 11:33:53 volumio-office go-librespot[2429]: time="2025-01-17T11:33:53Z" level=info msg="generated new device id: 1369ea2ede478656553d1d0bd6fe3c6a33ec3bbb" Jan 17 11:33:53 volumio-office go-librespot[2429]: time="2025-01-17T11:33:53Z" level=debug msg="stored credentials found for bignick8t3" Jan 17 11:33:53 volumio-office go-librespot[2429]: time="2025-01-17T11:33:53Z" level=debug msg="obtained new client token: AAC1MrQMD44JAsNx91lOz1yf2zH2QBcaIdGu54XoRXPTS1x2EAJMRGQnL3DDPUgbwB/sTOFm6q7SHMXHBsXLrC6K6V1cSWORBLsVHeJQWqGwcN+sXSZpzkQfXvepJ+VnTSQNhclbyD59r0Mnpmuonk9V2M5ZE+OzwwSURGcedMJkiiLnPBw2PqJcxN62VQeTRMeLv/SPR4YLwVAY8kx8Z2pU7gNKWUE1Janhzk/j4uLjRGgyq5H8x9xmYdHSdA==" Jan 17 11:33:53 volumio-office go-librespot[2429]: time="2025-01-17T11:33:53Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 17 11:33:53 volumio-office sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 11:32 Jan 17 11:33:53 volumio-office sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 11:33:53 volumio-office go-librespot[2429]: time="2025-01-17T11:33:53Z" level=debug msg="completed keyexchange" PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"